{"level":"warn","ts":"2026-05-19T00:06:18.238388Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T00:06:18.23847Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186: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-05-19T00:06:18.238534Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T00:06:18.238543Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-05-19T00:06:18.238563Z","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-05-19T00:06:18.239167Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-05-19T00:06:18.239234Z","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://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186: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-05-19T00:06:18.244642Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.199708ms"} {"level":"info","ts":"2026-05-19T00:06:18.252433Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-05-19T00:06:18.252536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T00:06:18.25257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-05-19T00:06:18.252589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T00:06:18.25261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-05-19T00:06:18.252661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-05-19T00:06:18.258292Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T00:06:18.260092Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T00:06:18.261994Z","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-05-19T00:06:18.265029Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T00:06:18.265163Z","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-05-19T00:06:18.265195Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T00:06:18.265479Z","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-05-19T00:06:18.265501Z","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-05-19T00:06:18.266264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-05-19T00:06:18.267165Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-05-19T00:06:18.26961Z","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-05-19T00:06:18.269721Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-05-19T00:06:18.269774Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-05-19T00:06:18.27006Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T00:06:18.270115Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T00:06:19.053294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T00:06:19.05355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T00:06:19.053583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-05-19T00:06:19.053602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-05-19T00:06:19.053612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-05-19T00:06:19.053627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-05-19T00:06:19.053641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-05-19T00:06:19.055034Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T00:06:19.055045Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T00:06:19.055062Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T00:06:19.055519Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T00:06:19.055596Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T00:06:19.056025Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T00:06:19.056153Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T00:06:19.056206Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T00:06:19.05821Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T00:06:19.058541Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T00:06:19.065984Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"info","ts":"2026-05-19T00:06:33.441875Z","caller":"traceutil/trace.go:171","msg":"trace[487712823] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"148.191072ms","start":"2026-05-19T00:06:33.293649Z","end":"2026-05-19T00:06:33.44184Z","steps":["trace[487712823] 'process raft request' (duration: 148.031768ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.017259Z","caller":"traceutil/trace.go:171","msg":"trace[1421859318] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"181.424364ms","start":"2026-05-19T00:06:48.835804Z","end":"2026-05-19T00:06:49.017229Z","steps":["trace[1421859318] 'process raft request' (duration: 181.27461ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077407Z","caller":"traceutil/trace.go:171","msg":"trace[788758201] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"229.471232ms","start":"2026-05-19T00:06:48.847895Z","end":"2026-05-19T00:06:49.077367Z","steps":["trace[788758201] 'process raft request' (duration: 229.142164ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077439Z","caller":"traceutil/trace.go:171","msg":"trace[337631831] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"226.600667ms","start":"2026-05-19T00:06:48.850815Z","end":"2026-05-19T00:06:49.077415Z","steps":["trace[337631831] 'process raft request' (duration: 226.447803ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077523Z","caller":"traceutil/trace.go:171","msg":"trace[1973916983] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"226.655188ms","start":"2026-05-19T00:06:48.850817Z","end":"2026-05-19T00:06:49.077472Z","steps":["trace[1973916983] 'process raft request' (duration: 226.496514ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077609Z","caller":"traceutil/trace.go:171","msg":"trace[2110048166] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"206.85133ms","start":"2026-05-19T00:06:48.870741Z","end":"2026-05-19T00:06:49.077592Z","steps":["trace[2110048166] 'process raft request' (duration: 206.617094ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077644Z","caller":"traceutil/trace.go:171","msg":"trace[1987741865] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"202.664814ms","start":"2026-05-19T00:06:48.874961Z","end":"2026-05-19T00:06:49.077626Z","steps":["trace[1987741865] 'process raft request' (duration: 202.511311ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077705Z","caller":"traceutil/trace.go:171","msg":"trace[333413311] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"202.597573ms","start":"2026-05-19T00:06:48.875099Z","end":"2026-05-19T00:06:49.077696Z","steps":["trace[333413311] 'process raft request' (duration: 202.507931ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077779Z","caller":"traceutil/trace.go:171","msg":"trace[292899412] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"202.675455ms","start":"2026-05-19T00:06:48.875037Z","end":"2026-05-19T00:06:49.077712Z","steps":["trace[292899412] 'process raft request' (duration: 202.475881ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077855Z","caller":"traceutil/trace.go:171","msg":"trace[929482601] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"185.21307ms","start":"2026-05-19T00:06:48.892635Z","end":"2026-05-19T00:06:49.077848Z","steps":["trace[929482601] 'process raft request' (duration: 185.108738ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077875Z","caller":"traceutil/trace.go:171","msg":"trace[770665736] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"160.377238ms","start":"2026-05-19T00:06:48.917475Z","end":"2026-05-19T00:06:49.077852Z","steps":["trace[770665736] 'process raft request' (duration: 160.326917ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077848Z","caller":"traceutil/trace.go:171","msg":"trace[2132340753] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"202.419859ms","start":"2026-05-19T00:06:48.875349Z","end":"2026-05-19T00:06:49.077769Z","steps":["trace[2132340753] 'process raft request' (duration: 202.299587ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.077814Z","caller":"traceutil/trace.go:171","msg":"trace[1402266994] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"194.103271ms","start":"2026-05-19T00:06:48.883691Z","end":"2026-05-19T00:06:49.077794Z","steps":["trace[1402266994] 'process raft request' (duration: 193.998859ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.126982Z","caller":"traceutil/trace.go:171","msg":"trace[1720206675] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"103.863241ms","start":"2026-05-19T00:06:49.023099Z","end":"2026-05-19T00:06:49.126962Z","steps":["trace[1720206675] 'process raft request' (duration: 103.751798ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.201406Z","caller":"traceutil/trace.go:171","msg":"trace[379213807] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"112.496214ms","start":"2026-05-19T00:06:49.088877Z","end":"2026-05-19T00:06:49.201373Z","steps":["trace[379213807] 'process raft request' (duration: 105.133848ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.201614Z","caller":"traceutil/trace.go:171","msg":"trace[124015372] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"112.593576ms","start":"2026-05-19T00:06:49.088994Z","end":"2026-05-19T00:06:49.201588Z","steps":["trace[124015372] 'process raft request' (duration: 112.260109ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.201602Z","caller":"traceutil/trace.go:171","msg":"trace[1834803103] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"110.368706ms","start":"2026-05-19T00:06:49.091149Z","end":"2026-05-19T00:06:49.201518Z","steps":["trace[1834803103] 'process raft request' (duration: 110.144251ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.20173Z","caller":"traceutil/trace.go:171","msg":"trace[1844267954] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"109.562778ms","start":"2026-05-19T00:06:49.092156Z","end":"2026-05-19T00:06:49.201719Z","steps":["trace[1844267954] 'process raft request' (duration: 109.311173ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.201755Z","caller":"traceutil/trace.go:171","msg":"trace[1531754614] transaction","detail":"{read_only:false; number_of_response:1; response_revision:524; }","duration":"109.543218ms","start":"2026-05-19T00:06:49.092114Z","end":"2026-05-19T00:06:49.201657Z","steps":["trace[1531754614] 'process raft request' (duration: 109.315293ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.201813Z","caller":"traceutil/trace.go:171","msg":"trace[1703289468] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"110.611522ms","start":"2026-05-19T00:06:49.091177Z","end":"2026-05-19T00:06:49.201788Z","steps":["trace[1703289468] 'process raft request' (duration: 110.166272ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.202435Z","caller":"traceutil/trace.go:171","msg":"trace[475277946] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"105.020135ms","start":"2026-05-19T00:06:49.096728Z","end":"2026-05-19T00:06:49.201748Z","steps":["trace[475277946] 'process raft request' (duration: 104.78906ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.203137Z","caller":"traceutil/trace.go:171","msg":"trace[2098186679] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"105.858584ms","start":"2026-05-19T00:06:49.096907Z","end":"2026-05-19T00:06:49.202766Z","steps":["trace[2098186679] 'process raft request' (duration: 104.670417ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.203657Z","caller":"traceutil/trace.go:171","msg":"trace[430907678] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"103.762797ms","start":"2026-05-19T00:06:49.099852Z","end":"2026-05-19T00:06:49.203615Z","steps":["trace[430907678] 'process raft request' (duration: 101.805083ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:49.206024Z","caller":"traceutil/trace.go:171","msg":"trace[188862232] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"105.609739ms","start":"2026-05-19T00:06:49.100375Z","end":"2026-05-19T00:06:49.205984Z","steps":["trace[188862232] 'process raft request' (duration: 103.792598ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:51.969306Z","caller":"traceutil/trace.go:171","msg":"trace[174318064] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"194.308783ms","start":"2026-05-19T00:06:51.774972Z","end":"2026-05-19T00:06:51.969281Z","steps":["trace[174318064] 'process raft request' (duration: 194.16975ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:52.122495Z","caller":"traceutil/trace.go:171","msg":"trace[832334483] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"149.114198ms","start":"2026-05-19T00:06:51.973351Z","end":"2026-05-19T00:06:52.122466Z","steps":["trace[832334483] 'process raft request' (duration: 124.236156ms)","trace[832334483] 'compare' (duration: 24.521614ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:06:52.122596Z","caller":"traceutil/trace.go:171","msg":"trace[842398419] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"148.265148ms","start":"2026-05-19T00:06:51.974283Z","end":"2026-05-19T00:06:52.122549Z","steps":["trace[842398419] 'process raft request' (duration: 148.116255ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:06:55.917866Z","caller":"traceutil/trace.go:171","msg":"trace[1744877703] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"101.413074ms","start":"2026-05-19T00:06:55.816414Z","end":"2026-05-19T00:06:55.917827Z","steps":["trace[1744877703] 'process raft request' (duration: 101.118177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:06:56.406166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.36523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T00:06:56.406429Z","caller":"traceutil/trace.go:171","msg":"trace[1131444556] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"264.740559ms","start":"2026-05-19T00:06:56.141669Z","end":"2026-05-19T00:06:56.406409Z","steps":["trace[1131444556] 'read index received' (duration: 163.520071ms)","trace[1131444556] 'applied index is now lower than readState.Index' (duration: 101.219418ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:06:56.40649Z","caller":"traceutil/trace.go:171","msg":"trace[265341751] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"473.904107ms","start":"2026-05-19T00:06:55.932574Z","end":"2026-05-19T00:06:56.406478Z","steps":["trace[265341751] 'process raft request' (duration: 108.755008ms)","trace[265341751] 'compare' (duration: 364.224977ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:06:56.406504Z","caller":"traceutil/trace.go:171","msg":"trace[1626493392] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"472.561576ms","start":"2026-05-19T00:06:55.933898Z","end":"2026-05-19T00:06:56.406459Z","steps":["trace[1626493392] 'process raft request' (duration: 472.442893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:06:56.406549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:06:55.932539Z","time spent":"473.982489ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T00:06:56.406666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.945095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T00:06:56.406755Z","caller":"traceutil/trace.go:171","msg":"trace[1864678939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"265.125479ms","start":"2026-05-19T00:06:56.141608Z","end":"2026-05-19T00:06:56.406734Z","steps":["trace[1864678939] 'agreement among raft nodes before linearized reading' (duration: 264.850353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:06:56.406994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:06:55.933875Z","time spent":"472.7444ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:08:24.978157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.458984ms","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-05-19T00:08:24.978285Z","caller":"traceutil/trace.go:171","msg":"trace[1663412317] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1857; }","duration":"262.646509ms","start":"2026-05-19T00:08:24.715593Z","end":"2026-05-19T00:08:24.978239Z","steps":["trace[1663412317] 'range keys from in-memory index tree' (duration: 262.229269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:24.978482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.777893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a61a882f-f1d5-426a-8e74-99bb51044bfc\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T00:08:24.978612Z","caller":"traceutil/trace.go:171","msg":"trace[614879337] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a61a882f-f1d5-426a-8e74-99bb51044bfc; range_end:; response_count:1; response_revision:1857; }","duration":"297.968218ms","start":"2026-05-19T00:08:24.680614Z","end":"2026-05-19T00:08:24.978582Z","steps":["trace[614879337] 'range keys from in-memory index tree' (duration: 297.518167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:24.978872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.299547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-19T00:08:24.979062Z","caller":"traceutil/trace.go:171","msg":"trace[2139958358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1857; }","duration":"101.541262ms","start":"2026-05-19T00:08:24.877493Z","end":"2026-05-19T00:08:24.979034Z","steps":["trace[2139958358] 'range keys from in-memory index tree' (duration: 100.567269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:25.39651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.868863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:08:25.396636Z","caller":"traceutil/trace.go:171","msg":"trace[1580223640] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1858; }","duration":"124.064388ms","start":"2026-05-19T00:08:25.272545Z","end":"2026-05-19T00:08:25.396609Z","steps":["trace[1580223640] 'count revisions from in-memory index tree' (duration: 123.790302ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:08:41.775983Z","caller":"traceutil/trace.go:171","msg":"trace[1493985451] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2065; }","duration":"144.243358ms","start":"2026-05-19T00:08:41.631706Z","end":"2026-05-19T00:08:41.77595Z","steps":["trace[1493985451] 'read index received' (duration: 143.96268ms)","trace[1493985451] 'applied index is now lower than readState.Index' (duration: 279.188µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:08:41.776088Z","caller":"traceutil/trace.go:171","msg":"trace[798308602] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"204.085379ms","start":"2026-05-19T00:08:41.57195Z","end":"2026-05-19T00:08:41.776036Z","steps":["trace[798308602] 'process raft request' (duration: 203.798262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:41.776317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.621846ms","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-05-19T00:08:41.776419Z","caller":"traceutil/trace.go:171","msg":"trace[221098819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1994; }","duration":"144.81269ms","start":"2026-05-19T00:08:41.631587Z","end":"2026-05-19T00:08:41.7764Z","steps":["trace[221098819] 'agreement among raft nodes before linearized reading' (duration: 144.489543ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:08:42.093125Z","caller":"traceutil/trace.go:171","msg":"trace[1104190926] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"236.357605ms","start":"2026-05-19T00:08:41.856726Z","end":"2026-05-19T00:08:42.093083Z","steps":["trace[1104190926] 'process raft request' (duration: 181.875601ms)","trace[1104190926] 'compare' (duration: 54.30678ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:08:42.112081Z","caller":"traceutil/trace.go:171","msg":"trace[129182052] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"169.254881ms","start":"2026-05-19T00:08:41.942788Z","end":"2026-05-19T00:08:42.112043Z","steps":["trace[129182052] 'process raft request' (duration: 169.038405ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:09:21.392449Z","caller":"traceutil/trace.go:171","msg":"trace[674334651] transaction","detail":"{read_only:false; response_revision:2213; number_of_response:1; }","duration":"112.693427ms","start":"2026-05-19T00:09:21.27973Z","end":"2026-05-19T00:09:21.392423Z","steps":["trace[674334651] 'process raft request' (duration: 112.508592ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:09:54.497295Z","caller":"traceutil/trace.go:171","msg":"trace[1750861297] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"109.636495ms","start":"2026-05-19T00:09:54.387631Z","end":"2026-05-19T00:09:54.497267Z","steps":["trace[1750861297] 'process raft request' (duration: 109.495721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:10:29.034555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.519131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T00:10:29.034676Z","caller":"traceutil/trace.go:171","msg":"trace[1431827345] linearizableReadLoop","detail":"{readStateIndex:2879; appliedIndex:2878; }","duration":"337.994719ms","start":"2026-05-19T00:10:28.696663Z","end":"2026-05-19T00:10:29.034658Z","steps":["trace[1431827345] 'read index received' (duration: 6.450763ms)","trace[1431827345] 'applied index is now lower than readState.Index' (duration: 331.542406ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:10:29.035073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.35931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T00:10:29.035127Z","caller":"traceutil/trace.go:171","msg":"trace[163064865] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"340.224573ms","start":"2026-05-19T00:10:28.694519Z","end":"2026-05-19T00:10:29.034743Z","steps":["trace[163064865] 'process raft request' (duration: 125.410695ms)","trace[163064865] 'compare' (duration: 214.236435ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:10:29.035162Z","caller":"traceutil/trace.go:171","msg":"trace[2077926897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2776; }","duration":"338.509213ms","start":"2026-05-19T00:10:28.696632Z","end":"2026-05-19T00:10:29.035141Z","steps":["trace[2077926897] 'agreement among raft nodes before linearized reading' (duration: 338.185095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:10:29.035207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:10:28.69662Z","time spent":"338.578884ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T00:10:29.035251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:10:28.694499Z","time spent":"340.695245ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:10:29.035405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.848867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T00:10:29.035491Z","caller":"traceutil/trace.go:171","msg":"trace[998042933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2776; }","duration":"239.97316ms","start":"2026-05-19T00:10:28.795496Z","end":"2026-05-19T00:10:29.035469Z","steps":["trace[998042933] 'agreement among raft nodes before linearized reading' (duration: 239.748755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:10:29.035602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.43105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T00:10:29.035665Z","caller":"traceutil/trace.go:171","msg":"trace[527298894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2776; }","duration":"232.618385ms","start":"2026-05-19T00:10:28.803029Z","end":"2026-05-19T00:10:29.035647Z","steps":["trace[527298894] 'agreement among raft nodes before linearized reading' (duration: 232.219675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:14:27.176103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.227902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-73884855-3e0c-4c7c-9dc9-beb24715d620\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-19T00:14:27.176254Z","caller":"traceutil/trace.go:171","msg":"trace[1943150443] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-73884855-3e0c-4c7c-9dc9-beb24715d620; range_end:; response_count:1; response_revision:4954; }","duration":"122.467898ms","start":"2026-05-19T00:14:27.05376Z","end":"2026-05-19T00:14:27.176228Z","steps":["trace[1943150443] 'range keys from in-memory index tree' (duration: 121.999427ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:14:27.176476Z","caller":"traceutil/trace.go:171","msg":"trace[286383257] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"110.389106ms","start":"2026-05-19T00:14:27.066051Z","end":"2026-05-19T00:14:27.17644Z","steps":["trace[286383257] 'process raft request' (duration: 72.46798ms)","trace[286383257] 'compare' (duration: 37.290091ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:14:27.176732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.561331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T00:14:27.176862Z","caller":"traceutil/trace.go:171","msg":"trace[2142192316] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4954; }","duration":"146.752725ms","start":"2026-05-19T00:14:27.030075Z","end":"2026-05-19T00:14:27.176828Z","steps":["trace[2142192316] 'count revisions from in-memory index tree' (duration: 146.463158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:14:27.176929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.404381ms","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-05-19T00:14:27.176971Z","caller":"traceutil/trace.go:171","msg":"trace[405330680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4954; }","duration":"282.493913ms","start":"2026-05-19T00:14:26.894466Z","end":"2026-05-19T00:14:27.17696Z","steps":["trace[405330680] 'range keys from in-memory index tree' (duration: 282.228747ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:14:27.471409Z","caller":"traceutil/trace.go:171","msg":"trace[1937187714] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5144; }","duration":"112.784025ms","start":"2026-05-19T00:14:27.358597Z","end":"2026-05-19T00:14:27.471381Z","steps":["trace[1937187714] 'read index received' (duration: 28.757976ms)","trace[1937187714] 'applied index is now lower than readState.Index' (duration: 84.024529ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:14:27.471697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.051301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:14:27.471863Z","caller":"traceutil/trace.go:171","msg":"trace[1317466016] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4958; }","duration":"113.304567ms","start":"2026-05-19T00:14:27.358532Z","end":"2026-05-19T00:14:27.471837Z","steps":["trace[1317466016] 'agreement among raft nodes before linearized reading' (duration: 112.961089ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:14:27.472494Z","caller":"traceutil/trace.go:171","msg":"trace[1836865068] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"126.406923ms","start":"2026-05-19T00:14:27.345978Z","end":"2026-05-19T00:14:27.472385Z","steps":["trace[1836865068] 'process raft request' (duration: 125.262086ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:14:37.970668Z","caller":"traceutil/trace.go:171","msg":"trace[1933238125] linearizableReadLoop","detail":"{readStateIndex:5374; appliedIndex:5373; }","duration":"114.766691ms","start":"2026-05-19T00:14:37.855866Z","end":"2026-05-19T00:14:37.970633Z","steps":["trace[1933238125] 'read index received' (duration: 114.409862ms)","trace[1933238125] 'applied index is now lower than readState.Index' (duration: 355.229µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:14:37.970695Z","caller":"traceutil/trace.go:171","msg":"trace[2056946129] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"155.854935ms","start":"2026-05-19T00:14:37.814801Z","end":"2026-05-19T00:14:37.970656Z","steps":["trace[2056946129] 'process raft request' (duration: 155.572998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:14:37.970843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.962507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T00:14:37.970904Z","caller":"traceutil/trace.go:171","msg":"trace[1661439713] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:5180; }","duration":"115.07618ms","start":"2026-05-19T00:14:37.855816Z","end":"2026-05-19T00:14:37.970892Z","steps":["trace[1661439713] 'agreement among raft nodes before linearized reading' (duration: 114.917116ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:14:38.063667Z","caller":"traceutil/trace.go:171","msg":"trace[705511913] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"157.263178ms","start":"2026-05-19T00:14:37.906373Z","end":"2026-05-19T00:14:38.063636Z","steps":["trace[705511913] 'process raft request' (duration: 155.663639ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:14:56.768104Z","caller":"traceutil/trace.go:171","msg":"trace[688256787] linearizableReadLoop","detail":"{readStateIndex:5698; appliedIndex:5697; }","duration":"185.132103ms","start":"2026-05-19T00:14:56.582948Z","end":"2026-05-19T00:14:56.76808Z","steps":["trace[688256787] 'read index received' (duration: 184.934427ms)","trace[688256787] 'applied index is now lower than readState.Index' (duration: 196.806µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:14:56.768287Z","caller":"traceutil/trace.go:171","msg":"trace[1345735417] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"230.427827ms","start":"2026-05-19T00:14:56.537805Z","end":"2026-05-19T00:14:56.768233Z","steps":["trace[1345735417] 'process raft request' (duration: 230.143971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:14:56.768365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.40471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-19T00:14:56.768415Z","caller":"traceutil/trace.go:171","msg":"trace[922547804] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5498; }","duration":"185.505622ms","start":"2026-05-19T00:14:56.582895Z","end":"2026-05-19T00:14:56.768401Z","steps":["trace[922547804] 'agreement among raft nodes before linearized reading' (duration: 185.318828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:14:56.76835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.142441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:14:56.768577Z","caller":"traceutil/trace.go:171","msg":"trace[1016677947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5498; }","duration":"177.422307ms","start":"2026-05-19T00:14:56.591139Z","end":"2026-05-19T00:14:56.768562Z","steps":["trace[1016677947] 'agreement among raft nodes before linearized reading' (duration: 177.12665ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:38.071489Z","caller":"traceutil/trace.go:171","msg":"trace[1077025110] transaction","detail":"{read_only:false; response_revision:5858; number_of_response:1; }","duration":"106.717698ms","start":"2026-05-19T00:15:37.964559Z","end":"2026-05-19T00:15:38.071277Z","steps":["trace[1077025110] 'process raft request' (duration: 48.076992ms)","trace[1077025110] 'compare' (duration: 58.371419ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:16:19.093361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3051} {"level":"info","ts":"2026-05-19T00:16:19.182236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3051,"took":"87.610648ms","hash":900553798,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":31924224,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T00:16:19.182393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":900553798,"revision":3051,"compact-revision":-1} {"level":"warn","ts":"2026-05-19T00:21:07.853444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.034948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:21:07.853563Z","caller":"traceutil/trace.go:171","msg":"trace[1932013337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8268; }","duration":"264.232903ms","start":"2026-05-19T00:21:07.58931Z","end":"2026-05-19T00:21:07.853543Z","steps":["trace[1932013337] 'range keys from in-memory index tree' (duration: 263.911885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:07.85396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.155315ms","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-05-19T00:21:07.854061Z","caller":"traceutil/trace.go:171","msg":"trace[1614623622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8268; }","duration":"204.317719ms","start":"2026-05-19T00:21:07.649722Z","end":"2026-05-19T00:21:07.85404Z","steps":["trace[1614623622] 'range keys from in-memory index tree' (duration: 203.558811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:08.7205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.819636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:21:08.720634Z","caller":"traceutil/trace.go:171","msg":"trace[1145323347] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:8272; }","duration":"149.99101ms","start":"2026-05-19T00:21:08.570612Z","end":"2026-05-19T00:21:08.720603Z","steps":["trace[1145323347] 'count revisions from in-memory index tree' (duration: 149.676962ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:08.720867Z","caller":"traceutil/trace.go:171","msg":"trace[1749206541] linearizableReadLoop","detail":"{readStateIndex:8563; appliedIndex:8562; }","duration":"130.512818ms","start":"2026-05-19T00:21:08.590318Z","end":"2026-05-19T00:21:08.720831Z","steps":["trace[1749206541] 'read index received' (duration: 119.119381ms)","trace[1749206541] 'applied index is now lower than readState.Index' (duration: 11.391717ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:21:08.721046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.708093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:21:08.721091Z","caller":"traceutil/trace.go:171","msg":"trace[452457774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8273; }","duration":"130.842706ms","start":"2026-05-19T00:21:08.590226Z","end":"2026-05-19T00:21:08.721069Z","steps":["trace[452457774] 'agreement among raft nodes before linearized reading' (duration: 130.739153ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:08.721078Z","caller":"traceutil/trace.go:171","msg":"trace[1558045130] transaction","detail":"{read_only:false; response_revision:8273; number_of_response:1; }","duration":"146.039575ms","start":"2026-05-19T00:21:08.574997Z","end":"2026-05-19T00:21:08.721036Z","steps":["trace[1558045130] 'process raft request' (duration: 134.381601ms)","trace[1558045130] 'compare' (duration: 10.975097ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:21:19.099846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6163} {"level":"info","ts":"2026-05-19T00:21:19.201766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6163,"took":"100.353316ms","hash":3499513590,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":23236608,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-19T00:21:19.201862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3499513590,"revision":6163,"compact-revision":3051} {"level":"warn","ts":"2026-05-19T00:21:37.566826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.225652ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-19T00:21:37.567211Z","caller":"traceutil/trace.go:171","msg":"trace[1361899271] transaction","detail":"{read_only:false; response_revision:8482; number_of_response:1; }","duration":"196.404917ms","start":"2026-05-19T00:21:37.370786Z","end":"2026-05-19T00:21:37.567191Z","steps":["trace[1361899271] 'process raft request' (duration: 196.137261ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:37.570298Z","caller":"traceutil/trace.go:171","msg":"trace[959327563] linearizableReadLoop","detail":"{readStateIndex:8782; appliedIndex:8779; }","duration":"152.85863ms","start":"2026-05-19T00:21:37.417387Z","end":"2026-05-19T00:21:37.570245Z","steps":["trace[959327563] 'read index received' (duration: 147.4943ms)","trace[959327563] 'applied index is now lower than readState.Index' (duration: 5.3634ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:21:37.570452Z","caller":"traceutil/trace.go:171","msg":"trace[354761462] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"188.805923ms","start":"2026-05-19T00:21:37.381625Z","end":"2026-05-19T00:21:37.570431Z","steps":["trace[354761462] 'process raft request' (duration: 185.558275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:37.570504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.109357ms","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-05-19T00:21:37.570541Z","caller":"traceutil/trace.go:171","msg":"trace[1212638722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8483; }","duration":"153.188359ms","start":"2026-05-19T00:21:37.417342Z","end":"2026-05-19T00:21:37.57053Z","steps":["trace[1212638722] 'agreement among raft nodes before linearized reading' (duration: 153.016705ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:40.367847Z","caller":"traceutil/trace.go:171","msg":"trace[660560542] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"181.918424ms","start":"2026-05-19T00:21:40.185895Z","end":"2026-05-19T00:21:40.367813Z","steps":["trace[660560542] 'process raft request' (duration: 161.814697ms)","trace[660560542] 'compare' (duration: 19.639446ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:21:49.682647Z","caller":"traceutil/trace.go:171","msg":"trace[1300795021] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"126.365428ms","start":"2026-05-19T00:21:49.556247Z","end":"2026-05-19T00:21:49.682613Z","steps":["trace[1300795021] 'process raft request' (duration: 105.03163ms)","trace[1300795021] 'compare' (duration: 21.03738ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:21:49.980148Z","caller":"traceutil/trace.go:171","msg":"trace[715565230] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"106.945715ms","start":"2026-05-19T00:21:49.873175Z","end":"2026-05-19T00:21:49.98012Z","steps":["trace[715565230] 'process raft request' (duration: 83.418684ms)","trace[715565230] 'compare' (duration: 23.149692ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:21:50.721322Z","caller":"traceutil/trace.go:171","msg":"trace[111310456] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"235.999778ms","start":"2026-05-19T00:21:50.4853Z","end":"2026-05-19T00:21:50.7213Z","steps":["trace[111310456] 'process raft request' (duration: 235.865244ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:50.848485Z","caller":"traceutil/trace.go:171","msg":"trace[1744552672] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"307.868102ms","start":"2026-05-19T00:21:50.540596Z","end":"2026-05-19T00:21:50.848464Z","steps":["trace[1744552672] 'process raft request' (duration: 307.80815ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:50.848509Z","caller":"traceutil/trace.go:171","msg":"trace[884570421] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"309.421191ms","start":"2026-05-19T00:21:50.539048Z","end":"2026-05-19T00:21:50.848469Z","steps":["trace[884570421] 'process raft request' (duration: 309.229356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:50.848617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:21:50.540559Z","time spent":"307.985275ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:21:50.848606Z","caller":"traceutil/trace.go:171","msg":"trace[252845106] linearizableReadLoop","detail":"{readStateIndex:8899; appliedIndex:8896; }","duration":"290.07626ms","start":"2026-05-19T00:21:50.558506Z","end":"2026-05-19T00:21:50.848582Z","steps":["trace[252845106] 'read index received' (duration: 162.635877ms)","trace[252845106] 'applied index is now lower than readState.Index' (duration: 127.439013ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:21:50.848625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:21:50.539027Z","time spent":"309.536453ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:21:50.848787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.272236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T00:21:50.848756Z","caller":"traceutil/trace.go:171","msg":"trace[1106779749] transaction","detail":"{read_only:false; response_revision:8596; number_of_response:1; }","duration":"285.696393ms","start":"2026-05-19T00:21:50.563006Z","end":"2026-05-19T00:21:50.848702Z","steps":["trace[1106779749] 'process raft request' (duration: 285.425127ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:50.848821Z","caller":"traceutil/trace.go:171","msg":"trace[1294453338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8596; }","duration":"290.353557ms","start":"2026-05-19T00:21:50.558459Z","end":"2026-05-19T00:21:50.848812Z","steps":["trace[1294453338] 'agreement among raft nodes before linearized reading' (duration: 290.225944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:50.849003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.145592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-19T00:21:50.849022Z","caller":"traceutil/trace.go:171","msg":"trace[268862170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8596; }","duration":"269.194324ms","start":"2026-05-19T00:21:50.579822Z","end":"2026-05-19T00:21:50.849016Z","steps":["trace[268862170] 'agreement among raft nodes before linearized reading' (duration: 269.121442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:50.849072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.268708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:21:50.849085Z","caller":"traceutil/trace.go:171","msg":"trace[1352345273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8596; }","duration":"258.322909ms","start":"2026-05-19T00:21:50.590758Z","end":"2026-05-19T00:21:50.849081Z","steps":["trace[1352345273] 'agreement among raft nodes before linearized reading' (duration: 258.301609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:50.849124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.945275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"warn","ts":"2026-05-19T00:21:50.849126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.202982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2tbk7\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-05-19T00:21:50.84916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.788896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T00:21:50.849167Z","caller":"traceutil/trace.go:171","msg":"trace[484968207] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8596; }","duration":"121.030547ms","start":"2026-05-19T00:21:50.728124Z","end":"2026-05-19T00:21:50.849154Z","steps":["trace[484968207] 'agreement among raft nodes before linearized reading' (duration: 120.898904ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:50.849229Z","caller":"traceutil/trace.go:171","msg":"trace[2052887476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8596; }","duration":"191.881137ms","start":"2026-05-19T00:21:50.657328Z","end":"2026-05-19T00:21:50.849209Z","steps":["trace[2052887476] 'agreement among raft nodes before linearized reading' (duration: 191.717393ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:50.849179Z","caller":"traceutil/trace.go:171","msg":"trace[391878640] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2tbk7; range_end:; response_count:1; response_revision:8596; }","duration":"175.297434ms","start":"2026-05-19T00:21:50.673867Z","end":"2026-05-19T00:21:50.849165Z","steps":["trace[391878640] 'agreement among raft nodes before linearized reading' (duration: 175.142471ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:50.962903Z","caller":"traceutil/trace.go:171","msg":"trace[1624755413] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"109.217461ms","start":"2026-05-19T00:21:50.853661Z","end":"2026-05-19T00:21:50.962879Z","steps":["trace[1624755413] 'process raft request' (duration: 102.014605ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:51.009351Z","caller":"traceutil/trace.go:171","msg":"trace[1972227029] transaction","detail":"{read_only:false; response_revision:8598; number_of_response:1; }","duration":"155.601465ms","start":"2026-05-19T00:21:50.853691Z","end":"2026-05-19T00:21:51.009292Z","steps":["trace[1972227029] 'process raft request' (duration: 155.336969ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:51.009371Z","caller":"traceutil/trace.go:171","msg":"trace[1682574432] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"155.302199ms","start":"2026-05-19T00:21:50.854047Z","end":"2026-05-19T00:21:51.009349Z","steps":["trace[1682574432] 'process raft request' (duration: 155.113874ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:51.009538Z","caller":"traceutil/trace.go:171","msg":"trace[1740000609] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"152.959552ms","start":"2026-05-19T00:21:50.856561Z","end":"2026-05-19T00:21:51.009521Z","steps":["trace[1740000609] 'process raft request' (duration: 152.684585ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:51.009576Z","caller":"traceutil/trace.go:171","msg":"trace[1228167678] linearizableReadLoop","detail":"{readStateIndex:8902; appliedIndex:8900; }","duration":"155.639737ms","start":"2026-05-19T00:21:50.853915Z","end":"2026-05-19T00:21:51.009555Z","steps":["trace[1228167678] 'read index received' (duration: 101.762929ms)","trace[1228167678] 'applied index is now lower than readState.Index' (duration: 53.875848ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:21:51.009623Z","caller":"traceutil/trace.go:171","msg":"trace[1254975437] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"145.962562ms","start":"2026-05-19T00:21:50.863645Z","end":"2026-05-19T00:21:51.009607Z","steps":["trace[1254975437] 'process raft request' (duration: 145.798978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:51.010124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.18605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228784"} {"level":"info","ts":"2026-05-19T00:21:51.010199Z","caller":"traceutil/trace.go:171","msg":"trace[2066221213] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8601; }","duration":"156.305513ms","start":"2026-05-19T00:21:50.853879Z","end":"2026-05-19T00:21:51.010184Z","steps":["trace[2066221213] 'agreement among raft nodes before linearized reading' (duration: 155.7604ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:51.493542Z","caller":"traceutil/trace.go:171","msg":"trace[1389518013] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"121.702504ms","start":"2026-05-19T00:21:51.371811Z","end":"2026-05-19T00:21:51.493514Z","steps":["trace[1389518013] 'process raft request' (duration: 121.513979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:52.101723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.263442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T00:21:52.101926Z","caller":"traceutil/trace.go:171","msg":"trace[2001717324] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"248.299486ms","start":"2026-05-19T00:21:51.853608Z","end":"2026-05-19T00:21:52.101907Z","steps":["trace[2001717324] 'process raft request' (duration: 248.234024ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:52.101925Z","caller":"traceutil/trace.go:171","msg":"trace[818034111] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"412.588784ms","start":"2026-05-19T00:21:51.689303Z","end":"2026-05-19T00:21:52.101892Z","steps":["trace[818034111] 'process raft request' (duration: 249.070825ms)","trace[818034111] 'compare' (duration: 163.126319ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:21:52.102015Z","caller":"traceutil/trace.go:171","msg":"trace[151150905] linearizableReadLoop","detail":"{readStateIndex:8908; appliedIndex:8906; }","duration":"374.658634ms","start":"2026-05-19T00:21:51.727323Z","end":"2026-05-19T00:21:52.101982Z","steps":["trace[151150905] 'read index received' (duration: 41.687252ms)","trace[151150905] 'applied index is now lower than readState.Index' (duration: 332.968432ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:21:52.102052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:21:51.689275Z","time spent":"412.707386ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:21:52.102182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.865358ms","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-05-19T00:21:52.102214Z","caller":"traceutil/trace.go:171","msg":"trace[74273484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8605; }","duration":"374.93172ms","start":"2026-05-19T00:21:51.727276Z","end":"2026-05-19T00:21:52.102208Z","steps":["trace[74273484] 'agreement among raft nodes before linearized reading' (duration: 374.809837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:52.102234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:21:51.727258Z","time spent":"374.972421ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T00:22:22.205778Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000240d.wal"} {"level":"info","ts":"2026-05-19T00:23:04.468506Z","caller":"traceutil/trace.go:171","msg":"trace[807299185] transaction","detail":"{read_only:false; response_revision:9326; number_of_response:1; }","duration":"110.760399ms","start":"2026-05-19T00:23:04.357717Z","end":"2026-05-19T00:23:04.468477Z","steps":["trace[807299185] 'process raft request' (duration: 69.085227ms)","trace[807299185] 'compare' (duration: 41.550969ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:23:04.770861Z","caller":"traceutil/trace.go:171","msg":"trace[915309207] linearizableReadLoop","detail":"{readStateIndex:9651; appliedIndex:9650; }","duration":"217.431079ms","start":"2026-05-19T00:23:04.553407Z","end":"2026-05-19T00:23:04.770839Z","steps":["trace[915309207] 'read index received' (duration: 198.499839ms)","trace[915309207] 'applied index is now lower than readState.Index' (duration: 18.93048ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:23:04.771003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.867662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:23:04.77096Z","caller":"traceutil/trace.go:171","msg":"trace[1319418962] transaction","detail":"{read_only:false; response_revision:9327; number_of_response:1; }","duration":"241.863703ms","start":"2026-05-19T00:23:04.529056Z","end":"2026-05-19T00:23:04.770919Z","steps":["trace[1319418962] 'process raft request' (duration: 222.827381ms)","trace[1319418962] 'compare' (duration: 18.857788ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:23:04.771036Z","caller":"traceutil/trace.go:171","msg":"trace[664954951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9327; }","duration":"180.938014ms","start":"2026-05-19T00:23:04.590088Z","end":"2026-05-19T00:23:04.771026Z","steps":["trace[664954951] 'agreement among raft nodes before linearized reading' (duration: 180.868752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:23:04.771286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.858439ms","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-05-19T00:23:04.77154Z","caller":"traceutil/trace.go:171","msg":"trace[2033044002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9327; }","duration":"218.061355ms","start":"2026-05-19T00:23:04.553353Z","end":"2026-05-19T00:23:04.771414Z","steps":["trace[2033044002] 'agreement among raft nodes before linearized reading' (duration: 217.565673ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:23:52.199925Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T00:23:52.204285Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T00:23:52.204388Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-19T00:26:19.104644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8333} {"level":"info","ts":"2026-05-19T00:26:19.177745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8333,"took":"70.757267ms","hash":913472756,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":26460160,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T00:26:19.177808Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":913472756,"revision":8333,"compact-revision":6163} {"level":"info","ts":"2026-05-19T00:26:49.261324Z","caller":"traceutil/trace.go:171","msg":"trace[1512013726] transaction","detail":"{read_only:false; response_revision:11179; number_of_response:1; }","duration":"135.924027ms","start":"2026-05-19T00:26:49.12537Z","end":"2026-05-19T00:26:49.261294Z","steps":["trace[1512013726] 'process raft request' (duration: 135.768654ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:26:49.794613Z","caller":"traceutil/trace.go:171","msg":"trace[1207754110] transaction","detail":"{read_only:false; response_revision:11182; number_of_response:1; }","duration":"102.219921ms","start":"2026-05-19T00:26:49.692373Z","end":"2026-05-19T00:26:49.794593Z","steps":["trace[1207754110] 'process raft request' (duration: 94.085874ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:26:50.154438Z","caller":"traceutil/trace.go:171","msg":"trace[337485916] transaction","detail":"{read_only:false; response_revision:11187; number_of_response:1; }","duration":"103.889921ms","start":"2026-05-19T00:26:50.050513Z","end":"2026-05-19T00:26:50.154403Z","steps":["trace[337485916] 'process raft request' (duration: 103.707207ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:31:19.115431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10975} {"level":"info","ts":"2026-05-19T00:31:19.212617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10975,"took":"93.683316ms","hash":2380904799,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":28020736,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-19T00:31:19.21273Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2380904799,"revision":10975,"compact-revision":8333} {"level":"warn","ts":"2026-05-19T00:33:18.82045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.017317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T00:33:18.820563Z","caller":"traceutil/trace.go:171","msg":"trace[2135845074] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:13800; }","duration":"114.179391ms","start":"2026-05-19T00:33:18.706354Z","end":"2026-05-19T00:33:18.820533Z","steps":["trace[2135845074] 'count revisions from in-memory index tree' (duration: 113.887563ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:33:19.941258Z","caller":"traceutil/trace.go:171","msg":"trace[1000651147] linearizableReadLoop","detail":"{readStateIndex:14272; appliedIndex:14271; }","duration":"385.591438ms","start":"2026-05-19T00:33:19.555645Z","end":"2026-05-19T00:33:19.941236Z","steps":["trace[1000651147] 'read index received' (duration: 385.407633ms)","trace[1000651147] 'applied index is now lower than readState.Index' (duration: 182.945µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:33:19.941597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.983246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:33:19.941651Z","caller":"traceutil/trace.go:171","msg":"trace[74760710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13803; }","duration":"349.077737ms","start":"2026-05-19T00:33:19.592564Z","end":"2026-05-19T00:33:19.941641Z","steps":["trace[74760710] 'agreement among raft nodes before linearized reading' (duration: 348.986245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:33:19.941671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.019818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T00:33:19.941718Z","caller":"traceutil/trace.go:171","msg":"trace[1461618311] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:13803; }","duration":"386.076969ms","start":"2026-05-19T00:33:19.555629Z","end":"2026-05-19T00:33:19.941706Z","steps":["trace[1461618311] 'agreement among raft nodes before linearized reading' (duration: 385.927745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:33:19.94173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.246202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T00:33:19.941754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:33:19.555608Z","time spent":"386.1367ms","remote":"127.0.0.1:42318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":63,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-19T00:33:19.94176Z","caller":"traceutil/trace.go:171","msg":"trace[345756388] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:13803; }","duration":"168.307824ms","start":"2026-05-19T00:33:19.773442Z","end":"2026-05-19T00:33:19.94175Z","steps":["trace[345756388] 'agreement among raft nodes before linearized reading' (duration: 168.263043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:33:19.94177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.821211ms","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-05-19T00:33:19.941818Z","caller":"traceutil/trace.go:171","msg":"trace[534926053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13803; }","duration":"167.901303ms","start":"2026-05-19T00:33:19.773903Z","end":"2026-05-19T00:33:19.941805Z","steps":["trace[534926053] 'agreement among raft nodes before linearized reading' (duration: 167.725659ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:33:19.941946Z","caller":"traceutil/trace.go:171","msg":"trace[1180145579] transaction","detail":"{read_only:false; response_revision:13803; number_of_response:1; }","duration":"402.512696ms","start":"2026-05-19T00:33:19.539422Z","end":"2026-05-19T00:33:19.941934Z","steps":["trace[1180145579] 'process raft request' (duration: 401.683236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:33:19.942029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:33:19.539405Z","time spent":"402.567297ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:33:19.942049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.002095ms","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-05-19T00:33:19.94208Z","caller":"traceutil/trace.go:171","msg":"trace[240470450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13803; }","duration":"160.068057ms","start":"2026-05-19T00:33:19.782003Z","end":"2026-05-19T00:33:19.942071Z","steps":["trace[240470450] 'agreement among raft nodes before linearized reading' (duration: 159.980975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:33:19.941684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:33:19.592548Z","time spent":"349.128879ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T00:33:30.180166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.180485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T00:33:30.180285Z","caller":"traceutil/trace.go:171","msg":"trace[1848016490] transaction","detail":"{read_only:false; response_revision:13975; number_of_response:1; }","duration":"163.454557ms","start":"2026-05-19T00:33:30.016812Z","end":"2026-05-19T00:33:30.180267Z","steps":["trace[1848016490] 'process raft request' (duration: 11.086197ms)","trace[1848016490] 'compare' (duration: 151.890198ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:33:30.453238Z","caller":"traceutil/trace.go:171","msg":"trace[1532591617] transaction","detail":"{read_only:false; response_revision:13976; number_of_response:1; }","duration":"107.625313ms","start":"2026-05-19T00:33:30.345583Z","end":"2026-05-19T00:33:30.453208Z","steps":["trace[1532591617] 'process raft request' (duration: 106.185478ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:35:12.027014Z","caller":"traceutil/trace.go:171","msg":"trace[1174083529] transaction","detail":"{read_only:false; response_revision:15071; number_of_response:1; }","duration":"129.235894ms","start":"2026-05-19T00:35:11.89775Z","end":"2026-05-19T00:35:12.026986Z","steps":["trace[1174083529] 'process raft request' (duration: 128.839574ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:36:19.14233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13028} {"level":"info","ts":"2026-05-19T00:36:19.363096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13028,"took":"217.744627ms","hash":2238574881,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":31031296,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T00:36:19.363195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2238574881,"revision":13028,"compact-revision":10975} {"level":"info","ts":"2026-05-19T00:40:46.204631Z","caller":"traceutil/trace.go:171","msg":"trace[1051272607] transaction","detail":"{read_only:false; response_revision:17636; number_of_response:1; }","duration":"109.01035ms","start":"2026-05-19T00:40:46.095597Z","end":"2026-05-19T00:40:46.204608Z","steps":["trace[1051272607] 'process raft request' (duration: 10.960842ms)","trace[1051272607] 'compare' (duration: 97.742431ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:40:52.476726Z","caller":"traceutil/trace.go:171","msg":"trace[625139756] linearizableReadLoop","detail":"{readStateIndex:18283; appliedIndex:18281; }","duration":"120.695289ms","start":"2026-05-19T00:40:52.35601Z","end":"2026-05-19T00:40:52.476705Z","steps":["trace[625139756] 'read index received' (duration: 54.648183ms)","trace[625139756] 'applied index is now lower than readState.Index' (duration: 66.046046ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:40:52.476924Z","caller":"traceutil/trace.go:171","msg":"trace[1489576012] transaction","detail":"{read_only:false; response_revision:17688; number_of_response:1; }","duration":"165.643512ms","start":"2026-05-19T00:40:52.311244Z","end":"2026-05-19T00:40:52.476887Z","steps":["trace[1489576012] 'process raft request' (duration: 99.401631ms)","trace[1489576012] 'compare' (duration: 65.823931ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:40:52.477219Z","caller":"traceutil/trace.go:171","msg":"trace[2084308856] transaction","detail":"{read_only:false; response_revision:17689; number_of_response:1; }","duration":"140.00848ms","start":"2026-05-19T00:40:52.337202Z","end":"2026-05-19T00:40:52.47721Z","steps":["trace[2084308856] 'process raft request' (duration: 139.383235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:40:52.477502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.474617ms","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-05-19T00:40:52.477705Z","caller":"traceutil/trace.go:171","msg":"trace[1595488175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17691; }","duration":"121.710722ms","start":"2026-05-19T00:40:52.35598Z","end":"2026-05-19T00:40:52.477691Z","steps":["trace[1595488175] 'agreement among raft nodes before linearized reading' (duration: 121.20314ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:40:53.029033Z","caller":"traceutil/trace.go:171","msg":"trace[410736435] linearizableReadLoop","detail":"{readStateIndex:18287; appliedIndex:18286; }","duration":"149.836825ms","start":"2026-05-19T00:40:52.879173Z","end":"2026-05-19T00:40:53.02901Z","steps":["trace[410736435] 'read index received' (duration: 149.650511ms)","trace[410736435] 'applied index is now lower than readState.Index' (duration: 185.514µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:40:53.029323Z","caller":"traceutil/trace.go:171","msg":"trace[838604338] transaction","detail":"{read_only:false; response_revision:17693; number_of_response:1; }","duration":"198.456624ms","start":"2026-05-19T00:40:52.830854Z","end":"2026-05-19T00:40:53.029311Z","steps":["trace[838604338] 'process raft request' (duration: 198.026244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:40:53.029578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.392198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-hdjf5\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-05-19T00:40:53.029612Z","caller":"traceutil/trace.go:171","msg":"trace[1281064321] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-hdjf5; range_end:; response_count:1; response_revision:17693; }","duration":"150.48112ms","start":"2026-05-19T00:40:52.879119Z","end":"2026-05-19T00:40:53.0296Z","steps":["trace[1281064321] 'agreement among raft nodes before linearized reading' (duration: 150.354707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:40:53.029826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.591612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T00:40:53.029854Z","caller":"traceutil/trace.go:171","msg":"trace[1474531445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17693; }","duration":"150.674085ms","start":"2026-05-19T00:40:52.879171Z","end":"2026-05-19T00:40:53.029845Z","steps":["trace[1474531445] 'agreement among raft nodes before linearized reading' (duration: 150.593173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:40:53.030671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.357101ms","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-05-19T00:40:53.030705Z","caller":"traceutil/trace.go:171","msg":"trace[105551531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17693; }","duration":"151.426192ms","start":"2026-05-19T00:40:52.879269Z","end":"2026-05-19T00:40:53.030695Z","steps":["trace[105551531] 'agreement among raft nodes before linearized reading' (duration: 151.34745ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:40:53.227719Z","caller":"traceutil/trace.go:171","msg":"trace[927890825] transaction","detail":"{read_only:false; response_revision:17694; number_of_response:1; }","duration":"191.372596ms","start":"2026-05-19T00:40:53.036314Z","end":"2026-05-19T00:40:53.227687Z","steps":["trace[927890825] 'process raft request' (duration: 191.14404ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:40:53.306902Z","caller":"traceutil/trace.go:171","msg":"trace[501030654] transaction","detail":"{read_only:false; response_revision:17695; number_of_response:1; }","duration":"269.581571ms","start":"2026-05-19T00:40:53.037286Z","end":"2026-05-19T00:40:53.306868Z","steps":["trace[501030654] 'process raft request' (duration: 269.350696ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:40:53.307121Z","caller":"traceutil/trace.go:171","msg":"trace[429253410] transaction","detail":"{read_only:false; response_revision:17696; number_of_response:1; }","duration":"259.637583ms","start":"2026-05-19T00:40:53.047465Z","end":"2026-05-19T00:40:53.307102Z","steps":["trace[429253410] 'process raft request' (duration: 259.317786ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:40:53.307293Z","caller":"traceutil/trace.go:171","msg":"trace[819561049] linearizableReadLoop","detail":"{readStateIndex:18289; appliedIndex:18287; }","duration":"269.238532ms","start":"2026-05-19T00:40:53.038027Z","end":"2026-05-19T00:40:53.307266Z","steps":["trace[819561049] 'read index received' (duration: 189.4813ms)","trace[819561049] 'applied index is now lower than readState.Index' (duration: 79.755952ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:40:53.307477Z","caller":"traceutil/trace.go:171","msg":"trace[1166346528] transaction","detail":"{read_only:false; response_revision:17697; number_of_response:1; }","duration":"100.853586ms","start":"2026-05-19T00:40:53.20657Z","end":"2026-05-19T00:40:53.307424Z","steps":["trace[1166346528] 'process raft request' (duration: 100.465387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:40:53.308071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.019801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:952982"} {"level":"info","ts":"2026-05-19T00:40:53.308116Z","caller":"traceutil/trace.go:171","msg":"trace[335114145] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:17697; }","duration":"270.098173ms","start":"2026-05-19T00:40:53.038008Z","end":"2026-05-19T00:40:53.308106Z","steps":["trace[335114145] 'agreement among raft nodes before linearized reading' (duration: 269.332845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:40:53.308974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.477837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:952982"} {"level":"info","ts":"2026-05-19T00:40:53.30903Z","caller":"traceutil/trace.go:171","msg":"trace[1260546272] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:17697; }","duration":"266.561058ms","start":"2026-05-19T00:40:53.042453Z","end":"2026-05-19T00:40:53.309014Z","steps":["trace[1260546272] 'agreement among raft nodes before linearized reading' (duration: 265.645656ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:41:19.153266Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15514} {"level":"info","ts":"2026-05-19T00:41:19.248359Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15514,"took":"90.10857ms","hash":637745000,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":33484800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T00:41:19.24843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":637745000,"revision":15514,"compact-revision":13028} {"level":"info","ts":"2026-05-19T00:44:23.064127Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T00:44:23.069813Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T00:44:23.069961Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-05-19T00:46:15.349607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.79417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:46:15.34973Z","caller":"traceutil/trace.go:171","msg":"trace[878013149] transaction","detail":"{read_only:false; response_revision:20156; number_of_response:1; }","duration":"286.639844ms","start":"2026-05-19T00:46:15.063068Z","end":"2026-05-19T00:46:15.349708Z","steps":["trace[878013149] 'process raft request' (duration: 115.649639ms)","trace[878013149] 'compare' (duration: 170.669747ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:46:19.159277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18095} {"level":"info","ts":"2026-05-19T00:46:19.279513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18095,"took":"117.302619ms","hash":2562491991,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":30461952,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T00:46:19.279591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2562491991,"revision":18095,"compact-revision":15514} {"level":"warn","ts":"2026-05-19T00:46:31.624368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.700313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T00:46:31.624482Z","caller":"traceutil/trace.go:171","msg":"trace[608428016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20241; }","duration":"184.863757ms","start":"2026-05-19T00:46:31.439599Z","end":"2026-05-19T00:46:31.624462Z","steps":["trace[608428016] 'range keys from in-memory index tree' (duration: 184.515649ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:46:31.654075Z","caller":"traceutil/trace.go:171","msg":"trace[1066502530] transaction","detail":"{read_only:false; response_revision:20242; number_of_response:1; }","duration":"109.288357ms","start":"2026-05-19T00:46:31.54477Z","end":"2026-05-19T00:46:31.654058Z","steps":["trace[1066502530] 'process raft request' (duration: 109.144284ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:46:31.912095Z","caller":"traceutil/trace.go:171","msg":"trace[211411896] transaction","detail":"{read_only:false; response_revision:20244; number_of_response:1; }","duration":"102.059514ms","start":"2026-05-19T00:46:31.81001Z","end":"2026-05-19T00:46:31.91207Z","steps":["trace[211411896] 'process raft request' (duration: 101.88912ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:46:31.952697Z","caller":"traceutil/trace.go:171","msg":"trace[147601771] transaction","detail":"{read_only:false; response_revision:20245; number_of_response:1; }","duration":"106.206854ms","start":"2026-05-19T00:46:31.846461Z","end":"2026-05-19T00:46:31.952668Z","steps":["trace[147601771] 'process raft request' (duration: 106.041329ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:50:07.428254Z","caller":"traceutil/trace.go:171","msg":"trace[1517910638] transaction","detail":"{read_only:false; response_revision:22425; number_of_response:1; }","duration":"101.7112ms","start":"2026-05-19T00:50:07.326487Z","end":"2026-05-19T00:50:07.428198Z","steps":["trace[1517910638] 'process raft request' (duration: 101.606498ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:51:19.16771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20179} {"level":"info","ts":"2026-05-19T00:51:19.270494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20179,"took":"99.816655ms","hash":2640703039,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":41594880,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-19T00:51:19.270593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2640703039,"revision":20179,"compact-revision":18095} {"level":"warn","ts":"2026-05-19T00:53:15.66834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.61119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:15.668519Z","caller":"traceutil/trace.go:171","msg":"trace[179131729] linearizableReadLoop","detail":"{readStateIndex:25293; appliedIndex:25291; }","duration":"208.025941ms","start":"2026-05-19T00:53:15.460477Z","end":"2026-05-19T00:53:15.668503Z","steps":["trace[179131729] 'read index received' (duration: 53.180386ms)","trace[179131729] 'applied index is now lower than readState.Index' (duration: 154.844384ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:15.668808Z","caller":"traceutil/trace.go:171","msg":"trace[1340045592] transaction","detail":"{read_only:false; response_revision:24493; number_of_response:1; }","duration":"274.834854ms","start":"2026-05-19T00:53:15.393947Z","end":"2026-05-19T00:53:15.668782Z","steps":["trace[1340045592] 'process raft request' (duration: 274.486106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:15.668837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.348648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:15.66888Z","caller":"traceutil/trace.go:171","msg":"trace[1034415149] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:24493; }","duration":"208.43735ms","start":"2026-05-19T00:53:15.46043Z","end":"2026-05-19T00:53:15.668867Z","steps":["trace[1034415149] 'agreement among raft nodes before linearized reading' (duration: 208.358238ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:15.66924Z","caller":"traceutil/trace.go:171","msg":"trace[109641998] transaction","detail":"{read_only:false; response_revision:24492; number_of_response:1; }","duration":"299.598388ms","start":"2026-05-19T00:53:15.369596Z","end":"2026-05-19T00:53:15.669194Z","steps":["trace[109641998] 'process raft request' (duration: 144.049666ms)","trace[109641998] 'compare' (duration: 154.419375ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:15.912287Z","caller":"traceutil/trace.go:171","msg":"trace[1610106331] transaction","detail":"{read_only:false; response_revision:24494; number_of_response:1; }","duration":"240.4948ms","start":"2026-05-19T00:53:15.671773Z","end":"2026-05-19T00:53:15.912268Z","steps":["trace[1610106331] 'process raft request' (duration: 240.348516ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:15.936891Z","caller":"traceutil/trace.go:171","msg":"trace[1479897055] transaction","detail":"{read_only:false; response_revision:24495; number_of_response:1; }","duration":"220.019869ms","start":"2026-05-19T00:53:15.716853Z","end":"2026-05-19T00:53:15.936873Z","steps":["trace[1479897055] 'process raft request' (duration: 219.867436ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:16.064906Z","caller":"traceutil/trace.go:171","msg":"trace[2024635724] transaction","detail":"{read_only:false; response_revision:24497; number_of_response:1; }","duration":"127.000387ms","start":"2026-05-19T00:53:15.937859Z","end":"2026-05-19T00:53:16.064859Z","steps":["trace[2024635724] 'process raft request' (duration: 114.250442ms)","trace[2024635724] 'compare' (duration: 12.618842ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:16.219846Z","caller":"traceutil/trace.go:171","msg":"trace[1758743140] linearizableReadLoop","detail":"{readStateIndex:25299; appliedIndex:25298; }","duration":"130.42774ms","start":"2026-05-19T00:53:16.08939Z","end":"2026-05-19T00:53:16.219818Z","steps":["trace[1758743140] 'read index received' (duration: 116.088806ms)","trace[1758743140] 'applied index is now lower than readState.Index' (duration: 14.337483ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:16.220003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.582193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:16.220056Z","caller":"traceutil/trace.go:171","msg":"trace[1487900551] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:24498; }","duration":"130.674896ms","start":"2026-05-19T00:53:16.089362Z","end":"2026-05-19T00:53:16.220037Z","steps":["trace[1487900551] 'agreement among raft nodes before linearized reading' (duration: 130.565053ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:16.585409Z","caller":"traceutil/trace.go:171","msg":"trace[766754846] linearizableReadLoop","detail":"{readStateIndex:25300; appliedIndex:25299; }","duration":"338.05393ms","start":"2026-05-19T00:53:16.247336Z","end":"2026-05-19T00:53:16.58539Z","steps":["trace[766754846] 'read index received' (duration: 337.855705ms)","trace[766754846] 'applied index is now lower than readState.Index' (duration: 197.714µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:16.585627Z","caller":"traceutil/trace.go:171","msg":"trace[1020898086] transaction","detail":"{read_only:false; response_revision:24499; number_of_response:1; }","duration":"364.569456ms","start":"2026-05-19T00:53:16.221021Z","end":"2026-05-19T00:53:16.585591Z","steps":["trace[1020898086] 'process raft request' (duration: 364.236258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:16.585763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.408668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T00:53:16.585806Z","caller":"traceutil/trace.go:171","msg":"trace[383655751] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24499; }","duration":"338.48866ms","start":"2026-05-19T00:53:16.247308Z","end":"2026-05-19T00:53:16.585797Z","steps":["trace[383655751] 'agreement among raft nodes before linearized reading' (duration: 338.338747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:16.585832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:16.247294Z","time spent":"338.531201ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-19T00:53:16.585923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:16.220996Z","time spent":"364.7323ms","remote":"127.0.0.1:42232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:16.623419Z","caller":"traceutil/trace.go:171","msg":"trace[740975695] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"139.925107ms","start":"2026-05-19T00:53:16.483466Z","end":"2026-05-19T00:53:16.623391Z","steps":["trace[740975695] 'process raft request' (duration: 139.465156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:16.623743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.60768ms","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-05-19T00:53:16.623783Z","caller":"traceutil/trace.go:171","msg":"trace[185966914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24500; }","duration":"164.674251ms","start":"2026-05-19T00:53:16.459098Z","end":"2026-05-19T00:53:16.623772Z","steps":["trace[185966914] 'agreement among raft nodes before linearized reading' (duration: 164.534438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:16.623962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.922242ms","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-05-19T00:53:16.623996Z","caller":"traceutil/trace.go:171","msg":"trace[2040218672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24500; }","duration":"168.955964ms","start":"2026-05-19T00:53:16.455032Z","end":"2026-05-19T00:53:16.623988Z","steps":["trace[2040218672] 'agreement among raft nodes before linearized reading' (duration: 168.868732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:16.62413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.281508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:16.624165Z","caller":"traceutil/trace.go:171","msg":"trace[558078778] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24500; }","duration":"341.35204ms","start":"2026-05-19T00:53:16.282803Z","end":"2026-05-19T00:53:16.624155Z","steps":["trace[558078778] 'agreement among raft nodes before linearized reading' (duration: 341.300729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:16.624193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:16.282782Z","time spent":"341.402341ms","remote":"127.0.0.1:46454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-05-19T00:53:16.86211Z","caller":"traceutil/trace.go:171","msg":"trace[380874452] transaction","detail":"{read_only:false; response_revision:24501; number_of_response:1; }","duration":"233.149504ms","start":"2026-05-19T00:53:16.628921Z","end":"2026-05-19T00:53:16.862071Z","steps":["trace[380874452] 'process raft request' (duration: 220.296175ms)","trace[380874452] 'compare' (duration: 12.370687ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:17.02867Z","caller":"traceutil/trace.go:171","msg":"trace[2011724272] transaction","detail":"{read_only:false; response_revision:24502; number_of_response:1; }","duration":"399.287511ms","start":"2026-05-19T00:53:16.629347Z","end":"2026-05-19T00:53:17.028634Z","steps":["trace[2011724272] 'process raft request' (duration: 351.172176ms)","trace[2011724272] 'compare' (duration: 47.90281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:17.02899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:16.629324Z","time spent":"399.539297ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:17.029068Z","caller":"traceutil/trace.go:171","msg":"trace[724431775] transaction","detail":"{read_only:false; response_revision:24503; number_of_response:1; }","duration":"399.432525ms","start":"2026-05-19T00:53:16.629597Z","end":"2026-05-19T00:53:17.02903Z","steps":["trace[724431775] 'process raft request' (duration: 398.981243ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:17.029103Z","caller":"traceutil/trace.go:171","msg":"trace[1243223693] linearizableReadLoop","detail":"{readStateIndex:25305; appliedIndex:25301; }","duration":"348.061302ms","start":"2026-05-19T00:53:16.681007Z","end":"2026-05-19T00:53:17.029068Z","steps":["trace[1243223693] 'read index received' (duration: 168.231525ms)","trace[1243223693] 'applied index is now lower than readState.Index' (duration: 179.828657ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:17.029151Z","caller":"traceutil/trace.go:171","msg":"trace[918109187] transaction","detail":"{read_only:false; response_revision:24504; number_of_response:1; }","duration":"376.447334ms","start":"2026-05-19T00:53:16.652652Z","end":"2026-05-19T00:53:17.0291Z","steps":["trace[918109187] 'process raft request' (duration: 376.202748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:17.029272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:16.629574Z","time spent":"399.588538ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:17.029295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.283318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T00:53:17.029334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:16.652634Z","time spent":"376.609027ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:17.029353Z","caller":"traceutil/trace.go:171","msg":"trace[1370682596] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24504; }","duration":"348.38242ms","start":"2026-05-19T00:53:16.680955Z","end":"2026-05-19T00:53:17.029338Z","steps":["trace[1370682596] 'agreement among raft nodes before linearized reading' (duration: 348.270088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:17.029393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:16.680931Z","time spent":"348.454431ms","remote":"127.0.0.1:42584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-19T00:53:17.029421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.612435ms","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-05-19T00:53:17.029482Z","caller":"traceutil/trace.go:171","msg":"trace[502527422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24504; }","duration":"120.712367ms","start":"2026-05-19T00:53:16.908754Z","end":"2026-05-19T00:53:17.029466Z","steps":["trace[502527422] 'agreement among raft nodes before linearized reading' (duration: 120.519813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:17.029501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.080512ms","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-05-19T00:53:17.02939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.864793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-19T00:53:17.029585Z","caller":"traceutil/trace.go:171","msg":"trace[776150027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24504; }","duration":"125.217256ms","start":"2026-05-19T00:53:16.904349Z","end":"2026-05-19T00:53:17.029566Z","steps":["trace[776150027] 'agreement among raft nodes before linearized reading' (duration: 124.96109ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:17.029727Z","caller":"traceutil/trace.go:171","msg":"trace[1793474873] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24504; }","duration":"234.134529ms","start":"2026-05-19T00:53:16.795456Z","end":"2026-05-19T00:53:17.02959Z","steps":["trace[1793474873] 'agreement among raft nodes before linearized reading' (duration: 233.777601ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:21.863295Z","caller":"traceutil/trace.go:171","msg":"trace[1168289903] transaction","detail":"{read_only:false; response_revision:24550; number_of_response:1; }","duration":"175.393017ms","start":"2026-05-19T00:53:21.687881Z","end":"2026-05-19T00:53:21.863274Z","steps":["trace[1168289903] 'process raft request' (duration: 175.265784ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:22.054399Z","caller":"traceutil/trace.go:171","msg":"trace[1110253558] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"364.248888ms","start":"2026-05-19T00:53:21.690123Z","end":"2026-05-19T00:53:22.054371Z","steps":["trace[1110253558] 'process raft request' (duration: 309.350192ms)","trace[1110253558] 'compare' (duration: 54.61558ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:22.054536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:21.690102Z","time spent":"364.363973ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:22.054582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.564156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:22.054628Z","caller":"traceutil/trace.go:171","msg":"trace[1446649732] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24552; }","duration":"326.672597ms","start":"2026-05-19T00:53:21.727944Z","end":"2026-05-19T00:53:22.054617Z","steps":["trace[1446649732] 'agreement among raft nodes before linearized reading' (duration: 326.508123ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:22.054403Z","caller":"traceutil/trace.go:171","msg":"trace[1432194167] linearizableReadLoop","detail":"{readStateIndex:25353; appliedIndex:25351; }","duration":"326.38055ms","start":"2026-05-19T00:53:21.727997Z","end":"2026-05-19T00:53:22.054378Z","steps":["trace[1432194167] 'read index received' (duration: 135.167272ms)","trace[1432194167] 'applied index is now lower than readState.Index' (duration: 191.210387ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:22.054663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:21.727919Z","time spent":"326.733349ms","remote":"127.0.0.1:51400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-19T00:53:22.054778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.137139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T00:53:22.054817Z","caller":"traceutil/trace.go:171","msg":"trace[2097132896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24552; }","duration":"109.21187ms","start":"2026-05-19T00:53:21.945593Z","end":"2026-05-19T00:53:22.054805Z","steps":["trace[2097132896] 'agreement among raft nodes before linearized reading' (duration: 109.103467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:22.405866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.646346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:22.405949Z","caller":"traceutil/trace.go:171","msg":"trace[843931714] linearizableReadLoop","detail":"{readStateIndex:25356; appliedIndex:25355; }","duration":"223.42471ms","start":"2026-05-19T00:53:22.182511Z","end":"2026-05-19T00:53:22.405936Z","steps":["trace[843931714] 'read index received' (duration: 54.614491ms)","trace[843931714] 'applied index is now lower than readState.Index' (duration: 168.809369ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:22.406036Z","caller":"traceutil/trace.go:171","msg":"trace[1461070680] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"307.251092ms","start":"2026-05-19T00:53:22.098757Z","end":"2026-05-19T00:53:22.406008Z","steps":["trace[1461070680] 'process raft request' (duration: 138.379481ms)","trace[1461070680] 'compare' (duration: 168.453191ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:22.406082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:22.09874Z","time spent":"307.318473ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:22.406397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.885301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:22.406434Z","caller":"traceutil/trace.go:171","msg":"trace[170647953] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24554; }","duration":"223.953913ms","start":"2026-05-19T00:53:22.182468Z","end":"2026-05-19T00:53:22.406422Z","steps":["trace[170647953] 'agreement among raft nodes before linearized reading' (duration: 223.893621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:22.773257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.570376ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-19T00:53:22.773378Z","caller":"traceutil/trace.go:171","msg":"trace[2045192427] linearizableReadLoop","detail":"{readStateIndex:25357; appliedIndex:25356; }","duration":"363.598284ms","start":"2026-05-19T00:53:22.409762Z","end":"2026-05-19T00:53:22.77336Z","steps":["trace[2045192427] 'read index received' (duration: 221.902424ms)","trace[2045192427] 'applied index is now lower than readState.Index' (duration: 141.694329ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:22.773463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:22.407706Z","time spent":"365.753735ms","remote":"127.0.0.1:42196","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-19T00:53:22.773649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.87768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:22.77369Z","caller":"traceutil/trace.go:171","msg":"trace[1922695962] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24554; }","duration":"363.946232ms","start":"2026-05-19T00:53:22.409733Z","end":"2026-05-19T00:53:22.773679Z","steps":["trace[1922695962] 'agreement among raft nodes before linearized reading' (duration: 363.87448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:22.773723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:22.409714Z","time spent":"364.002273ms","remote":"127.0.0.1:42318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-19T00:53:22.775559Z","caller":"traceutil/trace.go:171","msg":"trace[631473857] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"129.597799ms","start":"2026-05-19T00:53:22.645942Z","end":"2026-05-19T00:53:22.77554Z","steps":["trace[631473857] 'process raft request' (duration: 129.463036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:22.780643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.082067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:22.780693Z","caller":"traceutil/trace.go:171","msg":"trace[1645326201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24556; }","duration":"189.169348ms","start":"2026-05-19T00:53:22.591512Z","end":"2026-05-19T00:53:22.780681Z","steps":["trace[1645326201] 'agreement among raft nodes before linearized reading' (duration: 189.097797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.069929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.662192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:23.07004Z","caller":"traceutil/trace.go:171","msg":"trace[168512609] linearizableReadLoop","detail":"{readStateIndex:25360; appliedIndex:25359; }","duration":"289.667579ms","start":"2026-05-19T00:53:22.780354Z","end":"2026-05-19T00:53:23.070021Z","steps":["trace[168512609] 'read index received' (duration: 106.71441ms)","trace[168512609] 'applied index is now lower than readState.Index' (duration: 182.951359ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:23.070145Z","caller":"traceutil/trace.go:171","msg":"trace[766661183] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"294.844103ms","start":"2026-05-19T00:53:22.775284Z","end":"2026-05-19T00:53:23.070128Z","steps":["trace[766661183] 'process raft request' (duration: 111.886254ms)","trace[766661183] 'compare' (duration: 180.888419ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:23.070581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.335914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T00:53:23.070629Z","caller":"traceutil/trace.go:171","msg":"trace[1492604393] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24557; }","duration":"292.411765ms","start":"2026-05-19T00:53:22.778203Z","end":"2026-05-19T00:53:23.070614Z","steps":["trace[1492604393] 'agreement among raft nodes before linearized reading' (duration: 292.21001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.070864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.408003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:23.070896Z","caller":"traceutil/trace.go:171","msg":"trace[1254192558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24557; }","duration":"289.461734ms","start":"2026-05-19T00:53:22.781425Z","end":"2026-05-19T00:53:23.070887Z","steps":["trace[1254192558] 'agreement among raft nodes before linearized reading' (duration: 289.405563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.071874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.140951ms","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-05-19T00:53:23.071903Z","caller":"traceutil/trace.go:171","msg":"trace[1114321901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24557; }","duration":"190.206683ms","start":"2026-05-19T00:53:22.881689Z","end":"2026-05-19T00:53:23.071896Z","steps":["trace[1114321901] 'agreement among raft nodes before linearized reading' (duration: 190.135951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.440406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.505867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:23.440505Z","caller":"traceutil/trace.go:171","msg":"trace[488002121] linearizableReadLoop","detail":"{readStateIndex:25361; appliedIndex:25360; }","duration":"357.710521ms","start":"2026-05-19T00:53:23.082778Z","end":"2026-05-19T00:53:23.440489Z","steps":["trace[488002121] 'read index received' (duration: 247.969299ms)","trace[488002121] 'applied index is now lower than readState.Index' (duration: 109.739832ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:23.440595Z","caller":"traceutil/trace.go:171","msg":"trace[1914974728] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"357.833744ms","start":"2026-05-19T00:53:23.082746Z","end":"2026-05-19T00:53:23.440579Z","steps":["trace[1914974728] 'process raft request' (duration: 248.075711ms)","trace[1914974728] 'compare' (duration: 109.084217ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:23.440654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:23.082732Z","time spent":"357.893285ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:23.440882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.120771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18621"} {"level":"info","ts":"2026-05-19T00:53:23.440919Z","caller":"traceutil/trace.go:171","msg":"trace[659809542] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24558; }","duration":"358.196832ms","start":"2026-05-19T00:53:23.08271Z","end":"2026-05-19T00:53:23.440907Z","steps":["trace[659809542] 'agreement among raft nodes before linearized reading' (duration: 358.050839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.440946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:23.082693Z","time spent":"358.247334ms","remote":"127.0.0.1:42296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18643,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-19T00:53:23.479471Z","caller":"traceutil/trace.go:171","msg":"trace[1075637280] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"394.78711ms","start":"2026-05-19T00:53:23.084664Z","end":"2026-05-19T00:53:23.479451Z","steps":["trace[1075637280] 'process raft request' (duration: 394.588136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.479595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:23.08464Z","time spent":"394.892433ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:23.479861Z","caller":"traceutil/trace.go:171","msg":"trace[454756409] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"394.107183ms","start":"2026-05-19T00:53:23.085742Z","end":"2026-05-19T00:53:23.479849Z","steps":["trace[454756409] 'process raft request' (duration: 393.665192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.479956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:23.085733Z","time spent":"394.166415ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:23.479972Z","caller":"traceutil/trace.go:171","msg":"trace[1293750940] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"393.645842ms","start":"2026-05-19T00:53:23.086267Z","end":"2026-05-19T00:53:23.479912Z","steps":["trace[1293750940] 'process raft request' (duration: 393.438047ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:23.48012Z","caller":"traceutil/trace.go:171","msg":"trace[1651521698] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"371.207404ms","start":"2026-05-19T00:53:23.108903Z","end":"2026-05-19T00:53:23.48011Z","steps":["trace[1651521698] 'process raft request' (duration: 370.942488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.480165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:23.086235Z","time spent":"393.838987ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:23.480189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:23.108887Z","time spent":"371.266656ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:23.480352Z","caller":"traceutil/trace.go:171","msg":"trace[310119859] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"275.356765ms","start":"2026-05-19T00:53:23.204975Z","end":"2026-05-19T00:53:23.480332Z","steps":["trace[310119859] 'process raft request' (duration: 275.089669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.480402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.348193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:23.480513Z","caller":"traceutil/trace.go:171","msg":"trace[1837524867] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24563; }","duration":"224.471956ms","start":"2026-05-19T00:53:23.256004Z","end":"2026-05-19T00:53:23.480476Z","steps":["trace[1837524867] 'agreement among raft nodes before linearized reading' (duration: 224.348223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.480594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.43402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-19T00:53:23.480633Z","caller":"traceutil/trace.go:171","msg":"trace[1053249469] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24563; }","duration":"396.474281ms","start":"2026-05-19T00:53:23.084149Z","end":"2026-05-19T00:53:23.480624Z","steps":["trace[1053249469] 'agreement among raft nodes before linearized reading' (duration: 396.360619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:23.480683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:23.084114Z","time spent":"396.547473ms","remote":"127.0.0.1:42196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-05-19T00:53:23.722184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.843071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:23.722403Z","caller":"traceutil/trace.go:171","msg":"trace[419966163] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"236.973126ms","start":"2026-05-19T00:53:23.485399Z","end":"2026-05-19T00:53:23.722373Z","steps":["trace[419966163] 'process raft request' (duration: 102.814877ms)","trace[419966163] 'compare' (duration: 133.322249ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:23.998376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.126483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:23.998484Z","caller":"traceutil/trace.go:171","msg":"trace[216326047] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"130.224444ms","start":"2026-05-19T00:53:23.868229Z","end":"2026-05-19T00:53:23.998453Z","steps":["trace[216326047] 'process raft request' (duration: 21.945427ms)","trace[216326047] 'compare' (duration: 107.791595ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:39.653032Z","caller":"traceutil/trace.go:171","msg":"trace[721398239] linearizableReadLoop","detail":"{readStateIndex:25497; appliedIndex:25495; }","duration":"101.117206ms","start":"2026-05-19T00:53:39.551896Z","end":"2026-05-19T00:53:39.653014Z","steps":["trace[721398239] 'read index received' (duration: 12.997291ms)","trace[721398239] 'applied index is now lower than readState.Index' (duration: 88.118965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:39.653376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.458714ms","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-05-19T00:53:39.653467Z","caller":"traceutil/trace.go:171","msg":"trace[1319094403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24692; }","duration":"101.549286ms","start":"2026-05-19T00:53:39.551892Z","end":"2026-05-19T00:53:39.653441Z","steps":["trace[1319094403] 'agreement among raft nodes before linearized reading' (duration: 101.28926ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:39.653633Z","caller":"traceutil/trace.go:171","msg":"trace[883762247] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"118.910903ms","start":"2026-05-19T00:53:39.534708Z","end":"2026-05-19T00:53:39.653619Z","steps":["trace[883762247] 'process raft request' (duration: 118.147875ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:39.653851Z","caller":"traceutil/trace.go:171","msg":"trace[1348217036] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"101.077745ms","start":"2026-05-19T00:53:39.552758Z","end":"2026-05-19T00:53:39.653836Z","steps":["trace[1348217036] 'process raft request' (duration: 100.217724ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:39.809943Z","caller":"traceutil/trace.go:171","msg":"trace[13802647] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"151.464445ms","start":"2026-05-19T00:53:39.658449Z","end":"2026-05-19T00:53:39.809913Z","steps":["trace[13802647] 'process raft request' (duration: 140.310547ms)","trace[13802647] 'compare' (duration: 11.048695ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:40.077148Z","caller":"traceutil/trace.go:171","msg":"trace[1288368713] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"415.065148ms","start":"2026-05-19T00:53:39.662059Z","end":"2026-05-19T00:53:40.077124Z","steps":["trace[1288368713] 'process raft request' (duration: 414.978136ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:40.077226Z","caller":"traceutil/trace.go:171","msg":"trace[1648973020] linearizableReadLoop","detail":"{readStateIndex:25503; appliedIndex:25499; }","duration":"211.70669ms","start":"2026-05-19T00:53:39.865491Z","end":"2026-05-19T00:53:40.077198Z","steps":["trace[1648973020] 'read index received' (duration: 185.559423ms)","trace[1648973020] 'applied index is now lower than readState.Index' (duration: 26.145697ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:40.077293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:39.662053Z","time spent":"415.16745ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:40.077515Z","caller":"traceutil/trace.go:171","msg":"trace[1944623389] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"418.271355ms","start":"2026-05-19T00:53:39.659228Z","end":"2026-05-19T00:53:40.077499Z","steps":["trace[1944623389] 'process raft request' (duration: 391.79397ms)","trace[1944623389] 'compare' (duration: 25.392709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:40.077575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:39.659219Z","time spent":"418.322206ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:40.077875Z","caller":"traceutil/trace.go:171","msg":"trace[1543742985] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"416.926934ms","start":"2026-05-19T00:53:39.660937Z","end":"2026-05-19T00:53:40.077864Z","steps":["trace[1543742985] 'process raft request' (duration: 415.996862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:40.077933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:39.660923Z","time spent":"416.978374ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:40.07856Z","caller":"traceutil/trace.go:171","msg":"trace[437408120] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"293.389579ms","start":"2026-05-19T00:53:39.785145Z","end":"2026-05-19T00:53:40.078535Z","steps":["trace[437408120] 'process raft request' (duration: 291.937284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:40.078681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.182406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:40.078747Z","caller":"traceutil/trace.go:171","msg":"trace[1483455473] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24697; }","duration":"213.288018ms","start":"2026-05-19T00:53:39.865442Z","end":"2026-05-19T00:53:40.07873Z","steps":["trace[1483455473] 'agreement among raft nodes before linearized reading' (duration: 213.198506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:42.527773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.519921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:42.528178Z","caller":"traceutil/trace.go:171","msg":"trace[1070821138] linearizableReadLoop","detail":"{readStateIndex:25521; appliedIndex:25519; }","duration":"350.56092ms","start":"2026-05-19T00:53:42.1776Z","end":"2026-05-19T00:53:42.528161Z","steps":["trace[1070821138] 'read index received' (duration: 134.575309ms)","trace[1070821138] 'applied index is now lower than readState.Index' (duration: 215.984621ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:42.528425Z","caller":"traceutil/trace.go:171","msg":"trace[1264201228] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"406.137134ms","start":"2026-05-19T00:53:42.122245Z","end":"2026-05-19T00:53:42.528399Z","steps":["trace[1264201228] 'process raft request' (duration: 189.902686ms)","trace[1264201228] 'compare' (duration: 213.969653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:42.528497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:42.122216Z","time spent":"406.247807ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:42.528901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.300978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-19T00:53:42.528941Z","caller":"traceutil/trace.go:171","msg":"trace[1009664775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24715; }","duration":"351.37759ms","start":"2026-05-19T00:53:42.177552Z","end":"2026-05-19T00:53:42.52893Z","steps":["trace[1009664775] 'agreement among raft nodes before linearized reading' (duration: 351.228686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:42.528973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:42.177534Z","time spent":"351.428771ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T00:53:42.529056Z","caller":"traceutil/trace.go:171","msg":"trace[1908575000] transaction","detail":"{read_only:false; response_revision:24715; number_of_response:1; }","duration":"405.192271ms","start":"2026-05-19T00:53:42.123835Z","end":"2026-05-19T00:53:42.529028Z","steps":["trace[1908575000] 'process raft request' (duration: 404.044164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:42.529225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:42.123825Z","time spent":"405.308373ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:42.82953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.096444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:42.829618Z","caller":"traceutil/trace.go:171","msg":"trace[1356028395] linearizableReadLoop","detail":"{readStateIndex:25522; appliedIndex:25521; }","duration":"301.384761ms","start":"2026-05-19T00:53:42.528219Z","end":"2026-05-19T00:53:42.829604Z","steps":["trace[1356028395] 'read index received' (duration: 114.054596ms)","trace[1356028395] 'applied index is now lower than readState.Index' (duration: 187.328895ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:42.829786Z","caller":"traceutil/trace.go:171","msg":"trace[598412422] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"353.171653ms","start":"2026-05-19T00:53:42.476605Z","end":"2026-05-19T00:53:42.829777Z","steps":["trace[598412422] 'process raft request' (duration: 161.74599ms)","trace[598412422] 'compare' (duration: 190.51499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:42.829842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:42.476584Z","time spent":"353.232305ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:42.830027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.918017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:42.83006Z","caller":"traceutil/trace.go:171","msg":"trace[143509822] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:24716; }","duration":"495.993659ms","start":"2026-05-19T00:53:42.334057Z","end":"2026-05-19T00:53:42.830051Z","steps":["trace[143509822] 'agreement among raft nodes before linearized reading' (duration: 495.937708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:42.830086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:42.334035Z","time spent":"496.044761ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T00:53:42.830091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.092098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-19T00:53:42.830124Z","caller":"traceutil/trace.go:171","msg":"trace[592936701] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:24716; }","duration":"520.16568ms","start":"2026-05-19T00:53:42.30995Z","end":"2026-05-19T00:53:42.830116Z","steps":["trace[592936701] 'agreement among raft nodes before linearized reading' (duration: 520.007346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:42.83015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:42.309923Z","time spent":"520.222441ms","remote":"127.0.0.1:42196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/162.253.55.186\" "} {"level":"warn","ts":"2026-05-19T00:53:43.112616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.17641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:43.113574Z","caller":"traceutil/trace.go:171","msg":"trace[164771684] transaction","detail":"{read_only:false; response_revision:24718; number_of_response:1; }","duration":"263.446631ms","start":"2026-05-19T00:53:42.850105Z","end":"2026-05-19T00:53:43.113551Z","steps":["trace[164771684] 'process raft request' (duration: 263.350839ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:43.113719Z","caller":"traceutil/trace.go:171","msg":"trace[95771840] transaction","detail":"{read_only:false; response_revision:24717; number_of_response:1; }","duration":"580.890057ms","start":"2026-05-19T00:53:42.532816Z","end":"2026-05-19T00:53:43.113706Z","steps":["trace[95771840] 'process raft request' (duration: 405.522039ms)","trace[95771840] 'compare' (duration: 174.044796ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:43.113817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:42.532757Z","time spent":"580.992519ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:43.113941Z","caller":"traceutil/trace.go:171","msg":"trace[2006578975] linearizableReadLoop","detail":"{readStateIndex:25523; appliedIndex:25522; }","duration":"284.25124ms","start":"2026-05-19T00:53:42.829638Z","end":"2026-05-19T00:53:43.113889Z","steps":["trace[2006578975] 'read index received' (duration: 108.78762ms)","trace[2006578975] 'applied index is now lower than readState.Index' (duration: 175.45617ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:43.114039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.504498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:43.114091Z","caller":"traceutil/trace.go:171","msg":"trace[389477766] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24718; }","duration":"427.630591ms","start":"2026-05-19T00:53:42.686446Z","end":"2026-05-19T00:53:43.114077Z","steps":["trace[389477766] 'agreement among raft nodes before linearized reading' (duration: 427.473427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:43.114088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.562159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:43.114194Z","caller":"traceutil/trace.go:171","msg":"trace[1556396978] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24718; }","duration":"402.719153ms","start":"2026-05-19T00:53:42.711452Z","end":"2026-05-19T00:53:43.114171Z","steps":["trace[1556396978] 'agreement among raft nodes before linearized reading' (duration: 402.435706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:43.114138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:42.686427Z","time spent":"427.703782ms","remote":"127.0.0.1:51446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-19T00:53:43.114181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.911079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:43.114682Z","caller":"traceutil/trace.go:171","msg":"trace[358599261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24718; }","duration":"526.520842ms","start":"2026-05-19T00:53:42.588133Z","end":"2026-05-19T00:53:43.114654Z","steps":["trace[358599261] 'agreement among raft nodes before linearized reading' (duration: 525.881647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:43.11473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:42.588116Z","time spent":"526.604055ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T00:53:43.114281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:42.71143Z","time spent":"402.809915ms","remote":"127.0.0.1:51468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T00:53:43.114377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.746249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-19T00:53:43.11523Z","caller":"traceutil/trace.go:171","msg":"trace[2097898146] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24718; }","duration":"244.623111ms","start":"2026-05-19T00:53:42.870591Z","end":"2026-05-19T00:53:43.115214Z","steps":["trace[2097898146] 'agreement among raft nodes before linearized reading' (duration: 243.517293ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:43.738519Z","caller":"traceutil/trace.go:171","msg":"trace[486214467] transaction","detail":"{read_only:false; response_revision:24720; number_of_response:1; }","duration":"139.59569ms","start":"2026-05-19T00:53:43.5989Z","end":"2026-05-19T00:53:43.738496Z","steps":["trace[486214467] 'process raft request' (duration: 139.461087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.044442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.876312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:44.04466Z","caller":"traceutil/trace.go:171","msg":"trace[1867900369] linearizableReadLoop","detail":"{readStateIndex:25529; appliedIndex:25527; }","duration":"234.078345ms","start":"2026-05-19T00:53:43.810566Z","end":"2026-05-19T00:53:44.044644Z","steps":["trace[1867900369] 'read index received' (duration: 59.864816ms)","trace[1867900369] 'applied index is now lower than readState.Index' (duration: 174.212599ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:44.044728Z","caller":"traceutil/trace.go:171","msg":"trace[344999413] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"377.521337ms","start":"2026-05-19T00:53:43.667175Z","end":"2026-05-19T00:53:44.044697Z","steps":["trace[344999413] 'process raft request' (duration: 203.309677ms)","trace[344999413] 'compare' (duration: 173.743659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:44.044892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.319621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T00:53:44.044925Z","caller":"traceutil/trace.go:171","msg":"trace[201414818] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24722; }","duration":"234.375743ms","start":"2026-05-19T00:53:43.810539Z","end":"2026-05-19T00:53:44.044914Z","steps":["trace[201414818] 'agreement among raft nodes before linearized reading' (duration: 234.213779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.044919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:43.66715Z","time spent":"377.683381ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:44.045022Z","caller":"traceutil/trace.go:171","msg":"trace[2065820425] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"376.503572ms","start":"2026-05-19T00:53:43.668479Z","end":"2026-05-19T00:53:44.044983Z","steps":["trace[2065820425] 'process raft request' (duration: 376.059152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.045134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.323454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-19T00:53:44.045217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:43.668468Z","time spent":"376.642695ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:44.045233Z","caller":"traceutil/trace.go:171","msg":"trace[325583852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24722; }","duration":"222.466167ms","start":"2026-05-19T00:53:43.822743Z","end":"2026-05-19T00:53:44.045209Z","steps":["trace[325583852] 'agreement among raft nodes before linearized reading' (duration: 222.17877ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:44.296249Z","caller":"traceutil/trace.go:171","msg":"trace[421630045] transaction","detail":"{read_only:false; response_revision:24723; number_of_response:1; }","duration":"245.581372ms","start":"2026-05-19T00:53:44.050642Z","end":"2026-05-19T00:53:44.296224Z","steps":["trace[421630045] 'process raft request' (duration: 245.413798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.710321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.164685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:44.710534Z","caller":"traceutil/trace.go:171","msg":"trace[1115647772] linearizableReadLoop","detail":"{readStateIndex:25532; appliedIndex:25529; }","duration":"614.842481ms","start":"2026-05-19T00:53:44.095675Z","end":"2026-05-19T00:53:44.710518Z","steps":["trace[1115647772] 'read index received' (duration: 200.413198ms)","trace[1115647772] 'applied index is now lower than readState.Index' (duration: 414.428413ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:44.71073Z","caller":"traceutil/trace.go:171","msg":"trace[78074778] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"619.912612ms","start":"2026-05-19T00:53:44.090788Z","end":"2026-05-19T00:53:44.710701Z","steps":["trace[78074778] 'process raft request' (duration: 456.220415ms)","trace[78074778] 'compare' (duration: 162.636422ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:44.710774Z","caller":"traceutil/trace.go:171","msg":"trace[1313491802] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"617.789291ms","start":"2026-05-19T00:53:44.092933Z","end":"2026-05-19T00:53:44.710722Z","steps":["trace[1313491802] 'process raft request' (duration: 617.487694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.710869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.090767Z","time spent":"620.024365ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:44.71095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.092916Z","time spent":"617.944765ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:44.710954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.265431ms","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-05-19T00:53:44.711029Z","caller":"traceutil/trace.go:171","msg":"trace[300993313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24725; }","duration":"615.373445ms","start":"2026-05-19T00:53:44.09564Z","end":"2026-05-19T00:53:44.711014Z","steps":["trace[300993313] 'agreement among raft nodes before linearized reading' (duration: 615.150389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.711078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.095623Z","time spent":"615.446796ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T00:53:44.712763Z","caller":"traceutil/trace.go:171","msg":"trace[1723321959] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"178.213366ms","start":"2026-05-19T00:53:44.534523Z","end":"2026-05-19T00:53:44.712737Z","steps":["trace[1723321959] 'process raft request' (duration: 177.989111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.712831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.986892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-19T00:53:44.71286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.908889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:44.712911Z","caller":"traceutil/trace.go:171","msg":"trace[1058249046] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24727; }","duration":"617.102915ms","start":"2026-05-19T00:53:44.095789Z","end":"2026-05-19T00:53:44.712892Z","steps":["trace[1058249046] 'agreement among raft nodes before linearized reading' (duration: 616.9009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.712957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.095773Z","time spent":"617.175356ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T00:53:44.712966Z","caller":"traceutil/trace.go:171","msg":"trace[316624022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24727; }","duration":"123.084163ms","start":"2026-05-19T00:53:44.58985Z","end":"2026-05-19T00:53:44.712935Z","steps":["trace[316624022] 'agreement among raft nodes before linearized reading' (duration: 122.844907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.713116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.319953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:44.713171Z","caller":"traceutil/trace.go:171","msg":"trace[1843278177] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24727; }","duration":"595.374774ms","start":"2026-05-19T00:53:44.117782Z","end":"2026-05-19T00:53:44.713157Z","steps":["trace[1843278177] 'agreement among raft nodes before linearized reading' (duration: 595.287302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.713209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.117733Z","time spent":"595.468646ms","remote":"127.0.0.1:42584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-19T00:53:44.713252Z","caller":"traceutil/trace.go:171","msg":"trace[530159857] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"172.762555ms","start":"2026-05-19T00:53:44.54046Z","end":"2026-05-19T00:53:44.713223Z","steps":["trace[530159857] 'process raft request' (duration: 172.171131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.71324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.050744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:44.713324Z","caller":"traceutil/trace.go:171","msg":"trace[2047643071] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24727; }","duration":"602.129497ms","start":"2026-05-19T00:53:44.111171Z","end":"2026-05-19T00:53:44.713301Z","steps":["trace[2047643071] 'agreement among raft nodes before linearized reading' (duration: 602.012024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.713377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.111144Z","time spent":"602.217548ms","remote":"127.0.0.1:52872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-19T00:53:44.713515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.174161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:44.713562Z","caller":"traceutil/trace.go:171","msg":"trace[1217988453] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:24727; }","duration":"365.323734ms","start":"2026-05-19T00:53:44.348225Z","end":"2026-05-19T00:53:44.713549Z","steps":["trace[1217988453] 'agreement among raft nodes before linearized reading' (duration: 365.15265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:44.7136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.348205Z","time spent":"365.387085ms","remote":"127.0.0.1:52954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-19T00:53:45.024913Z","caller":"traceutil/trace.go:171","msg":"trace[639212143] linearizableReadLoop","detail":"{readStateIndex:25535; appliedIndex:25534; }","duration":"307.444356ms","start":"2026-05-19T00:53:44.717413Z","end":"2026-05-19T00:53:45.024857Z","steps":["trace[639212143] 'read index received' (duration: 307.273672ms)","trace[639212143] 'applied index is now lower than readState.Index' (duration: 169.774µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:45.025057Z","caller":"traceutil/trace.go:171","msg":"trace[1450448354] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"309.340641ms","start":"2026-05-19T00:53:44.715677Z","end":"2026-05-19T00:53:45.025017Z","steps":["trace[1450448354] 'process raft request' (duration: 309.043714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:45.025121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.689273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:45.025155Z","caller":"traceutil/trace.go:171","msg":"trace[1305778830] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24728; }","duration":"307.773544ms","start":"2026-05-19T00:53:44.717371Z","end":"2026-05-19T00:53:45.025144Z","steps":["trace[1305778830] 'agreement among raft nodes before linearized reading' (duration: 307.633221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:45.025234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.715662Z","time spent":"309.475015ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:45.025238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.71735Z","time spent":"307.877647ms","remote":"127.0.0.1:42318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-19T00:53:45.057658Z","caller":"traceutil/trace.go:171","msg":"trace[1517672600] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"326.74472ms","start":"2026-05-19T00:53:44.730891Z","end":"2026-05-19T00:53:45.057635Z","steps":["trace[1517672600] 'process raft request' (duration: 326.659968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:45.057818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.730877Z","time spent":"326.861642ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:45.057669Z","caller":"traceutil/trace.go:171","msg":"trace[570602655] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"338.713036ms","start":"2026-05-19T00:53:44.718932Z","end":"2026-05-19T00:53:45.057645Z","steps":["trace[570602655] 'process raft request' (duration: 338.46678ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:45.057827Z","caller":"traceutil/trace.go:171","msg":"trace[471144130] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"223.313458ms","start":"2026-05-19T00:53:44.834493Z","end":"2026-05-19T00:53:45.057807Z","steps":["trace[471144130] 'process raft request' (duration: 223.104203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:45.057966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:44.718909Z","time spent":"338.977663ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:45.057983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.046743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:45.058194Z","caller":"traceutil/trace.go:171","msg":"trace[361389362] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24731; }","duration":"296.302379ms","start":"2026-05-19T00:53:44.761841Z","end":"2026-05-19T00:53:45.058144Z","steps":["trace[361389362] 'agreement among raft nodes before linearized reading' (duration: 296.051513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:45.398903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.871662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:45.398975Z","caller":"traceutil/trace.go:171","msg":"trace[34407065] linearizableReadLoop","detail":"{readStateIndex:25539; appliedIndex:25538; }","duration":"278.876201ms","start":"2026-05-19T00:53:45.120087Z","end":"2026-05-19T00:53:45.398963Z","steps":["trace[34407065] 'read index received' (duration: 114.873916ms)","trace[34407065] 'applied index is now lower than readState.Index' (duration: 164.001545ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:45.399024Z","caller":"traceutil/trace.go:171","msg":"trace[1623519058] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"279.551967ms","start":"2026-05-19T00:53:45.119436Z","end":"2026-05-19T00:53:45.398988Z","steps":["trace[1623519058] 'process raft request' (duration: 115.537272ms)","trace[1623519058] 'compare' (duration: 162.107509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:45.399078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.990573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-19T00:53:45.3991Z","caller":"traceutil/trace.go:171","msg":"trace[403009435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24732; }","duration":"279.049045ms","start":"2026-05-19T00:53:45.120043Z","end":"2026-05-19T00:53:45.399092Z","steps":["trace[403009435] 'agreement among raft nodes before linearized reading' (duration: 278.950692ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:45.444295Z","caller":"traceutil/trace.go:171","msg":"trace[1310332635] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"137.294984ms","start":"2026-05-19T00:53:45.306965Z","end":"2026-05-19T00:53:45.44426Z","steps":["trace[1310332635] 'process raft request' (duration: 137.11444ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:45.720489Z","caller":"traceutil/trace.go:171","msg":"trace[14451704] linearizableReadLoop","detail":"{readStateIndex:25541; appliedIndex:25540; }","duration":"164.786962ms","start":"2026-05-19T00:53:45.555673Z","end":"2026-05-19T00:53:45.72046Z","steps":["trace[14451704] 'read index received' (duration: 164.537376ms)","trace[14451704] 'applied index is now lower than readState.Index' (duration: 248.676µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:45.720594Z","caller":"traceutil/trace.go:171","msg":"trace[1813588231] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"317.620951ms","start":"2026-05-19T00:53:45.402955Z","end":"2026-05-19T00:53:45.720575Z","steps":["trace[1813588231] 'process raft request' (duration: 317.351494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:45.720673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.976808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T00:53:45.720703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:45.402928Z","time spent":"317.707993ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:45.720712Z","caller":"traceutil/trace.go:171","msg":"trace[1538520787] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:24734; }","duration":"165.06841ms","start":"2026-05-19T00:53:45.555631Z","end":"2026-05-19T00:53:45.7207Z","steps":["trace[1538520787] 'agreement among raft nodes before linearized reading' (duration: 164.958737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:45.720771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.897168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:45.720809Z","caller":"traceutil/trace.go:171","msg":"trace[2006284689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24734; }","duration":"129.968539ms","start":"2026-05-19T00:53:45.590832Z","end":"2026-05-19T00:53:45.720801Z","steps":["trace[2006284689] 'agreement among raft nodes before linearized reading' (duration: 129.915428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:46.061081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.047585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-05-19T00:53:46.061142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.930453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-554f9f5455-frcfc.18b0d1505f22b0cc\" ","response":"range_response_count:1 size:834"} {"level":"info","ts":"2026-05-19T00:53:46.061174Z","caller":"traceutil/trace.go:171","msg":"trace[1028744717] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24735; }","duration":"244.182018ms","start":"2026-05-19T00:53:45.816965Z","end":"2026-05-19T00:53:46.061147Z","steps":["trace[1028744717] 'range keys from in-memory index tree' (duration: 243.8613ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:46.061214Z","caller":"traceutil/trace.go:171","msg":"trace[725952863] range","detail":"{range_begin:/registry/events/openstack/magnum-api-554f9f5455-frcfc.18b0d1505f22b0cc; range_end:; response_count:1; response_revision:24735; }","duration":"124.039785ms","start":"2026-05-19T00:53:45.937156Z","end":"2026-05-19T00:53:46.061195Z","steps":["trace[725952863] 'range keys from in-memory index tree' (duration: 123.791399ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:46.418932Z","caller":"traceutil/trace.go:171","msg":"trace[1234661035] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"355.990621ms","start":"2026-05-19T00:53:46.062929Z","end":"2026-05-19T00:53:46.418919Z","steps":["trace[1234661035] 'process raft request' (duration: 355.916289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:46.419023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:46.062907Z","time spent":"356.063372ms","remote":"127.0.0.1:42232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:46.419024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.980466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-157803f6-bd4b-4280-9e17-5bee0845ae70\" ","response":"range_response_count:1 size:1294"} {"level":"info","ts":"2026-05-19T00:53:46.419054Z","caller":"traceutil/trace.go:171","msg":"trace[2032053750] range","detail":"{range_begin:/registry/persistentvolumes/pvc-157803f6-bd4b-4280-9e17-5bee0845ae70; range_end:; response_count:1; response_revision:24739; }","duration":"355.035288ms","start":"2026-05-19T00:53:46.064011Z","end":"2026-05-19T00:53:46.419046Z","steps":["trace[2032053750] 'agreement among raft nodes before linearized reading' (duration: 354.945566ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:46.419041Z","caller":"traceutil/trace.go:171","msg":"trace[1072129909] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"367.901916ms","start":"2026-05-19T00:53:46.051087Z","end":"2026-05-19T00:53:46.418989Z","steps":["trace[1072129909] 'process raft request' (duration: 310.857706ms)","trace[1072129909] 'compare' (duration: 56.63117ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:46.419075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:46.063996Z","time spent":"355.074739ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1316,"request content":"key:\"/registry/persistentvolumes/pvc-157803f6-bd4b-4280-9e17-5bee0845ae70\" "} {"level":"info","ts":"2026-05-19T00:53:46.418928Z","caller":"traceutil/trace.go:171","msg":"trace[278681497] linearizableReadLoop","detail":"{readStateIndex:25546; appliedIndex:25544; }","duration":"354.861894ms","start":"2026-05-19T00:53:46.064051Z","end":"2026-05-19T00:53:46.418913Z","steps":["trace[278681497] 'read index received' (duration: 76.792423ms)","trace[278681497] 'applied index is now lower than readState.Index' (duration: 278.068671ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:46.419189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:46.051061Z","time spent":"368.05091ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:46.419296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.374804ms","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-05-19T00:53:46.419349Z","caller":"traceutil/trace.go:171","msg":"trace[168606057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24739; }","duration":"119.543148ms","start":"2026-05-19T00:53:46.299792Z","end":"2026-05-19T00:53:46.419335Z","steps":["trace[168606057] 'agreement among raft nodes before linearized reading' (duration: 119.372164ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:46.827467Z","caller":"traceutil/trace.go:171","msg":"trace[1632545637] transaction","detail":"{read_only:false; response_revision:24742; number_of_response:1; }","duration":"111.444263ms","start":"2026-05-19T00:53:46.715998Z","end":"2026-05-19T00:53:46.827443Z","steps":["trace[1632545637] 'process raft request' (duration: 111.194367ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:47.099698Z","caller":"traceutil/trace.go:171","msg":"trace[1084234909] transaction","detail":"{read_only:false; response_revision:24745; number_of_response:1; }","duration":"379.830003ms","start":"2026-05-19T00:53:46.719843Z","end":"2026-05-19T00:53:47.099673Z","steps":["trace[1084234909] 'process raft request' (duration: 379.789392ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:47.099755Z","caller":"traceutil/trace.go:171","msg":"trace[1186312860] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"382.253821ms","start":"2026-05-19T00:53:46.717465Z","end":"2026-05-19T00:53:47.099719Z","steps":["trace[1186312860] 'process raft request' (duration: 381.968615ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:47.099773Z","caller":"traceutil/trace.go:171","msg":"trace[1061398829] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"381.600905ms","start":"2026-05-19T00:53:46.718146Z","end":"2026-05-19T00:53:47.099747Z","steps":["trace[1061398829] 'process raft request' (duration: 381.445111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:47.099846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:46.719827Z","time spent":"379.940936ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:47.099928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:46.718131Z","time spent":"381.708197ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:47.099933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:46.717405Z","time spent":"382.426745ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:55.520226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.446217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:55.5205Z","caller":"traceutil/trace.go:171","msg":"trace[1135669375] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"155.054221ms","start":"2026-05-19T00:53:55.365424Z","end":"2026-05-19T00:53:55.520478Z","steps":["trace[1135669375] 'process raft request' (duration: 40.273987ms)","trace[1135669375] 'compare' (duration: 114.347603ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:55.948175Z","caller":"traceutil/trace.go:171","msg":"trace[1071422216] linearizableReadLoop","detail":"{readStateIndex:25681; appliedIndex:25680; }","duration":"100.736926ms","start":"2026-05-19T00:53:55.847418Z","end":"2026-05-19T00:53:55.948155Z","steps":["trace[1071422216] 'read index received' (duration: 100.560062ms)","trace[1071422216] 'applied index is now lower than readState.Index' (duration: 175.724µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:55.948278Z","caller":"traceutil/trace.go:171","msg":"trace[1427044006] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"138.577354ms","start":"2026-05-19T00:53:55.809662Z","end":"2026-05-19T00:53:55.948239Z","steps":["trace[1427044006] 'process raft request' (duration: 138.312878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:55.948408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.976223ms","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-05-19T00:53:55.948469Z","caller":"traceutil/trace.go:171","msg":"trace[1521240943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24872; }","duration":"101.078635ms","start":"2026-05-19T00:53:55.847378Z","end":"2026-05-19T00:53:55.948456Z","steps":["trace[1521240943] 'agreement among raft nodes before linearized reading' (duration: 100.926182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:56.225876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.819274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:56.226012Z","caller":"traceutil/trace.go:171","msg":"trace[1306621163] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"273.710747ms","start":"2026-05-19T00:53:55.952274Z","end":"2026-05-19T00:53:56.225985Z","steps":["trace[1306621163] 'process raft request' (duration: 63.674688ms)","trace[1306621163] 'compare' (duration: 209.522996ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:56.266825Z","caller":"traceutil/trace.go:171","msg":"trace[358818471] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"312.156148ms","start":"2026-05-19T00:53:55.954637Z","end":"2026-05-19T00:53:56.266793Z","steps":["trace[358818471] 'process raft request' (duration: 311.965274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:56.26701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:55.95461Z","time spent":"312.301362ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:53:56.525756Z","caller":"traceutil/trace.go:171","msg":"trace[1710833759] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"451.889602ms","start":"2026-05-19T00:53:56.073835Z","end":"2026-05-19T00:53:56.525725Z","steps":["trace[1710833759] 'process raft request' (duration: 451.522862ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:56.525821Z","caller":"traceutil/trace.go:171","msg":"trace[1422741335] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"358.345937ms","start":"2026-05-19T00:53:56.167456Z","end":"2026-05-19T00:53:56.525802Z","steps":["trace[1422741335] 'process raft request' (duration: 358.277135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:56.525983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:56.073807Z","time spent":"452.090477ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:56.526158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:56.167437Z","time spent":"358.589313ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:56.780335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.412437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:53:56.780405Z","caller":"traceutil/trace.go:171","msg":"trace[57923107] linearizableReadLoop","detail":"{readStateIndex:25686; appliedIndex:25683; }","duration":"315.677434ms","start":"2026-05-19T00:53:56.464716Z","end":"2026-05-19T00:53:56.780394Z","steps":["trace[57923107] 'read index received' (duration: 60.660806ms)","trace[57923107] 'applied index is now lower than readState.Index' (duration: 255.015088ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:56.780463Z","caller":"traceutil/trace.go:171","msg":"trace[373240998] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"319.187988ms","start":"2026-05-19T00:53:56.461269Z","end":"2026-05-19T00:53:56.780457Z","steps":["trace[373240998] 'process raft request' (duration: 191.586327ms)","trace[373240998] 'compare' (duration: 127.260832ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:56.780504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:56.461245Z","time spent":"319.240479ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:53:56.780688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.972171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-19T00:53:56.780708Z","caller":"traceutil/trace.go:171","msg":"trace[1487948873] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24877; }","duration":"316.015812ms","start":"2026-05-19T00:53:56.464686Z","end":"2026-05-19T00:53:56.780702Z","steps":["trace[1487948873] 'agreement among raft nodes before linearized reading' (duration: 315.92877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:56.780723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:53:56.464677Z","time spent":"316.042103ms","remote":"127.0.0.1:42930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3331,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-19T00:53:56.780946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.223153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-72f1b910-3246-4ba7-bb92-bfdc85a4dd23\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-19T00:53:56.780967Z","caller":"traceutil/trace.go:171","msg":"trace[64598038] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-72f1b910-3246-4ba7-bb92-bfdc85a4dd23; range_end:; response_count:1; response_revision:24877; }","duration":"255.276474ms","start":"2026-05-19T00:53:56.525684Z","end":"2026-05-19T00:53:56.780961Z","steps":["trace[64598038] 'agreement among raft nodes before linearized reading' (duration: 255.222253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:56.781121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.613082ms","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-05-19T00:53:56.781214Z","caller":"traceutil/trace.go:171","msg":"trace[546260603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24877; }","duration":"295.827088ms","start":"2026-05-19T00:53:56.485368Z","end":"2026-05-19T00:53:56.781195Z","steps":["trace[546260603] 'agreement among raft nodes before linearized reading' (duration: 295.513221ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:56.930062Z","caller":"traceutil/trace.go:171","msg":"trace[1855912108] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"144.868245ms","start":"2026-05-19T00:53:56.785161Z","end":"2026-05-19T00:53:56.930029Z","steps":["trace[1855912108] 'process raft request' (duration: 132.795596ms)","trace[1855912108] 'compare' (duration: 11.922876ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:56.930173Z","caller":"traceutil/trace.go:171","msg":"trace[1424257148] linearizableReadLoop","detail":"{readStateIndex:25687; appliedIndex:25686; }","duration":"142.923489ms","start":"2026-05-19T00:53:56.787215Z","end":"2026-05-19T00:53:56.930139Z","steps":["trace[1424257148] 'read index received' (duration: 130.651595ms)","trace[1424257148] 'applied index is now lower than readState.Index' (duration: 12.269354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:53:56.930377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.144964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-05-19T00:53:56.930449Z","caller":"traceutil/trace.go:171","msg":"trace[306857495] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24879; }","duration":"143.245677ms","start":"2026-05-19T00:53:56.787185Z","end":"2026-05-19T00:53:56.930431Z","steps":["trace[306857495] 'agreement among raft nodes before linearized reading' (duration: 143.041332ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:57.163535Z","caller":"traceutil/trace.go:171","msg":"trace[792046431] linearizableReadLoop","detail":"{readStateIndex:25689; appliedIndex:25688; }","duration":"220.444898ms","start":"2026-05-19T00:53:56.943067Z","end":"2026-05-19T00:53:57.163512Z","steps":["trace[792046431] 'read index received' (duration: 220.352376ms)","trace[792046431] 'applied index is now lower than readState.Index' (duration: 91.452µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:57.163673Z","caller":"traceutil/trace.go:171","msg":"trace[510030818] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"223.694707ms","start":"2026-05-19T00:53:56.939967Z","end":"2026-05-19T00:53:57.163662Z","steps":["trace[510030818] 'process raft request' (duration: 223.39475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:57.163826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.743196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-05-19T00:53:57.163895Z","caller":"traceutil/trace.go:171","msg":"trace[405292647] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24880; }","duration":"220.817227ms","start":"2026-05-19T00:53:56.943062Z","end":"2026-05-19T00:53:57.163879Z","steps":["trace[405292647] 'agreement among raft nodes before linearized reading' (duration: 220.634853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:57.165649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.053572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:53:57.165709Z","caller":"traceutil/trace.go:171","msg":"trace[1905609643] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:24884; }","duration":"173.166694ms","start":"2026-05-19T00:53:56.992527Z","end":"2026-05-19T00:53:57.165694Z","steps":["trace[1905609643] 'agreement among raft nodes before linearized reading' (duration: 173.047531ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:59.420451Z","caller":"traceutil/trace.go:171","msg":"trace[841273224] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"247.942849ms","start":"2026-05-19T00:53:59.172484Z","end":"2026-05-19T00:53:59.420427Z","steps":["trace[841273224] 'process raft request' (duration: 247.750784ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:59.420632Z","caller":"traceutil/trace.go:171","msg":"trace[1112851278] linearizableReadLoop","detail":"{readStateIndex:25712; appliedIndex:25708; }","duration":"241.544065ms","start":"2026-05-19T00:53:59.179069Z","end":"2026-05-19T00:53:59.420613Z","steps":["trace[1112851278] 'read index received' (duration: 90.423079ms)","trace[1112851278] 'applied index is now lower than readState.Index' (duration: 151.120326ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:59.420684Z","caller":"traceutil/trace.go:171","msg":"trace[87720182] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"246.75572ms","start":"2026-05-19T00:53:59.173923Z","end":"2026-05-19T00:53:59.420679Z","steps":["trace[87720182] 'process raft request' (duration: 246.600836ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:59.420634Z","caller":"traceutil/trace.go:171","msg":"trace[281505086] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"247.013206ms","start":"2026-05-19T00:53:59.173598Z","end":"2026-05-19T00:53:59.420611Z","steps":["trace[281505086] 'process raft request' (duration: 246.78366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:59.420811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.668597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-19T00:53:59.42081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.727149ms","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-05-19T00:53:59.420844Z","caller":"traceutil/trace.go:171","msg":"trace[1673924140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24902; }","duration":"241.731019ms","start":"2026-05-19T00:53:59.179102Z","end":"2026-05-19T00:53:59.420833Z","steps":["trace[1673924140] 'agreement among raft nodes before linearized reading' (duration: 241.637967ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:59.420847Z","caller":"traceutil/trace.go:171","msg":"trace[1828479437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24902; }","duration":"241.79828ms","start":"2026-05-19T00:53:59.17904Z","end":"2026-05-19T00:53:59.420839Z","steps":["trace[1828479437] 'agreement among raft nodes before linearized reading' (duration: 241.682288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:59.420994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.627568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-19T00:53:59.421041Z","caller":"traceutil/trace.go:171","msg":"trace[77632247] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24902; }","duration":"106.71396ms","start":"2026-05-19T00:53:59.314313Z","end":"2026-05-19T00:53:59.421027Z","steps":["trace[77632247] 'agreement among raft nodes before linearized reading' (duration: 106.564226ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:04.129668Z","caller":"traceutil/trace.go:171","msg":"trace[735381588] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"125.918291ms","start":"2026-05-19T00:54:04.003731Z","end":"2026-05-19T00:54:04.129649Z","steps":["trace[735381588] 'process raft request' (duration: 125.531281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:04.412337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.875814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T00:54:04.412434Z","caller":"traceutil/trace.go:171","msg":"trace[1847852601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24966; }","duration":"162.009658ms","start":"2026-05-19T00:54:04.250402Z","end":"2026-05-19T00:54:04.412411Z","steps":["trace[1847852601] 'range keys from in-memory index tree' (duration: 161.668179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:09.460885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.488746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:54:09.460993Z","caller":"traceutil/trace.go:171","msg":"trace[200386602] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25011; }","duration":"189.65418ms","start":"2026-05-19T00:54:09.271319Z","end":"2026-05-19T00:54:09.460973Z","steps":["trace[200386602] 'count revisions from in-memory index tree' (duration: 189.423025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:09.461011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.141702ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:54:09.461126Z","caller":"traceutil/trace.go:171","msg":"trace[205226358] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"147.17692ms","start":"2026-05-19T00:54:09.31393Z","end":"2026-05-19T00:54:09.461107Z","steps":["trace[205226358] 'compare' (duration: 139.860866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:09.739016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.545724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:54:09.739132Z","caller":"traceutil/trace.go:171","msg":"trace[1941691571] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"279.072405ms","start":"2026-05-19T00:54:09.460039Z","end":"2026-05-19T00:54:09.739111Z","steps":["trace[1941691571] 'process raft request' (duration: 135.335286ms)","trace[1941691571] 'compare' (duration: 143.267998ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:09.829399Z","caller":"traceutil/trace.go:171","msg":"trace[1507162813] linearizableReadLoop","detail":"{readStateIndex:25827; appliedIndex:25824; }","duration":"321.758559ms","start":"2026-05-19T00:54:09.507622Z","end":"2026-05-19T00:54:09.829381Z","steps":["trace[1507162813] 'read index received' (duration: 87.764745ms)","trace[1507162813] 'applied index is now lower than readState.Index' (duration: 233.992954ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:09.829424Z","caller":"traceutil/trace.go:171","msg":"trace[484201963] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"367.618911ms","start":"2026-05-19T00:54:09.46178Z","end":"2026-05-19T00:54:09.829399Z","steps":["trace[484201963] 'process raft request' (duration: 367.417626ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:09.829504Z","caller":"traceutil/trace.go:171","msg":"trace[917692336] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"256.592457ms","start":"2026-05-19T00:54:09.57289Z","end":"2026-05-19T00:54:09.829482Z","steps":["trace[917692336] 'process raft request' (duration: 256.457804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:09.829543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:09.461736Z","time spent":"367.744104ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:54:09.82953Z","caller":"traceutil/trace.go:171","msg":"trace[903530358] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"359.630349ms","start":"2026-05-19T00:54:09.469847Z","end":"2026-05-19T00:54:09.829477Z","steps":["trace[903530358] 'process raft request' (duration: 359.471185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:09.829678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.61804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:54:09.829747Z","caller":"traceutil/trace.go:171","msg":"trace[1949429679] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:25016; }","duration":"277.749744ms","start":"2026-05-19T00:54:09.551981Z","end":"2026-05-19T00:54:09.82973Z","steps":["trace[1949429679] 'agreement among raft nodes before linearized reading' (duration: 277.61385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:09.829746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:09.469827Z","time spent":"359.810993ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:54:09.829823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.285857ms","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-05-19T00:54:09.829901Z","caller":"traceutil/trace.go:171","msg":"trace[706847215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25016; }","duration":"273.40812ms","start":"2026-05-19T00:54:09.556473Z","end":"2026-05-19T00:54:09.829881Z","steps":["trace[706847215] 'agreement among raft nodes before linearized reading' (duration: 273.173395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:09.829933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.288033ms","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-05-19T00:54:09.83Z","caller":"traceutil/trace.go:171","msg":"trace[1603186420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25016; }","duration":"322.412226ms","start":"2026-05-19T00:54:09.507572Z","end":"2026-05-19T00:54:09.829984Z","steps":["trace[1603186420] 'agreement among raft nodes before linearized reading' (duration: 322.142869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:09.830032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:09.507549Z","time spent":"322.477527ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T00:54:19.993383Z","caller":"traceutil/trace.go:171","msg":"trace[1295903791] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"130.719817ms","start":"2026-05-19T00:54:19.862638Z","end":"2026-05-19T00:54:19.993358Z","steps":["trace[1295903791] 'process raft request' (duration: 130.563863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:20.313032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.50503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:54:20.313214Z","caller":"traceutil/trace.go:171","msg":"trace[178048545] linearizableReadLoop","detail":"{readStateIndex:25913; appliedIndex:25910; }","duration":"447.321362ms","start":"2026-05-19T00:54:19.865875Z","end":"2026-05-19T00:54:20.313197Z","steps":["trace[178048545] 'read index received' (duration: 127.376956ms)","trace[178048545] 'applied index is now lower than readState.Index' (duration: 319.943256ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:20.313303Z","caller":"traceutil/trace.go:171","msg":"trace[1543986600] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"449.569155ms","start":"2026-05-19T00:54:19.863721Z","end":"2026-05-19T00:54:20.31329Z","steps":["trace[1543986600] 'process raft request' (duration: 449.400891ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:20.313299Z","caller":"traceutil/trace.go:171","msg":"trace[1715707785] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"449.605016ms","start":"2026-05-19T00:54:19.863673Z","end":"2026-05-19T00:54:20.313278Z","steps":["trace[1715707785] 'process raft request' (duration: 258.792998ms)","trace[1715707785] 'compare' (duration: 190.403368ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:54:20.313364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:19.863694Z","time spent":"449.631917ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:54:20.313391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:19.863666Z","time spent":"449.673677ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:54:20.313592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.700601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-882e6dd5-9b3d-4d2b-a056-2c58707a905f\" ","response":"range_response_count:1 size:1316"} {"level":"info","ts":"2026-05-19T00:54:20.313684Z","caller":"traceutil/trace.go:171","msg":"trace[1352868818] range","detail":"{range_begin:/registry/persistentvolumes/pvc-882e6dd5-9b3d-4d2b-a056-2c58707a905f; range_end:; response_count:1; response_revision:25099; }","duration":"447.826084ms","start":"2026-05-19T00:54:19.865828Z","end":"2026-05-19T00:54:20.313655Z","steps":["trace[1352868818] 'agreement among raft nodes before linearized reading' (duration: 447.584588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:20.313745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:19.865815Z","time spent":"447.902506ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1338,"request content":"key:\"/registry/persistentvolumes/pvc-882e6dd5-9b3d-4d2b-a056-2c58707a905f\" "} {"level":"warn","ts":"2026-05-19T00:54:20.314023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.952484ms","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-05-19T00:54:20.314111Z","caller":"traceutil/trace.go:171","msg":"trace[1817304493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25099; }","duration":"417.076916ms","start":"2026-05-19T00:54:19.897008Z","end":"2026-05-19T00:54:20.314085Z","steps":["trace[1817304493] 'agreement among raft nodes before linearized reading' (duration: 416.81798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:20.314168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:19.896993Z","time spent":"417.162809ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T00:54:20.314149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.59041ms","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-05-19T00:54:20.31423Z","caller":"traceutil/trace.go:171","msg":"trace[61606841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25099; }","duration":"415.696803ms","start":"2026-05-19T00:54:19.898513Z","end":"2026-05-19T00:54:20.31421Z","steps":["trace[61606841] 'agreement among raft nodes before linearized reading' (duration: 415.406936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:20.314298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:19.898507Z","time spent":"415.781485ms","remote":"127.0.0.1:42402","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-05-19T00:54:20.582527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.219202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:54:20.582745Z","caller":"traceutil/trace.go:171","msg":"trace[620330617] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"265.13994ms","start":"2026-05-19T00:54:20.317574Z","end":"2026-05-19T00:54:20.582714Z","steps":["trace[620330617] 'process raft request' (duration: 132.648101ms)","trace[620330617] 'compare' (duration: 131.71393ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:20.582789Z","caller":"traceutil/trace.go:171","msg":"trace[2123077890] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"264.534036ms","start":"2026-05-19T00:54:20.318234Z","end":"2026-05-19T00:54:20.582768Z","steps":["trace[2123077890] 'process raft request' (duration: 264.435814ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:20.583463Z","caller":"traceutil/trace.go:171","msg":"trace[498231625] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"263.829039ms","start":"2026-05-19T00:54:20.31961Z","end":"2026-05-19T00:54:20.583439Z","steps":["trace[498231625] 'process raft request' (duration: 263.120292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:20.996732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.178286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:54:20.996938Z","caller":"traceutil/trace.go:171","msg":"trace[1988244596] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"406.214596ms","start":"2026-05-19T00:54:20.59067Z","end":"2026-05-19T00:54:20.996885Z","steps":["trace[1988244596] 'process raft request' (duration: 129.721932ms)","trace[1988244596] 'compare' (duration: 275.170082ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:20.997012Z","caller":"traceutil/trace.go:171","msg":"trace[168422107] linearizableReadLoop","detail":"{readStateIndex:25917; appliedIndex:25916; }","duration":"406.204635ms","start":"2026-05-19T00:54:20.590752Z","end":"2026-05-19T00:54:20.996957Z","steps":["trace[168422107] 'read index received' (duration: 129.65125ms)","trace[168422107] 'applied index is now lower than readState.Index' (duration: 276.550085ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:54:20.997093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:20.590652Z","time spent":"406.353869ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:54:20.997126Z","caller":"traceutil/trace.go:171","msg":"trace[1924653724] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"137.386186ms","start":"2026-05-19T00:54:20.859715Z","end":"2026-05-19T00:54:20.997101Z","steps":["trace[1924653724] 'process raft request' (duration: 137.322624ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:20.997236Z","caller":"traceutil/trace.go:171","msg":"trace[912830528] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"383.282456ms","start":"2026-05-19T00:54:20.613926Z","end":"2026-05-19T00:54:20.997209Z","steps":["trace[912830528] 'process raft request' (duration: 382.982169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:20.997338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:20.613913Z","time spent":"383.368738ms","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:54:20.997343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.593089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T00:54:20.997363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.545858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-05-19T00:54:20.997381Z","caller":"traceutil/trace.go:171","msg":"trace[799244996] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:25105; }","duration":"119.668061ms","start":"2026-05-19T00:54:20.877703Z","end":"2026-05-19T00:54:20.997371Z","steps":["trace[799244996] 'agreement among raft nodes before linearized reading' (duration: 119.60155ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:20.997415Z","caller":"traceutil/trace.go:171","msg":"trace[143184247] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25105; }","duration":"402.63442ms","start":"2026-05-19T00:54:20.594767Z","end":"2026-05-19T00:54:20.997402Z","steps":["trace[143184247] 'agreement among raft nodes before linearized reading' (duration: 402.403904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:20.997456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:20.594761Z","time spent":"402.686231ms","remote":"127.0.0.1:42228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-19T00:54:20.997536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.104409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T00:54:20.997616Z","caller":"traceutil/trace.go:171","msg":"trace[1248459475] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25105; }","duration":"325.215763ms","start":"2026-05-19T00:54:20.67238Z","end":"2026-05-19T00:54:20.997596Z","steps":["trace[1248459475] 'agreement among raft nodes before linearized reading' (duration: 324.976497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:20.997658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:20.672345Z","time spent":"325.304915ms","remote":"127.0.0.1:42302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-19T00:54:20.99775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.975704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:91 size:118967"} {"level":"info","ts":"2026-05-19T00:54:20.997821Z","caller":"traceutil/trace.go:171","msg":"trace[236350977] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:91; response_revision:25105; }","duration":"407.059445ms","start":"2026-05-19T00:54:20.590745Z","end":"2026-05-19T00:54:20.997805Z","steps":["trace[236350977] 'agreement among raft nodes before linearized reading' (duration: 406.308718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:20.997864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:54:20.590731Z","time spent":"407.122367ms","remote":"127.0.0.1:42318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":91,"response size":118989,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-05-19T00:54:21.260479Z","caller":"traceutil/trace.go:171","msg":"trace[840175930] linearizableReadLoop","detail":"{readStateIndex:25920; appliedIndex:25919; }","duration":"239.748631ms","start":"2026-05-19T00:54:21.020709Z","end":"2026-05-19T00:54:21.260458Z","steps":["trace[840175930] 'read index received' (duration: 239.021104ms)","trace[840175930] 'applied index is now lower than readState.Index' (duration: 726.767µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:21.260602Z","caller":"traceutil/trace.go:171","msg":"trace[404938188] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"247.63138ms","start":"2026-05-19T00:54:21.012961Z","end":"2026-05-19T00:54:21.260592Z","steps":["trace[404938188] 'process raft request' (duration: 246.835631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:21.260873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.154751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-19T00:54:21.260911Z","caller":"traceutil/trace.go:171","msg":"trace[608083159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25106; }","duration":"240.240513ms","start":"2026-05-19T00:54:21.020661Z","end":"2026-05-19T00:54:21.260902Z","steps":["trace[608083159] 'agreement among raft nodes before linearized reading' (duration: 240.10586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:21.261073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.155473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:54:21.261107Z","caller":"traceutil/trace.go:171","msg":"trace[1491842358] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25106; }","duration":"203.215235ms","start":"2026-05-19T00:54:21.057884Z","end":"2026-05-19T00:54:21.261099Z","steps":["trace[1491842358] 'agreement among raft nodes before linearized reading' (duration: 203.160054ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:22.425455Z","caller":"traceutil/trace.go:171","msg":"trace[615453366] transaction","detail":"{read_only:false; response_revision:25127; number_of_response:1; }","duration":"107.700364ms","start":"2026-05-19T00:54:22.317735Z","end":"2026-05-19T00:54:22.425435Z","steps":["trace[615453366] 'process raft request' (duration: 107.656262ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:22.426065Z","caller":"traceutil/trace.go:171","msg":"trace[15263531] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"108.370179ms","start":"2026-05-19T00:54:22.317682Z","end":"2026-05-19T00:54:22.426052Z","steps":["trace[15263531] 'process raft request' (duration: 107.596321ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:27.127099Z","caller":"traceutil/trace.go:171","msg":"trace[1230657798] linearizableReadLoop","detail":"{readStateIndex:25980; appliedIndex:25978; }","duration":"107.337875ms","start":"2026-05-19T00:54:27.019727Z","end":"2026-05-19T00:54:27.127064Z","steps":["trace[1230657798] 'read index received' (duration: 23.741039ms)","trace[1230657798] 'applied index is now lower than readState.Index' (duration: 83.595856ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:27.127155Z","caller":"traceutil/trace.go:171","msg":"trace[551203972] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"111.144876ms","start":"2026-05-19T00:54:27.01598Z","end":"2026-05-19T00:54:27.127125Z","steps":["trace[551203972] 'process raft request' (duration: 84.091747ms)","trace[551203972] 'compare' (duration: 26.835184ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:54:27.127364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.610601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T00:54:27.1274Z","caller":"traceutil/trace.go:171","msg":"trace[534055646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25164; }","duration":"107.680293ms","start":"2026-05-19T00:54:27.01971Z","end":"2026-05-19T00:54:27.127391Z","steps":["trace[534055646] 'agreement among raft nodes before linearized reading' (duration: 107.514839ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:30.787442Z","caller":"traceutil/trace.go:171","msg":"trace[632059260] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"286.884033ms","start":"2026-05-19T00:54:30.500541Z","end":"2026-05-19T00:54:30.787425Z","steps":["trace[632059260] 'process raft request' (duration: 286.743699ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:30.787685Z","caller":"traceutil/trace.go:171","msg":"trace[2041028591] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"287.021556ms","start":"2026-05-19T00:54:30.50065Z","end":"2026-05-19T00:54:30.787672Z","steps":["trace[2041028591] 'process raft request' (duration: 286.743789ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:30.787653Z","caller":"traceutil/trace.go:171","msg":"trace[155154881] linearizableReadLoop","detail":"{readStateIndex:26005; appliedIndex:26003; }","duration":"197.131349ms","start":"2026-05-19T00:54:30.590487Z","end":"2026-05-19T00:54:30.787618Z","steps":["trace[155154881] 'read index received' (duration: 196.807622ms)","trace[155154881] 'applied index is now lower than readState.Index' (duration: 321.517µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:54:30.787777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.288872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:54:30.787842Z","caller":"traceutil/trace.go:171","msg":"trace[1486231221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25188; }","duration":"197.401165ms","start":"2026-05-19T00:54:30.590424Z","end":"2026-05-19T00:54:30.787825Z","steps":["trace[1486231221] 'agreement among raft nodes before linearized reading' (duration: 197.287903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:30.788005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.978362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:54:30.788039Z","caller":"traceutil/trace.go:171","msg":"trace[1990325578] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25188; }","duration":"181.065983ms","start":"2026-05-19T00:54:30.606965Z","end":"2026-05-19T00:54:30.788031Z","steps":["trace[1990325578] 'agreement among raft nodes before linearized reading' (duration: 180.980101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:30.788065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.155591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T00:54:30.788111Z","caller":"traceutil/trace.go:171","msg":"trace[1178699774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25188; }","duration":"157.212072ms","start":"2026-05-19T00:54:30.630886Z","end":"2026-05-19T00:54:30.788098Z","steps":["trace[1178699774] 'agreement among raft nodes before linearized reading' (duration: 157.075648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:30.788236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.365735ms","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-05-19T00:54:30.788254Z","caller":"traceutil/trace.go:171","msg":"trace[400038800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25188; }","duration":"157.412306ms","start":"2026-05-19T00:54:30.630837Z","end":"2026-05-19T00:54:30.788249Z","steps":["trace[400038800] 'agreement among raft nodes before linearized reading' (duration: 157.300074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:30.788317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.912185ms","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-05-19T00:54:30.788373Z","caller":"traceutil/trace.go:171","msg":"trace[1142371681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25188; }","duration":"162.018848ms","start":"2026-05-19T00:54:30.626337Z","end":"2026-05-19T00:54:30.788356Z","steps":["trace[1142371681] 'agreement among raft nodes before linearized reading' (duration: 161.830983ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:30.911558Z","caller":"traceutil/trace.go:171","msg":"trace[1565694114] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"118.139334ms","start":"2026-05-19T00:54:30.793386Z","end":"2026-05-19T00:54:30.911525Z","steps":["trace[1565694114] 'process raft request' (duration: 118.070812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:30.911922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.852835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-19T00:54:30.911964Z","caller":"traceutil/trace.go:171","msg":"trace[287145189] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25191; }","duration":"114.943858ms","start":"2026-05-19T00:54:30.797011Z","end":"2026-05-19T00:54:30.911954Z","steps":["trace[287145189] 'agreement among raft nodes before linearized reading' (duration: 114.770354ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:30.911989Z","caller":"traceutil/trace.go:171","msg":"trace[169504806] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"118.463702ms","start":"2026-05-19T00:54:30.793488Z","end":"2026-05-19T00:54:30.911952Z","steps":["trace[169504806] 'process raft request' (duration: 118.001641ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:30.911742Z","caller":"traceutil/trace.go:171","msg":"trace[2139239183] linearizableReadLoop","detail":"{readStateIndex:26008; appliedIndex:26005; }","duration":"114.683811ms","start":"2026-05-19T00:54:30.797023Z","end":"2026-05-19T00:54:30.911707Z","steps":["trace[2139239183] 'read index received' (duration: 67.688684ms)","trace[2139239183] 'applied index is now lower than readState.Index' (duration: 46.994407ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:30.911904Z","caller":"traceutil/trace.go:171","msg":"trace[1802073689] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"118.865151ms","start":"2026-05-19T00:54:30.793025Z","end":"2026-05-19T00:54:30.91189Z","steps":["trace[1802073689] 'process raft request' (duration: 71.650959ms)","trace[1802073689] 'compare' (duration: 46.498585ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:52.254833Z","caller":"traceutil/trace.go:171","msg":"trace[1391428419] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"141.876815ms","start":"2026-05-19T00:54:52.112923Z","end":"2026-05-19T00:54:52.2548Z","steps":["trace[1391428419] 'process raft request' (duration: 91.054916ms)","trace[1391428419] 'compare' (duration: 50.687075ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:54:52.621739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.814372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-19T00:54:52.621825Z","caller":"traceutil/trace.go:171","msg":"trace[1902949765] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25364; }","duration":"126.956075ms","start":"2026-05-19T00:54:52.494846Z","end":"2026-05-19T00:54:52.621802Z","steps":["trace[1902949765] 'range keys from in-memory index tree' (duration: 126.601747ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:52.955148Z","caller":"traceutil/trace.go:171","msg":"trace[1384411684] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"103.470512ms","start":"2026-05-19T00:54:52.851643Z","end":"2026-05-19T00:54:52.955114Z","steps":["trace[1384411684] 'process raft request' (duration: 103.221896ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:52.996188Z","caller":"traceutil/trace.go:171","msg":"trace[2079692841] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"142.14129ms","start":"2026-05-19T00:54:52.854022Z","end":"2026-05-19T00:54:52.996164Z","steps":["trace[2079692841] 'process raft request' (duration: 141.987667ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:53.159662Z","caller":"traceutil/trace.go:171","msg":"trace[1953630464] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"142.153451ms","start":"2026-05-19T00:54:53.017473Z","end":"2026-05-19T00:54:53.159626Z","steps":["trace[1953630464] 'process raft request' (duration: 141.932645ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:53.159869Z","caller":"traceutil/trace.go:171","msg":"trace[1494985333] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"142.357425ms","start":"2026-05-19T00:54:53.017466Z","end":"2026-05-19T00:54:53.159824Z","steps":["trace[1494985333] 'process raft request' (duration: 142.107979ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:53.367084Z","caller":"traceutil/trace.go:171","msg":"trace[478434051] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"276.580956ms","start":"2026-05-19T00:54:53.090475Z","end":"2026-05-19T00:54:53.367056Z","steps":["trace[478434051] 'process raft request' (duration: 217.95088ms)","trace[478434051] 'compare' (duration: 58.35178ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:53.367189Z","caller":"traceutil/trace.go:171","msg":"trace[93892086] linearizableReadLoop","detail":"{readStateIndex:26193; appliedIndex:26191; }","duration":"196.588356ms","start":"2026-05-19T00:54:53.170582Z","end":"2026-05-19T00:54:53.36717Z","steps":["trace[93892086] 'read index received' (duration: 137.845477ms)","trace[93892086] 'applied index is now lower than readState.Index' (duration: 58.741709ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:54:53.367435Z","caller":"traceutil/trace.go:171","msg":"trace[1932560010] transaction","detail":"{read_only:false; response_revision:25371; number_of_response:1; }","duration":"234.64531ms","start":"2026-05-19T00:54:53.132745Z","end":"2026-05-19T00:54:53.36739Z","steps":["trace[1932560010] 'process raft request' (duration: 234.252431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:53.367492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.878243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T00:54:53.367577Z","caller":"traceutil/trace.go:171","msg":"trace[251657316] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:25371; }","duration":"197.007816ms","start":"2026-05-19T00:54:53.170547Z","end":"2026-05-19T00:54:53.367555Z","steps":["trace[251657316] 'agreement among raft nodes before linearized reading' (duration: 196.797901ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:53.400164Z","caller":"traceutil/trace.go:171","msg":"trace[1089253881] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"162.291784ms","start":"2026-05-19T00:54:53.237846Z","end":"2026-05-19T00:54:53.400138Z","steps":["trace[1089253881] 'process raft request' (duration: 162.12302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:53.400253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.439357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T00:54:53.400333Z","caller":"traceutil/trace.go:171","msg":"trace[1784537306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25372; }","duration":"162.55577ms","start":"2026-05-19T00:54:53.237759Z","end":"2026-05-19T00:54:53.400315Z","steps":["trace[1784537306] 'agreement among raft nodes before linearized reading' (duration: 162.351085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:54:53.400298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.506915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:54:53.400412Z","caller":"traceutil/trace.go:171","msg":"trace[1206153725] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25372; }","duration":"161.653719ms","start":"2026-05-19T00:54:53.238736Z","end":"2026-05-19T00:54:53.400389Z","steps":["trace[1206153725] 'agreement among raft nodes before linearized reading' (duration: 161.460834ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:53.515486Z","caller":"traceutil/trace.go:171","msg":"trace[1475429623] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"135.443769ms","start":"2026-05-19T00:54:53.380013Z","end":"2026-05-19T00:54:53.515457Z","steps":["trace[1475429623] 'process raft request' (duration: 133.933244ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:54:53.515486Z","caller":"traceutil/trace.go:171","msg":"trace[386422900] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"110.234155ms","start":"2026-05-19T00:54:53.405225Z","end":"2026-05-19T00:54:53.51546Z","steps":["trace[386422900] 'process raft request' (duration: 110.156853ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:55:42.261472Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068a0.wal"} {"level":"info","ts":"2026-05-19T00:56:19.174812Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23359} {"level":"info","ts":"2026-05-19T00:56:19.306095Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23359,"took":"127.991371ms","hash":3367063906,"current-db-size-bytes":59367424,"current-db-size":"59 MB","current-db-size-in-use-bytes":36958208,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-19T00:56:19.306143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3367063906,"revision":23359,"compact-revision":20179} {"level":"warn","ts":"2026-05-19T00:58:00.669404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.203583ms","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-05-19T00:58:00.669494Z","caller":"traceutil/trace.go:171","msg":"trace[1297784082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27302; }","duration":"124.332795ms","start":"2026-05-19T00:58:00.545139Z","end":"2026-05-19T00:58:00.669472Z","steps":["trace[1297784082] 'range keys from in-memory index tree' (duration: 124.051589ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:22.104259Z","caller":"traceutil/trace.go:171","msg":"trace[196421769] transaction","detail":"{read_only:false; response_revision:27562; number_of_response:1; }","duration":"201.039946ms","start":"2026-05-19T00:58:21.903192Z","end":"2026-05-19T00:58:22.104232Z","steps":["trace[196421769] 'process raft request' (duration: 200.891652ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:22.115782Z","caller":"traceutil/trace.go:171","msg":"trace[732903841] transaction","detail":"{read_only:false; response_revision:27563; number_of_response:1; }","duration":"138.674849ms","start":"2026-05-19T00:58:21.977086Z","end":"2026-05-19T00:58:22.115761Z","steps":["trace[732903841] 'process raft request' (duration: 138.563896ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T01:01:19.181156Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26295} {"level":"info","ts":"2026-05-19T01:01:19.323567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26295,"took":"138.993596ms","hash":4023849838,"current-db-size-bytes":59367424,"current-db-size":"59 MB","current-db-size-in-use-bytes":34713600,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-19T01:01:19.323642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4023849838,"revision":26295,"compact-revision":23359} {"level":"info","ts":"2026-05-19T01:01:31.134943Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T01:01:31.140957Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-19T01:01:31.141035Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-19T01:06:19.186732Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28998} {"level":"info","ts":"2026-05-19T01:06:19.323915Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28998,"took":"132.973817ms","hash":1999772661,"current-db-size-bytes":59367424,"current-db-size":"59 MB","current-db-size-in-use-bytes":30609408,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T01:06:19.323997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1999772661,"revision":28998,"compact-revision":26295} {"level":"info","ts":"2026-05-19T01:11:19.193518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31259} {"level":"info","ts":"2026-05-19T01:11:19.325265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31259,"took":"127.168088ms","hash":3881307320,"current-db-size-bytes":59367424,"current-db-size":"59 MB","current-db-size-in-use-bytes":30543872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T01:11:19.325334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3881307320,"revision":31259,"compact-revision":28998} {"level":"info","ts":"2026-05-19T01:16:19.200899Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33534} {"level":"info","ts":"2026-05-19T01:16:19.345778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33534,"took":"140.104383ms","hash":2776568222,"current-db-size-bytes":59367424,"current-db-size":"59 MB","current-db-size-in-use-bytes":31911936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T01:16:19.345833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2776568222,"revision":33534,"compact-revision":31259} {"level":"info","ts":"2026-05-19T01:21:19.206769Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35835} {"level":"info","ts":"2026-05-19T01:21:19.358335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35835,"took":"145.599365ms","hash":636298491,"current-db-size-bytes":60694528,"current-db-size":"61 MB","current-db-size-in-use-bytes":31666176,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T01:21:19.358436Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":636298491,"revision":35835,"compact-revision":33534} {"level":"info","ts":"2026-05-19T01:22:51.191811Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T01:22:51.196925Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-19T01:22:51.197017Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-19T01:26:19.212743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38079} {"level":"info","ts":"2026-05-19T01:26:19.327646Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38079,"took":"109.839927ms","hash":3966571535,"current-db-size-bytes":60694528,"current-db-size":"61 MB","current-db-size-in-use-bytes":31735808,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T01:26:19.327704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3966571535,"revision":38079,"compact-revision":35835}