{"level":"warn","ts":"2026-05-12T17:04:21.548186Z","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-12T17:04:21.54826Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-12T17:04:21.548317Z","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-12T17:04:21.548331Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-05-12T17:04:21.54835Z","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-12T17:04:21.548937Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-05-12T17:04:21.549013Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-12T17:04:21.553982Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.774214ms"} {"level":"info","ts":"2026-05-12T17:04:21.561658Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-05-12T17:04:21.561817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T17:04:21.561891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-05-12T17:04:21.561909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T17:04:21.561962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-05-12T17:04:21.562035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-05-12T17:04:21.571505Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T17:04:21.572914Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T17:04:21.574104Z","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-12T17:04:21.576273Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T17:04:21.577117Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T17:04:21.577273Z","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-12T17:04:21.577333Z","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-12T17:04:21.577348Z","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-12T17:04:21.58001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-05-12T17:04:21.580137Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-05-12T17:04:21.580663Z","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-12T17:04:21.580762Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-05-12T17:04:21.580806Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-05-12T17:04:21.58089Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T17:04:21.58094Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T17:04:22.168011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T17:04:22.168062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T17:04:22.168079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-05-12T17:04:22.168093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-05-12T17:04:22.168101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-05-12T17:04:22.168112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-05-12T17:04:22.168122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-05-12T17:04:22.170529Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T17:04:22.176292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T17:04:22.176293Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T17:04:22.176367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T17:04:22.17684Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T17:04:22.176926Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T17:04:22.17697Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T17:04:22.177394Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T17:04:22.177437Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T17:04:22.179374Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T17:04:22.179754Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"warn","ts":"2026-05-12T17:04:40.300054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.72169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-12T17:04:40.300157Z","caller":"traceutil/trace.go:171","msg":"trace[948488538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"272.843432ms","start":"2026-05-12T17:04:40.027292Z","end":"2026-05-12T17:04:40.300135Z","steps":["trace[948488538] 'range keys from in-memory index tree' (duration: 272.587488ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:04:58.713639Z","caller":"traceutil/trace.go:171","msg":"trace[1282513963] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"134.38993ms","start":"2026-05-12T17:04:58.57922Z","end":"2026-05-12T17:04:58.71361Z","steps":["trace[1282513963] 'process raft request' (duration: 133.86547ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:04:59.201073Z","caller":"traceutil/trace.go:171","msg":"trace[282617084] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"108.342526ms","start":"2026-05-12T17:04:59.092693Z","end":"2026-05-12T17:04:59.201035Z","steps":["trace[282617084] 'read index received' (duration: 103.655344ms)","trace[282617084] 'applied index is now lower than readState.Index' (duration: 4.685922ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:04:59.201118Z","caller":"traceutil/trace.go:171","msg":"trace[779276931] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"426.113328ms","start":"2026-05-12T17:04:58.774949Z","end":"2026-05-12T17:04:59.201062Z","steps":["trace[779276931] 'process raft request' (duration: 421.454826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:04:59.201509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.775374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fv5gf\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-12T17:04:59.201613Z","caller":"traceutil/trace.go:171","msg":"trace[213755761] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fv5gf; range_end:; response_count:1; response_revision:578; }","duration":"108.923628ms","start":"2026-05-12T17:04:59.092644Z","end":"2026-05-12T17:04:59.201567Z","steps":["trace[213755761] 'agreement among raft nodes before linearized reading' (duration: 108.49551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:04:59.20177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:04:58.774919Z","time spent":"426.302182ms","remote":"127.0.0.1:47140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T17:06:33.581761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.097508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T17:06:33.581905Z","caller":"traceutil/trace.go:171","msg":"trace[909403106] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1829; }","duration":"150.323653ms","start":"2026-05-12T17:06:33.431554Z","end":"2026-05-12T17:06:33.581877Z","steps":["trace[909403106] 'count revisions from in-memory index tree' (duration: 149.805914ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:06:33.795755Z","caller":"traceutil/trace.go:171","msg":"trace[1823144564] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"182.634428ms","start":"2026-05-12T17:06:33.613091Z","end":"2026-05-12T17:06:33.795726Z","steps":["trace[1823144564] 'process raft request' (duration: 182.454925ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:06:33.815227Z","caller":"traceutil/trace.go:171","msg":"trace[1256446530] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"193.824224ms","start":"2026-05-12T17:06:33.621372Z","end":"2026-05-12T17:06:33.815197Z","steps":["trace[1256446530] 'process raft request' (duration: 193.62261ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:06:33.815435Z","caller":"traceutil/trace.go:171","msg":"trace[1284561495] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1897; }","duration":"140.781219ms","start":"2026-05-12T17:06:33.674632Z","end":"2026-05-12T17:06:33.815413Z","steps":["trace[1284561495] 'read index received' (duration: 120.980815ms)","trace[1284561495] 'applied index is now lower than readState.Index' (duration: 19.799624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:06:33.815666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.122471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fe02321b-ff49-44e3-997b-edac93d82fde\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-05-12T17:06:33.815718Z","caller":"traceutil/trace.go:171","msg":"trace[27222253] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fe02321b-ff49-44e3-997b-edac93d82fde; range_end:; response_count:1; response_revision:1832; }","duration":"106.251103ms","start":"2026-05-12T17:06:33.709455Z","end":"2026-05-12T17:06:33.815706Z","steps":["trace[27222253] 'agreement among raft nodes before linearized reading' (duration: 106.119101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:06:33.816136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.481601ms","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-12T17:06:33.816209Z","caller":"traceutil/trace.go:171","msg":"trace[1258150176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1832; }","duration":"141.615264ms","start":"2026-05-12T17:06:33.674577Z","end":"2026-05-12T17:06:33.816193Z","steps":["trace[1258150176] 'agreement among raft nodes before linearized reading' (duration: 141.072734ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:06:34.161877Z","caller":"traceutil/trace.go:171","msg":"trace[1970848034] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"156.775042ms","start":"2026-05-12T17:06:34.005075Z","end":"2026-05-12T17:06:34.16185Z","steps":["trace[1970848034] 'process raft request' (duration: 156.546558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:07:29.62779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.701354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T17:07:29.628188Z","caller":"traceutil/trace.go:171","msg":"trace[1733354652] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"337.593862ms","start":"2026-05-12T17:07:29.29057Z","end":"2026-05-12T17:07:29.628164Z","steps":["trace[1733354652] 'process raft request' (duration: 132.073264ms)","trace[1733354652] 'compare' (duration: 204.588882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:07:29.62828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:07:29.290543Z","time spent":"337.693665ms","remote":"127.0.0.1:46924","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-12T17:08:11.986807Z","caller":"traceutil/trace.go:171","msg":"trace[563148182] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"157.265319ms","start":"2026-05-12T17:08:11.82952Z","end":"2026-05-12T17:08:11.986785Z","steps":["trace[563148182] 'process raft request' (duration: 155.354233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:08:44.917585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.733914ms","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:5"} {"level":"info","ts":"2026-05-12T17:08:44.917682Z","caller":"traceutil/trace.go:171","msg":"trace[42217276] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2788; }","duration":"272.895177ms","start":"2026-05-12T17:08:44.64477Z","end":"2026-05-12T17:08:44.917665Z","steps":["trace[42217276] 'count revisions from in-memory index tree' (duration: 272.615682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:08:44.917989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.970361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T17:08:44.918148Z","caller":"traceutil/trace.go:171","msg":"trace[2066450463] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"249.742068ms","start":"2026-05-12T17:08:44.668384Z","end":"2026-05-12T17:08:44.918126Z","steps":["trace[2066450463] 'process raft request' (duration: 71.518973ms)","trace[2066450463] 'compare' (duration: 177.767627ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:11:15.211004Z","caller":"traceutil/trace.go:171","msg":"trace[1903570047] transaction","detail":"{read_only:false; response_revision:3780; number_of_response:1; }","duration":"193.874722ms","start":"2026-05-12T17:11:15.017102Z","end":"2026-05-12T17:11:15.210976Z","steps":["trace[1903570047] 'process raft request' (duration: 193.702839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:12:58.008482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.180859ms","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-12T17:12:58.008594Z","caller":"traceutil/trace.go:171","msg":"trace[203125063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5293; }","duration":"112.360733ms","start":"2026-05-12T17:12:57.896214Z","end":"2026-05-12T17:12:58.008575Z","steps":["trace[203125063] 'range keys from in-memory index tree' (duration: 111.992996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:12:58.008942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.922176ms","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-12T17:12:58.008988Z","caller":"traceutil/trace.go:171","msg":"trace[724134861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5293; }","duration":"148.999288ms","start":"2026-05-12T17:12:57.859978Z","end":"2026-05-12T17:12:58.008977Z","steps":["trace[724134861] 'range keys from in-memory index tree' (duration: 148.790344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:12:58.00922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.142814ms","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-12T17:12:58.009304Z","caller":"traceutil/trace.go:171","msg":"trace[1296738140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5293; }","duration":"138.236986ms","start":"2026-05-12T17:12:57.871049Z","end":"2026-05-12T17:12:58.009286Z","steps":["trace[1296738140] 'range keys from in-memory index tree' (duration: 137.939711ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:13:11.457257Z","caller":"traceutil/trace.go:171","msg":"trace[1900481633] linearizableReadLoop","detail":"{readStateIndex:5715; appliedIndex:5714; }","duration":"111.372825ms","start":"2026-05-12T17:13:11.345848Z","end":"2026-05-12T17:13:11.457221Z","steps":["trace[1900481633] 'read index received' (duration: 111.095328ms)","trace[1900481633] 'applied index is now lower than readState.Index' (duration: 276.397µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:13:11.457377Z","caller":"traceutil/trace.go:171","msg":"trace[1733519321] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"145.461572ms","start":"2026-05-12T17:13:11.311889Z","end":"2026-05-12T17:13:11.45735Z","steps":["trace[1733519321] 'process raft request' (duration: 145.077294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:13:11.457485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.597738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T17:13:11.457531Z","caller":"traceutil/trace.go:171","msg":"trace[320650849] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5514; }","duration":"111.72571ms","start":"2026-05-12T17:13:11.345795Z","end":"2026-05-12T17:13:11.457521Z","steps":["trace[320650849] 'agreement among raft nodes before linearized reading' (duration: 111.591108ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:14:22.216585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2992} {"level":"info","ts":"2026-05-12T17:14:22.287079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2992,"took":"69.146394ms","hash":1457265362,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":33742848,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-12T17:14:22.287138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1457265362,"revision":2992,"compact-revision":-1} {"level":"info","ts":"2026-05-12T17:19:06.169355Z","caller":"traceutil/trace.go:171","msg":"trace[505018663] linearizableReadLoop","detail":"{readStateIndex:8472; appliedIndex:8471; }","duration":"197.56518ms","start":"2026-05-12T17:19:05.971762Z","end":"2026-05-12T17:19:06.169328Z","steps":["trace[505018663] 'read index received' (duration: 197.339216ms)","trace[505018663] 'applied index is now lower than readState.Index' (duration: 225.074µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:19:06.16951Z","caller":"traceutil/trace.go:171","msg":"trace[346344238] transaction","detail":"{read_only:false; response_revision:8188; number_of_response:1; }","duration":"198.164511ms","start":"2026-05-12T17:19:05.971292Z","end":"2026-05-12T17:19:06.169457Z","steps":["trace[346344238] 'process raft request' (duration: 197.866866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:19:06.169626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.479845ms","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-12T17:19:06.169671Z","caller":"traceutil/trace.go:171","msg":"trace[981160389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8188; }","duration":"191.583037ms","start":"2026-05-12T17:19:05.978073Z","end":"2026-05-12T17:19:06.169656Z","steps":["trace[981160389] 'agreement among raft nodes before linearized reading' (duration: 191.415544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:19:06.16969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.875466ms","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-12T17:19:06.169794Z","caller":"traceutil/trace.go:171","msg":"trace[1150763473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8188; }","duration":"198.08709ms","start":"2026-05-12T17:19:05.97168Z","end":"2026-05-12T17:19:06.169767Z","steps":["trace[1150763473] 'agreement among raft nodes before linearized reading' (duration: 197.768094ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:19:18.112655Z","caller":"traceutil/trace.go:171","msg":"trace[1327262543] transaction","detail":"{read_only:false; response_revision:8251; number_of_response:1; }","duration":"107.719052ms","start":"2026-05-12T17:19:18.004885Z","end":"2026-05-12T17:19:18.112604Z","steps":["trace[1327262543] 'process raft request' (duration: 107.432437ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:19:19.023749Z","caller":"traceutil/trace.go:171","msg":"trace[1514547773] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"183.330782ms","start":"2026-05-12T17:19:18.840376Z","end":"2026-05-12T17:19:19.023707Z","steps":["trace[1514547773] 'process raft request' (duration: 183.023507ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:19:22.223938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6105} {"level":"info","ts":"2026-05-12T17:19:22.317293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6105,"took":"91.572879ms","hash":719347399,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":23146496,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-12T17:19:22.317372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":719347399,"revision":6105,"compact-revision":2992} {"level":"info","ts":"2026-05-12T17:19:47.972861Z","caller":"traceutil/trace.go:171","msg":"trace[589563933] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"267.463822ms","start":"2026-05-12T17:19:47.705371Z","end":"2026-05-12T17:19:47.972835Z","steps":["trace[589563933] 'process raft request' (duration: 266.857901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:19:48.14674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.980142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-544586ff7c-zzrp2.18aee10a7bae918f\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-05-12T17:19:48.14683Z","caller":"traceutil/trace.go:171","msg":"trace[64602510] range","detail":"{range_begin:/registry/events/openstack/barbican-api-544586ff7c-zzrp2.18aee10a7bae918f; range_end:; response_count:1; response_revision:8467; }","duration":"146.124205ms","start":"2026-05-12T17:19:48.000685Z","end":"2026-05-12T17:19:48.146809Z","steps":["trace[64602510] 'range keys from in-memory index tree' (duration: 145.782387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:20:00.828802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.323659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T17:20:00.82913Z","caller":"traceutil/trace.go:171","msg":"trace[1236955582] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"192.739509ms","start":"2026-05-12T17:20:00.636362Z","end":"2026-05-12T17:20:00.829101Z","steps":["trace[1236955582] 'process raft request' (duration: 192.605217ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:20:00.829321Z","caller":"traceutil/trace.go:171","msg":"trace[1251431548] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"192.553686ms","start":"2026-05-12T17:20:00.636483Z","end":"2026-05-12T17:20:00.829037Z","steps":["trace[1251431548] 'process raft request' (duration: 71.87929ms)","trace[1251431548] 'compare' (duration: 120.162767ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:20:01.361871Z","caller":"traceutil/trace.go:171","msg":"trace[338207986] linearizableReadLoop","detail":"{readStateIndex:8886; appliedIndex:8885; }","duration":"121.146794ms","start":"2026-05-12T17:20:01.240696Z","end":"2026-05-12T17:20:01.361843Z","steps":["trace[338207986] 'read index received' (duration: 46.677265ms)","trace[338207986] 'applied index is now lower than readState.Index' (duration: 74.468499ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:20:01.361959Z","caller":"traceutil/trace.go:171","msg":"trace[707062851] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"147.163124ms","start":"2026-05-12T17:20:01.214759Z","end":"2026-05-12T17:20:01.361922Z","steps":["trace[707062851] 'process raft request' (duration: 72.740966ms)","trace[707062851] 'compare' (duration: 74.194033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:20:01.363111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.394147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2213912"} {"level":"info","ts":"2026-05-12T17:20:01.363553Z","caller":"traceutil/trace.go:171","msg":"trace[116548748] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8584; }","duration":"122.888687ms","start":"2026-05-12T17:20:01.240645Z","end":"2026-05-12T17:20:01.363533Z","steps":["trace[116548748] 'agreement among raft nodes before linearized reading' (duration: 121.354178ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:20:01.531615Z","caller":"traceutil/trace.go:171","msg":"trace[151251102] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"163.226885ms","start":"2026-05-12T17:20:01.368338Z","end":"2026-05-12T17:20:01.531565Z","steps":["trace[151251102] 'process raft request' (duration: 98.687893ms)","trace[151251102] 'compare' (duration: 64.343978ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:20:01.531757Z","caller":"traceutil/trace.go:171","msg":"trace[137945574] linearizableReadLoop","detail":"{readStateIndex:8887; appliedIndex:8886; }","duration":"142.550517ms","start":"2026-05-12T17:20:01.389183Z","end":"2026-05-12T17:20:01.531733Z","steps":["trace[137945574] 'read index received' (duration: 77.925122ms)","trace[137945574] 'applied index is now lower than readState.Index' (duration: 64.623965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:20:01.532057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.847412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-05-12T17:20:01.532111Z","caller":"traceutil/trace.go:171","msg":"trace[1472943618] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8586; }","duration":"142.958014ms","start":"2026-05-12T17:20:01.389136Z","end":"2026-05-12T17:20:01.532094Z","steps":["trace[1472943618] 'agreement among raft nodes before linearized reading' (duration: 142.71001ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:20:51.173341Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002473.wal"} {"level":"info","ts":"2026-05-12T17:21:21.537955Z","caller":"traceutil/trace.go:171","msg":"trace[325008667] transaction","detail":"{read_only:false; response_revision:9352; number_of_response:1; }","duration":"259.753649ms","start":"2026-05-12T17:21:21.278173Z","end":"2026-05-12T17:21:21.537927Z","steps":["trace[325008667] 'process raft request' (duration: 252.705807ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:22:05.257398Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T17:22:05.267589Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-12T17:22:05.267695Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-12T17:24:22.231625Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8280} {"level":"info","ts":"2026-05-12T17:24:22.316469Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8280,"took":"82.383807ms","hash":3408835374,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":23834624,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-12T17:24:22.316569Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3408835374,"revision":8280,"compact-revision":6105} {"level":"info","ts":"2026-05-12T17:25:28.870465Z","caller":"traceutil/trace.go:171","msg":"trace[1395759721] transaction","detail":"{read_only:false; response_revision:10955; number_of_response:1; }","duration":"143.030858ms","start":"2026-05-12T17:25:28.727409Z","end":"2026-05-12T17:25:28.87044Z","steps":["trace[1395759721] 'process raft request' (duration: 142.873625ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:28.886551Z","caller":"traceutil/trace.go:171","msg":"trace[1236785568] transaction","detail":"{read_only:false; response_revision:10956; number_of_response:1; }","duration":"157.898217ms","start":"2026-05-12T17:25:28.728633Z","end":"2026-05-12T17:25:28.886531Z","steps":["trace[1236785568] 'process raft request' (duration: 157.119732ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:26:50.721661Z","caller":"traceutil/trace.go:171","msg":"trace[927227129] transaction","detail":"{read_only:false; response_revision:11733; number_of_response:1; }","duration":"178.419382ms","start":"2026-05-12T17:26:50.543217Z","end":"2026-05-12T17:26:50.721636Z","steps":["trace[927227129] 'process raft request' (duration: 178.264429ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:26:51.233241Z","caller":"traceutil/trace.go:171","msg":"trace[923795221] linearizableReadLoop","detail":"{readStateIndex:12135; appliedIndex:12134; }","duration":"227.505014ms","start":"2026-05-12T17:26:51.005707Z","end":"2026-05-12T17:26:51.233212Z","steps":["trace[923795221] 'read index received' (duration: 227.31513ms)","trace[923795221] 'applied index is now lower than readState.Index' (duration: 188.224µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:26:51.233328Z","caller":"traceutil/trace.go:171","msg":"trace[489840580] transaction","detail":"{read_only:false; response_revision:11736; number_of_response:1; }","duration":"242.263421ms","start":"2026-05-12T17:26:50.991023Z","end":"2026-05-12T17:26:51.233287Z","steps":["trace[489840580] 'process raft request' (duration: 241.924565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:26:51.233518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.797359ms","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-12T17:26:51.233631Z","caller":"traceutil/trace.go:171","msg":"trace[1124505332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11736; }","duration":"227.959573ms","start":"2026-05-12T17:26:51.005644Z","end":"2026-05-12T17:26:51.233603Z","steps":["trace[1124505332] 'agreement among raft nodes before linearized reading' (duration: 227.708857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:26:51.241638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.704567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8hph9\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-12T17:26:51.241718Z","caller":"traceutil/trace.go:171","msg":"trace[1862355976] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8hph9; range_end:; response_count:1; response_revision:11738; }","duration":"178.794218ms","start":"2026-05-12T17:26:51.062907Z","end":"2026-05-12T17:26:51.241701Z","steps":["trace[1862355976] 'agreement among raft nodes before linearized reading' (duration: 178.589605ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:29:22.238303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10500} {"level":"info","ts":"2026-05-12T17:29:22.316453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10500,"took":"75.690753ms","hash":2843116427,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":25976832,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-12T17:29:22.316527Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2843116427,"revision":10500,"compact-revision":8280} {"level":"info","ts":"2026-05-12T17:33:12.73795Z","caller":"traceutil/trace.go:171","msg":"trace[613837339] transaction","detail":"{read_only:false; response_revision:14300; number_of_response:1; }","duration":"120.176698ms","start":"2026-05-12T17:33:12.61775Z","end":"2026-05-12T17:33:12.737927Z","steps":["trace[613837339] 'process raft request' (duration: 119.855792ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:33:50.613933Z","caller":"traceutil/trace.go:171","msg":"trace[2001256012] transaction","detail":"{read_only:false; response_revision:14692; number_of_response:1; }","duration":"191.759253ms","start":"2026-05-12T17:33:50.422146Z","end":"2026-05-12T17:33:50.613905Z","steps":["trace[2001256012] 'process raft request' (duration: 191.59039ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:34:22.245686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12757} {"level":"info","ts":"2026-05-12T17:34:22.323714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12757,"took":"75.123112ms","hash":729998223,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":28655616,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-12T17:34:22.323768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":729998223,"revision":12757,"compact-revision":10500} {"level":"info","ts":"2026-05-12T17:34:41.719871Z","caller":"traceutil/trace.go:171","msg":"trace[1922862108] transaction","detail":"{read_only:false; response_revision:15327; number_of_response:1; }","duration":"131.089563ms","start":"2026-05-12T17:34:41.588748Z","end":"2026-05-12T17:34:41.719838Z","steps":["trace[1922862108] 'process raft request' (duration: 130.837089ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:34:41.720661Z","caller":"traceutil/trace.go:171","msg":"trace[1424354636] transaction","detail":"{read_only:false; response_revision:15328; number_of_response:1; }","duration":"129.735887ms","start":"2026-05-12T17:34:41.590912Z","end":"2026-05-12T17:34:41.720648Z","steps":["trace[1424354636] 'process raft request' (duration: 129.661276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:35:16.311886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.747334ms","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-12T17:35:16.312006Z","caller":"traceutil/trace.go:171","msg":"trace[1508340430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15509; }","duration":"116.899297ms","start":"2026-05-12T17:35:16.195059Z","end":"2026-05-12T17:35:16.311958Z","steps":["trace[1508340430] 'range keys from in-memory index tree' (duration: 116.56878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:35:16.312026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.751121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T17:35:16.312116Z","caller":"traceutil/trace.go:171","msg":"trace[717434325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15509; }","duration":"110.891774ms","start":"2026-05-12T17:35:16.201204Z","end":"2026-05-12T17:35:16.312096Z","steps":["trace[717434325] 'range keys from in-memory index tree' (duration: 110.625899ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:39:22.253703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14909} {"level":"info","ts":"2026-05-12T17:39:22.346043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14909,"took":"89.52298ms","hash":70804633,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":30453760,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T17:39:22.346098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":70804633,"revision":14909,"compact-revision":12757} {"level":"info","ts":"2026-05-12T17:40:48.48827Z","caller":"traceutil/trace.go:171","msg":"trace[1800721990] linearizableReadLoop","detail":"{readStateIndex:18615; appliedIndex:18614; }","duration":"110.398613ms","start":"2026-05-12T17:40:48.377844Z","end":"2026-05-12T17:40:48.488243Z","steps":["trace[1800721990] 'read index received' (duration: 110.051576ms)","trace[1800721990] 'applied index is now lower than readState.Index' (duration: 345.887µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:40:48.488308Z","caller":"traceutil/trace.go:171","msg":"trace[829817991] transaction","detail":"{read_only:false; response_revision:18004; number_of_response:1; }","duration":"129.187764ms","start":"2026-05-12T17:40:48.359087Z","end":"2026-05-12T17:40:48.488275Z","steps":["trace[829817991] 'process raft request' (duration: 128.824137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:40:48.48843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.570735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:40:48.488462Z","caller":"traceutil/trace.go:171","msg":"trace[815204853] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:18004; }","duration":"110.651086ms","start":"2026-05-12T17:40:48.377802Z","end":"2026-05-12T17:40:48.488453Z","steps":["trace[815204853] 'agreement among raft nodes before linearized reading' (duration: 110.558195ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:40:55.020545Z","caller":"traceutil/trace.go:171","msg":"trace[788179107] transaction","detail":"{read_only:false; response_revision:18044; number_of_response:1; }","duration":"162.957138ms","start":"2026-05-12T17:40:54.857548Z","end":"2026-05-12T17:40:55.020506Z","steps":["trace[788179107] 'process raft request' (duration: 162.673253ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:40:55.415345Z","caller":"traceutil/trace.go:171","msg":"trace[1633490037] linearizableReadLoop","detail":"{readStateIndex:18657; appliedIndex:18656; }","duration":"310.742342ms","start":"2026-05-12T17:40:55.104573Z","end":"2026-05-12T17:40:55.415315Z","steps":["trace[1633490037] 'read index received' (duration: 310.327884ms)","trace[1633490037] 'applied index is now lower than readState.Index' (duration: 413.548µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:40:55.415393Z","caller":"traceutil/trace.go:171","msg":"trace[1181099453] transaction","detail":"{read_only:false; response_revision:18045; number_of_response:1; }","duration":"379.144935ms","start":"2026-05-12T17:40:55.036213Z","end":"2026-05-12T17:40:55.415358Z","steps":["trace[1181099453] 'process raft request' (duration: 378.794158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:40:55.415554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:40:55.03619Z","time spent":"379.275367ms","remote":"127.0.0.1:46840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20970,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:40:55.415628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.038718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-kzpz4\" ","response":"range_response_count:1 size:9788"} {"level":"info","ts":"2026-05-12T17:40:55.415719Z","caller":"traceutil/trace.go:171","msg":"trace[1493753281] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-kzpz4; range_end:; response_count:1; response_revision:18045; }","duration":"311.174021ms","start":"2026-05-12T17:40:55.104521Z","end":"2026-05-12T17:40:55.415696Z","steps":["trace[1493753281] 'agreement among raft nodes before linearized reading' (duration: 310.927596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:40:55.415768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:40:55.104502Z","time spent":"311.256162ms","remote":"127.0.0.1:46840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9812,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-kzpz4\" "} {"level":"warn","ts":"2026-05-12T17:40:55.442554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.229085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-12T17:40:55.442632Z","caller":"traceutil/trace.go:171","msg":"trace[760765868] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:18046; }","duration":"129.357568ms","start":"2026-05-12T17:40:55.313256Z","end":"2026-05-12T17:40:55.442614Z","steps":["trace[760765868] 'agreement among raft nodes before linearized reading' (duration: 129.180394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:40:55.442845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.109976ms","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-12T17:40:55.442883Z","caller":"traceutil/trace.go:171","msg":"trace[915776962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18046; }","duration":"257.185436ms","start":"2026-05-12T17:40:55.185689Z","end":"2026-05-12T17:40:55.442874Z","steps":["trace[915776962] 'agreement among raft nodes before linearized reading' (duration: 257.088085ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:40:55.546682Z","caller":"traceutil/trace.go:171","msg":"trace[6911188] transaction","detail":"{read_only:false; response_revision:18047; number_of_response:1; }","duration":"100.114668ms","start":"2026-05-12T17:40:55.446544Z","end":"2026-05-12T17:40:55.546659Z","steps":["trace[6911188] 'process raft request' (duration: 51.772201ms)","trace[6911188] 'compare' (duration: 47.845078ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:43:21.085105Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T17:43:21.090948Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-12T17:43:21.091063Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-12T17:44:22.259603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17395} {"level":"info","ts":"2026-05-12T17:44:22.356829Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17395,"took":"94.288178ms","hash":1254898769,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":34324480,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-12T17:44:22.356883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1254898769,"revision":17395,"compact-revision":14909} {"level":"info","ts":"2026-05-12T17:44:32.758472Z","caller":"traceutil/trace.go:171","msg":"trace[989411000] transaction","detail":"{read_only:false; response_revision:19828; number_of_response:1; }","duration":"100.488955ms","start":"2026-05-12T17:44:32.657923Z","end":"2026-05-12T17:44:32.758412Z","steps":["trace[989411000] 'process raft request' (duration: 83.70185ms)","trace[989411000] 'compare' (duration: 16.558991ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:46:13.177863Z","caller":"traceutil/trace.go:171","msg":"trace[831479968] transaction","detail":"{read_only:false; response_revision:20595; number_of_response:1; }","duration":"201.52626ms","start":"2026-05-12T17:46:12.976278Z","end":"2026-05-12T17:46:13.177804Z","steps":["trace[831479968] 'process raft request' (duration: 201.352477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:47:00.911835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.883929ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-12T17:47:01.082998Z","caller":"traceutil/trace.go:171","msg":"trace[1345431983] linearizableReadLoop","detail":"{readStateIndex:21548; appliedIndex:21547; }","duration":"183.375171ms","start":"2026-05-12T17:47:00.899598Z","end":"2026-05-12T17:47:01.082973Z","steps":["trace[1345431983] 'read index received' (duration: 183.114385ms)","trace[1345431983] 'applied index is now lower than readState.Index' (duration: 259.836µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:47:01.083102Z","caller":"traceutil/trace.go:171","msg":"trace[1153553547] transaction","detail":"{read_only:false; response_revision:20842; number_of_response:1; }","duration":"246.968203ms","start":"2026-05-12T17:47:00.83609Z","end":"2026-05-12T17:47:01.083058Z","steps":["trace[1153553547] 'process raft request' (duration: 246.676307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:47:01.083252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.629495ms","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-12T17:47:01.083316Z","caller":"traceutil/trace.go:171","msg":"trace[1184692434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20842; }","duration":"183.743527ms","start":"2026-05-12T17:47:00.899559Z","end":"2026-05-12T17:47:01.083302Z","steps":["trace[1184692434] 'agreement among raft nodes before linearized reading' (duration: 183.527473ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:47:01.211777Z","caller":"traceutil/trace.go:171","msg":"trace[1340182561] linearizableReadLoop","detail":"{readStateIndex:21549; appliedIndex:21548; }","duration":"106.794804ms","start":"2026-05-12T17:47:01.104957Z","end":"2026-05-12T17:47:01.211752Z","steps":["trace[1340182561] 'read index received' (duration: 27.518007ms)","trace[1340182561] 'applied index is now lower than readState.Index' (duration: 79.275927ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:47:01.211898Z","caller":"traceutil/trace.go:171","msg":"trace[422681281] transaction","detail":"{read_only:false; response_revision:20843; number_of_response:1; }","duration":"124.451375ms","start":"2026-05-12T17:47:01.087433Z","end":"2026-05-12T17:47:01.211884Z","steps":["trace[422681281] 'process raft request' (duration: 44.992874ms)","trace[422681281] 'compare' (duration: 79.213706ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:47:01.212104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.141251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:47:01.212129Z","caller":"traceutil/trace.go:171","msg":"trace[963085422] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:20843; }","duration":"107.220803ms","start":"2026-05-12T17:47:01.104902Z","end":"2026-05-12T17:47:01.212123Z","steps":["trace[963085422] 'agreement among raft nodes before linearized reading' (duration: 107.151261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:47:01.510718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.09236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:47:01.510828Z","caller":"traceutil/trace.go:171","msg":"trace[764253405] linearizableReadLoop","detail":"{readStateIndex:21550; appliedIndex:21549; }","duration":"117.978123ms","start":"2026-05-12T17:47:01.392834Z","end":"2026-05-12T17:47:01.510812Z","steps":["trace[764253405] 'read index received' (duration: 52.061µs)","trace[764253405] 'applied index is now lower than readState.Index' (duration: 117.924982ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:47:01.510965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.5ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:47:01.511004Z","caller":"traceutil/trace.go:171","msg":"trace[420888665] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:20844; }","duration":"104.580901ms","start":"2026-05-12T17:47:01.406411Z","end":"2026-05-12T17:47:01.510992Z","steps":["trace[420888665] 'agreement among raft nodes before linearized reading' (duration: 104.51261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:47:01.51101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.146717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:47:01.511034Z","caller":"traceutil/trace.go:171","msg":"trace[1732648028] transaction","detail":"{read_only:false; response_revision:20844; number_of_response:1; }","duration":"162.55939ms","start":"2026-05-12T17:47:01.348424Z","end":"2026-05-12T17:47:01.510983Z","steps":["trace[1732648028] 'process raft request' (duration: 24.109253ms)","trace[1732648028] 'compare' (duration: 137.966458ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:47:01.511112Z","caller":"traceutil/trace.go:171","msg":"trace[2018682926] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:20844; }","duration":"118.306199ms","start":"2026-05-12T17:47:01.392782Z","end":"2026-05-12T17:47:01.511089Z","steps":["trace[2018682926] 'agreement among raft nodes before linearized reading' (duration: 118.132326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:47:01.849479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.292773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:47:01.849576Z","caller":"traceutil/trace.go:171","msg":"trace[956200027] linearizableReadLoop","detail":"{readStateIndex:21551; appliedIndex:21550; }","duration":"250.416917ms","start":"2026-05-12T17:47:01.599146Z","end":"2026-05-12T17:47:01.849563Z","steps":["trace[956200027] 'read index received' (duration: 62.703746ms)","trace[956200027] 'applied index is now lower than readState.Index' (duration: 187.711551ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:47:01.84962Z","caller":"traceutil/trace.go:171","msg":"trace[1405821973] transaction","detail":"{read_only:false; response_revision:20845; number_of_response:1; }","duration":"303.067065ms","start":"2026-05-12T17:47:01.546521Z","end":"2026-05-12T17:47:01.849588Z","steps":["trace[1405821973] 'process raft request' (duration: 115.593098ms)","trace[1405821973] 'compare' (duration: 187.09135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:47:01.849733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.59634ms","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-12T17:47:01.849762Z","caller":"traceutil/trace.go:171","msg":"trace[1901749789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20845; }","duration":"250.622811ms","start":"2026-05-12T17:47:01.599129Z","end":"2026-05-12T17:47:01.849752Z","steps":["trace[1901749789] 'agreement among raft nodes before linearized reading' (duration: 250.504339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:47:01.849786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:47:01.546494Z","time spent":"303.206738ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:47:01.850102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.985571ms","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-12T17:47:01.850188Z","caller":"traceutil/trace.go:171","msg":"trace[1937079626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20845; }","duration":"233.105372ms","start":"2026-05-12T17:47:01.617062Z","end":"2026-05-12T17:47:01.850167Z","steps":["trace[1937079626] 'agreement among raft nodes before linearized reading' (duration: 232.861358ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:49:22.268385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19729} {"level":"info","ts":"2026-05-12T17:49:22.38179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19729,"took":"109.873831ms","hash":3853190225,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":29253632,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-12T17:49:22.381875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3853190225,"revision":19729,"compact-revision":17395} {"level":"info","ts":"2026-05-12T17:51:09.15995Z","caller":"traceutil/trace.go:171","msg":"trace[472904473] transaction","detail":"{read_only:false; response_revision:23367; number_of_response:1; }","duration":"331.444788ms","start":"2026-05-12T17:51:08.828477Z","end":"2026-05-12T17:51:09.159922Z","steps":["trace[472904473] 'process raft request' (duration: 331.264445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:51:09.160182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:51:08.828455Z","time spent":"331.623881ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:51:09.338527Z","caller":"traceutil/trace.go:171","msg":"trace[482392109] transaction","detail":"{read_only:false; response_revision:23368; number_of_response:1; }","duration":"446.861193ms","start":"2026-05-12T17:51:08.891636Z","end":"2026-05-12T17:51:09.338498Z","steps":["trace[482392109] 'process raft request' (duration: 432.905002ms)","trace[482392109] 'compare' (duration: 13.33064ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:51:09.338702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:51:08.891609Z","time spent":"446.992885ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:51:09.3394Z","caller":"traceutil/trace.go:171","msg":"trace[1815620525] transaction","detail":"{read_only:false; response_revision:23369; number_of_response:1; }","duration":"300.675951ms","start":"2026-05-12T17:51:09.03871Z","end":"2026-05-12T17:51:09.339386Z","steps":["trace[1815620525] 'process raft request' (duration: 300.537668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:51:09.339544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:51:09.038678Z","time spent":"300.780682ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:51:09.339744Z","caller":"traceutil/trace.go:171","msg":"trace[1779161934] linearizableReadLoop","detail":"{readStateIndex:24152; appliedIndex:24149; }","duration":"164.551977ms","start":"2026-05-12T17:51:09.175134Z","end":"2026-05-12T17:51:09.339686Z","steps":["trace[1779161934] 'read index received' (duration: 149.345051ms)","trace[1779161934] 'applied index is now lower than readState.Index' (duration: 15.188116ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:51:09.339789Z","caller":"traceutil/trace.go:171","msg":"trace[1249747713] transaction","detail":"{read_only:false; response_revision:23370; number_of_response:1; }","duration":"188.10311ms","start":"2026-05-12T17:51:09.151638Z","end":"2026-05-12T17:51:09.339741Z","steps":["trace[1249747713] 'process raft request' (duration: 187.696022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:51:09.340054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.922354ms","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-12T17:51:09.340126Z","caller":"traceutil/trace.go:171","msg":"trace[825917725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23370; }","duration":"165.041936ms","start":"2026-05-12T17:51:09.175068Z","end":"2026-05-12T17:51:09.34011Z","steps":["trace[825917725] 'agreement among raft nodes before linearized reading' (duration: 164.797371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:51:09.340254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.713823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:51:09.340312Z","caller":"traceutil/trace.go:171","msg":"trace[2095506872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23370; }","duration":"138.828654ms","start":"2026-05-12T17:51:09.201466Z","end":"2026-05-12T17:51:09.340294Z","steps":["trace[2095506872] 'agreement among raft nodes before linearized reading' (duration: 138.451787ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:54:22.282038Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21958} {"level":"info","ts":"2026-05-12T17:54:22.389731Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21958,"took":"104.47348ms","hash":3621956639,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":42782720,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-12T17:54:22.390244Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3621956639,"revision":21958,"compact-revision":19729} {"level":"info","ts":"2026-05-12T17:54:48.952439Z","caller":"traceutil/trace.go:171","msg":"trace[1120656492] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"133.75599ms","start":"2026-05-12T17:54:48.818648Z","end":"2026-05-12T17:54:48.952404Z","steps":["trace[1120656492] 'process raft request' (duration: 133.532465ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:54:49.107013Z","caller":"traceutil/trace.go:171","msg":"trace[222040985] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"180.589519ms","start":"2026-05-12T17:54:48.926401Z","end":"2026-05-12T17:54:49.106991Z","steps":["trace[222040985] 'process raft request' (duration: 180.269943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:54:49.107136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.502623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:54:49.107256Z","caller":"traceutil/trace.go:171","msg":"trace[1280971806] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25527; }","duration":"151.675046ms","start":"2026-05-12T17:54:48.955545Z","end":"2026-05-12T17:54:49.10722Z","steps":["trace[1280971806] 'agreement among raft nodes before linearized reading' (duration: 151.486512ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:54:49.106936Z","caller":"traceutil/trace.go:171","msg":"trace[205676113] linearizableReadLoop","detail":"{readStateIndex:26366; appliedIndex:26365; }","duration":"151.310209ms","start":"2026-05-12T17:54:48.955593Z","end":"2026-05-12T17:54:49.106904Z","steps":["trace[205676113] 'read index received' (duration: 150.937322ms)","trace[205676113] 'applied index is now lower than readState.Index' (duration: 371.787µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:54:49.349371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.326805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:54:49.349622Z","caller":"traceutil/trace.go:171","msg":"trace[1618483272] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"237.464074ms","start":"2026-05-12T17:54:49.11212Z","end":"2026-05-12T17:54:49.349584Z","steps":["trace[1618483272] 'process raft request' (duration: 122.787523ms)","trace[1618483272] 'compare' (duration: 114.145432ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:53.716755Z","caller":"traceutil/trace.go:171","msg":"trace[1599423929] linearizableReadLoop","detail":"{readStateIndex:26404; appliedIndex:26403; }","duration":"106.61834ms","start":"2026-05-12T17:54:53.610108Z","end":"2026-05-12T17:54:53.716727Z","steps":["trace[1599423929] 'read index received' (duration: 106.416867ms)","trace[1599423929] 'applied index is now lower than readState.Index' (duration: 199.863µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:53.716809Z","caller":"traceutil/trace.go:171","msg":"trace[521177472] transaction","detail":"{read_only:false; response_revision:25564; number_of_response:1; }","duration":"125.691948ms","start":"2026-05-12T17:54:53.59108Z","end":"2026-05-12T17:54:53.716772Z","steps":["trace[521177472] 'process raft request' (duration: 125.442284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:54:53.716945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.819494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:54:53.717046Z","caller":"traceutil/trace.go:171","msg":"trace[1269357703] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25564; }","duration":"106.930686ms","start":"2026-05-12T17:54:53.61005Z","end":"2026-05-12T17:54:53.716981Z","steps":["trace[1269357703] 'agreement among raft nodes before linearized reading' (duration: 106.829834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:54:54.216782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.186335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:54:54.216928Z","caller":"traceutil/trace.go:171","msg":"trace[490891563] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"193.828836ms","start":"2026-05-12T17:54:54.023069Z","end":"2026-05-12T17:54:54.216898Z","steps":["trace[490891563] 'process raft request' (duration: 57.412857ms)","trace[490891563] 'compare' (duration: 135.979451ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:54.490447Z","caller":"traceutil/trace.go:171","msg":"trace[810927996] transaction","detail":"{read_only:false; response_revision:25569; number_of_response:1; }","duration":"140.973705ms","start":"2026-05-12T17:54:54.349436Z","end":"2026-05-12T17:54:54.49041Z","steps":["trace[810927996] 'process raft request' (duration: 47.675795ms)","trace[810927996] 'compare' (duration: 93.142697ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:54.542207Z","caller":"traceutil/trace.go:171","msg":"trace[2087743486] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"185.881727ms","start":"2026-05-12T17:54:54.35629Z","end":"2026-05-12T17:54:54.542172Z","steps":["trace[2087743486] 'process raft request' (duration: 185.314406ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:54:54.878351Z","caller":"traceutil/trace.go:171","msg":"trace[505756433] linearizableReadLoop","detail":"{readStateIndex:26412; appliedIndex:26410; }","duration":"328.243088ms","start":"2026-05-12T17:54:54.55009Z","end":"2026-05-12T17:54:54.878333Z","steps":["trace[505756433] 'read index received' (duration: 249.580452ms)","trace[505756433] 'applied index is now lower than readState.Index' (duration: 78.661396ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:54.878437Z","caller":"traceutil/trace.go:171","msg":"trace[1919220769] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"329.522593ms","start":"2026-05-12T17:54:54.54888Z","end":"2026-05-12T17:54:54.878403Z","steps":["trace[1919220769] 'process raft request' (duration: 329.36439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:54:54.878609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:54:54.548869Z","time spent":"329.636674ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:54:54.878605Z","caller":"traceutil/trace.go:171","msg":"trace[106919383] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"331.605801ms","start":"2026-05-12T17:54:54.546835Z","end":"2026-05-12T17:54:54.878441Z","steps":["trace[106919383] 'process raft request' (duration: 252.67183ms)","trace[106919383] 'compare' (duration: 78.556574ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:54:54.878863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:54:54.546817Z","time spent":"331.872126ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:54:54.878932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.826639ms","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-12T17:54:54.878972Z","caller":"traceutil/trace.go:171","msg":"trace[1190406269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25572; }","duration":"328.8955ms","start":"2026-05-12T17:54:54.550063Z","end":"2026-05-12T17:54:54.878958Z","steps":["trace[1190406269] 'agreement among raft nodes before linearized reading' (duration: 328.38708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:54:54.879013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:54:54.550049Z","time spent":"328.94892ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T17:54:54.879004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.954983ms","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-12T17:54:54.879104Z","caller":"traceutil/trace.go:171","msg":"trace[127828349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25572; }","duration":"328.071575ms","start":"2026-05-12T17:54:54.551012Z","end":"2026-05-12T17:54:54.879083Z","steps":["trace[127828349] 'agreement among raft nodes before linearized reading' (duration: 327.765849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:54:54.879154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:54:54.550991Z","time spent":"328.155156ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T17:54:55.196459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.329918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:54:55.196562Z","caller":"traceutil/trace.go:171","msg":"trace[1491080984] transaction","detail":"{read_only:false; response_revision:25575; number_of_response:1; }","duration":"221.576506ms","start":"2026-05-12T17:54:54.974967Z","end":"2026-05-12T17:54:55.196544Z","steps":["trace[1491080984] 'process raft request' (duration: 102.075655ms)","trace[1491080984] 'compare' (duration: 119.112674ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:54:55.49504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.127455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:54:55.495149Z","caller":"traceutil/trace.go:171","msg":"trace[19982285] linearizableReadLoop","detail":"{readStateIndex:26416; appliedIndex:26415; }","duration":"291.852856ms","start":"2026-05-12T17:54:55.203281Z","end":"2026-05-12T17:54:55.495133Z","steps":["trace[19982285] 'read index received' (duration: 180.483236ms)","trace[19982285] 'applied index is now lower than readState.Index' (duration: 111.368509ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:55.495224Z","caller":"traceutil/trace.go:171","msg":"trace[2059630965] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"369.91772ms","start":"2026-05-12T17:54:55.125291Z","end":"2026-05-12T17:54:55.495209Z","steps":["trace[2059630965] 'process raft request' (duration: 258.53922ms)","trace[2059630965] 'compare' (duration: 110.994083ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:54:55.495288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:54:55.125271Z","time spent":"369.986941ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:54:55.495413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.1218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:54:55.495518Z","caller":"traceutil/trace.go:171","msg":"trace[1454514128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25576; }","duration":"292.259442ms","start":"2026-05-12T17:54:55.203245Z","end":"2026-05-12T17:54:55.495504Z","steps":["trace[1454514128] 'agreement among raft nodes before linearized reading' (duration: 291.960757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:54:55.49859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.190726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-05-12T17:54:55.498611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.919443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:54:55.49867Z","caller":"traceutil/trace.go:171","msg":"trace[1034832020] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:25579; }","duration":"135.316408ms","start":"2026-05-12T17:54:55.363335Z","end":"2026-05-12T17:54:55.498651Z","steps":["trace[1034832020] 'agreement among raft nodes before linearized reading' (duration: 135.094364ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:54:55.498686Z","caller":"traceutil/trace.go:171","msg":"trace[1665507241] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25579; }","duration":"238.053756ms","start":"2026-05-12T17:54:55.260613Z","end":"2026-05-12T17:54:55.498667Z","steps":["trace[1665507241] 'agreement among raft nodes before linearized reading' (duration: 237.933553ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:54:56.041995Z","caller":"traceutil/trace.go:171","msg":"trace[1116668914] linearizableReadLoop","detail":"{readStateIndex:26422; appliedIndex:26421; }","duration":"107.670649ms","start":"2026-05-12T17:54:55.9343Z","end":"2026-05-12T17:54:56.04197Z","steps":["trace[1116668914] 'read index received' (duration: 107.477925ms)","trace[1116668914] 'applied index is now lower than readState.Index' (duration: 191.594µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:56.042132Z","caller":"traceutil/trace.go:171","msg":"trace[168082572] transaction","detail":"{read_only:false; response_revision:25581; number_of_response:1; }","duration":"124.456944ms","start":"2026-05-12T17:54:55.917665Z","end":"2026-05-12T17:54:56.042122Z","steps":["trace[168082572] 'process raft request' (duration: 124.092457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:54:56.042319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.008546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:54:56.042368Z","caller":"traceutil/trace.go:171","msg":"trace[866288124] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25581; }","duration":"108.102657ms","start":"2026-05-12T17:54:55.934254Z","end":"2026-05-12T17:54:56.042357Z","steps":["trace[866288124] 'agreement among raft nodes before linearized reading' (duration: 108.007915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:54:56.433129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.338927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:54:56.433217Z","caller":"traceutil/trace.go:171","msg":"trace[714503954] transaction","detail":"{read_only:false; response_revision:25583; number_of_response:1; }","duration":"210.487489ms","start":"2026-05-12T17:54:56.222716Z","end":"2026-05-12T17:54:56.433204Z","steps":["trace[714503954] 'process raft request' (duration: 97.02052ms)","trace[714503954] 'compare' (duration: 112.853058ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:56.454212Z","caller":"traceutil/trace.go:171","msg":"trace[1528231862] transaction","detail":"{read_only:false; response_revision:25584; number_of_response:1; }","duration":"214.143976ms","start":"2026-05-12T17:54:56.240052Z","end":"2026-05-12T17:54:56.454196Z","steps":["trace[1528231862] 'process raft request' (duration: 213.996364ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:54:56.454352Z","caller":"traceutil/trace.go:171","msg":"trace[2025832920] transaction","detail":"{read_only:false; response_revision:25585; number_of_response:1; }","duration":"202.379666ms","start":"2026-05-12T17:54:56.251963Z","end":"2026-05-12T17:54:56.454343Z","steps":["trace[2025832920] 'process raft request' (duration: 202.196283ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:54:56.67708Z","caller":"traceutil/trace.go:171","msg":"trace[1228140460] transaction","detail":"{read_only:false; response_revision:25586; number_of_response:1; }","duration":"181.915773ms","start":"2026-05-12T17:54:56.495136Z","end":"2026-05-12T17:54:56.677052Z","steps":["trace[1228140460] 'process raft request' (duration: 104.430069ms)","trace[1228140460] 'compare' (duration: 77.28722ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:57.055933Z","caller":"traceutil/trace.go:171","msg":"trace[145484738] transaction","detail":"{read_only:false; response_revision:25587; number_of_response:1; }","duration":"109.202159ms","start":"2026-05-12T17:54:56.946702Z","end":"2026-05-12T17:54:57.055904Z","steps":["trace[145484738] 'process raft request' (duration: 109.027666ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:54:57.235459Z","caller":"traceutil/trace.go:171","msg":"trace[1542895795] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"281.482501ms","start":"2026-05-12T17:54:56.953941Z","end":"2026-05-12T17:54:57.235423Z","steps":["trace[1542895795] 'process raft request' (duration: 218.194544ms)","trace[1542895795] 'compare' (duration: 62.9284ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:57.235468Z","caller":"traceutil/trace.go:171","msg":"trace[1505683804] linearizableReadLoop","detail":"{readStateIndex:26430; appliedIndex:26427; }","duration":"260.805303ms","start":"2026-05-12T17:54:56.974637Z","end":"2026-05-12T17:54:57.235442Z","steps":["trace[1505683804] 'read index received' (duration: 81.198734ms)","trace[1505683804] 'applied index is now lower than readState.Index' (duration: 179.605319ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:54:57.235584Z","caller":"traceutil/trace.go:171","msg":"trace[2050114897] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"281.545111ms","start":"2026-05-12T17:54:56.954014Z","end":"2026-05-12T17:54:57.235559Z","steps":["trace[2050114897] 'process raft request' (duration: 281.291127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:54:57.23573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.078978ms","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-12T17:54:57.235771Z","caller":"traceutil/trace.go:171","msg":"trace[252448101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25589; }","duration":"261.16871ms","start":"2026-05-12T17:54:56.974592Z","end":"2026-05-12T17:54:57.235761Z","steps":["trace[252448101] 'agreement among raft nodes before linearized reading' (duration: 260.930386ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:03.722238Z","caller":"traceutil/trace.go:171","msg":"trace[1185475015] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"199.644925ms","start":"2026-05-12T17:55:03.522568Z","end":"2026-05-12T17:55:03.722213Z","steps":["trace[1185475015] 'process raft request' (duration: 199.460472ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:03.73653Z","caller":"traceutil/trace.go:171","msg":"trace[1151331183] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"210.806445ms","start":"2026-05-12T17:55:03.525699Z","end":"2026-05-12T17:55:03.736506Z","steps":["trace[1151331183] 'process raft request' (duration: 210.717423ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:03.736545Z","caller":"traceutil/trace.go:171","msg":"trace[1900267808] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"211.704073ms","start":"2026-05-12T17:55:03.52481Z","end":"2026-05-12T17:55:03.736514Z","steps":["trace[1900267808] 'process raft request' (duration: 209.022212ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:03.736734Z","caller":"traceutil/trace.go:171","msg":"trace[1407481916] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"189.74333ms","start":"2026-05-12T17:55:03.546939Z","end":"2026-05-12T17:55:03.736682Z","steps":["trace[1407481916] 'process raft request' (duration: 189.525626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:03.970647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.010175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:03.97073Z","caller":"traceutil/trace.go:171","msg":"trace[1680359644] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:25651; }","duration":"118.165748ms","start":"2026-05-12T17:55:03.852544Z","end":"2026-05-12T17:55:03.97071Z","steps":["trace[1680359644] 'count revisions from in-memory index tree' (duration: 117.839771ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:04.340198Z","caller":"traceutil/trace.go:171","msg":"trace[725417708] transaction","detail":"{read_only:false; response_revision:25652; number_of_response:1; }","duration":"158.126496ms","start":"2026-05-12T17:55:04.182048Z","end":"2026-05-12T17:55:04.340175Z","steps":["trace[725417708] 'process raft request' (duration: 157.953393ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:04.567523Z","caller":"traceutil/trace.go:171","msg":"trace[1258571627] transaction","detail":"{read_only:false; response_revision:25653; number_of_response:1; }","duration":"100.73017ms","start":"2026-05-12T17:55:04.466774Z","end":"2026-05-12T17:55:04.567504Z","steps":["trace[1258571627] 'process raft request' (duration: 100.579647ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:04.751924Z","caller":"traceutil/trace.go:171","msg":"trace[1769833237] transaction","detail":"{read_only:false; response_revision:25656; number_of_response:1; }","duration":"196.968634ms","start":"2026-05-12T17:55:04.554939Z","end":"2026-05-12T17:55:04.751908Z","steps":["trace[1769833237] 'process raft request' (duration: 196.934824ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:04.752049Z","caller":"traceutil/trace.go:171","msg":"trace[771047700] transaction","detail":"{read_only:false; response_revision:25654; number_of_response:1; }","duration":"272.542204ms","start":"2026-05-12T17:55:04.479489Z","end":"2026-05-12T17:55:04.752031Z","steps":["trace[771047700] 'process raft request' (duration: 272.256617ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:04.752121Z","caller":"traceutil/trace.go:171","msg":"trace[2063027081] transaction","detail":"{read_only:false; response_revision:25655; number_of_response:1; }","duration":"270.969924ms","start":"2026-05-12T17:55:04.481112Z","end":"2026-05-12T17:55:04.752082Z","steps":["trace[2063027081] 'process raft request' (duration: 270.733908ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:04.810232Z","caller":"traceutil/trace.go:171","msg":"trace[570016696] transaction","detail":"{read_only:false; response_revision:25657; number_of_response:1; }","duration":"104.966968ms","start":"2026-05-12T17:55:04.70524Z","end":"2026-05-12T17:55:04.810207Z","steps":["trace[570016696] 'process raft request' (duration: 104.840036ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:05.307467Z","caller":"traceutil/trace.go:171","msg":"trace[552827554] linearizableReadLoop","detail":"{readStateIndex:26500; appliedIndex:26499; }","duration":"106.437907ms","start":"2026-05-12T17:55:05.20101Z","end":"2026-05-12T17:55:05.307448Z","steps":["trace[552827554] 'read index received' (duration: 106.330305ms)","trace[552827554] 'applied index is now lower than readState.Index' (duration: 106.212µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:55:05.307604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.58781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:05.307595Z","caller":"traceutil/trace.go:171","msg":"trace[1628830221] transaction","detail":"{read_only:false; response_revision:25658; number_of_response:1; }","duration":"219.101451ms","start":"2026-05-12T17:55:05.088456Z","end":"2026-05-12T17:55:05.307558Z","steps":["trace[1628830221] 'process raft request' (duration: 218.856716ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:05.307667Z","caller":"traceutil/trace.go:171","msg":"trace[728381226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25658; }","duration":"106.694091ms","start":"2026-05-12T17:55:05.200959Z","end":"2026-05-12T17:55:05.307653Z","steps":["trace[728381226] 'agreement among raft nodes before linearized reading' (duration: 106.59668ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:11.458797Z","caller":"traceutil/trace.go:171","msg":"trace[1384998549] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"216.869299ms","start":"2026-05-12T17:55:11.241903Z","end":"2026-05-12T17:55:11.458772Z","steps":["trace[1384998549] 'process raft request' (duration: 216.710656ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:11.458906Z","caller":"traceutil/trace.go:171","msg":"trace[1241691891] linearizableReadLoop","detail":"{readStateIndex:26558; appliedIndex:26558; }","duration":"211.294865ms","start":"2026-05-12T17:55:11.247578Z","end":"2026-05-12T17:55:11.458873Z","steps":["trace[1241691891] 'read index received' (duration: 211.269124ms)","trace[1241691891] 'applied index is now lower than readState.Index' (duration: 22.521µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:55:11.459195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.58624ms","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-12T17:55:11.459246Z","caller":"traceutil/trace.go:171","msg":"trace[902445735] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:25714; }","duration":"211.687572ms","start":"2026-05-12T17:55:11.247544Z","end":"2026-05-12T17:55:11.459232Z","steps":["trace[902445735] 'agreement among raft nodes before linearized reading' (duration: 211.503669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:11.472289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.156615ms","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-12T17:55:11.472267Z","caller":"traceutil/trace.go:171","msg":"trace[1024438489] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"133.618055ms","start":"2026-05-12T17:55:11.338621Z","end":"2026-05-12T17:55:11.472239Z","steps":["trace[1024438489] 'process raft request' (duration: 133.435762ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:11.472331Z","caller":"traceutil/trace.go:171","msg":"trace[1396434262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25715; }","duration":"135.241257ms","start":"2026-05-12T17:55:11.337079Z","end":"2026-05-12T17:55:11.47232Z","steps":["trace[1396434262] 'agreement among raft nodes before linearized reading' (duration: 135.100304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:11.472341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.368531ms","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-12T17:55:11.472397Z","caller":"traceutil/trace.go:171","msg":"trace[361019428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25715; }","duration":"126.476992ms","start":"2026-05-12T17:55:11.345907Z","end":"2026-05-12T17:55:11.472384Z","steps":["trace[361019428] 'agreement among raft nodes before linearized reading' (duration: 126.284669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:11.472435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.215315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T17:55:11.472465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.975808ms","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-12T17:55:11.472499Z","caller":"traceutil/trace.go:171","msg":"trace[1828602499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25715; }","duration":"128.318277ms","start":"2026-05-12T17:55:11.344166Z","end":"2026-05-12T17:55:11.472485Z","steps":["trace[1828602499] 'agreement among raft nodes before linearized reading' (duration: 128.090202ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:11.472521Z","caller":"traceutil/trace.go:171","msg":"trace[564190647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25715; }","duration":"130.08321ms","start":"2026-05-12T17:55:11.342424Z","end":"2026-05-12T17:55:11.472508Z","steps":["trace[564190647] 'agreement among raft nodes before linearized reading' (duration: 129.917476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:11.741741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.050316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:55:11.741818Z","caller":"traceutil/trace.go:171","msg":"trace[1053263390] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"265.743556ms","start":"2026-05-12T17:55:11.476063Z","end":"2026-05-12T17:55:11.741807Z","steps":["trace[1053263390] 'process raft request' (duration: 124.570637ms)","trace[1053263390] 'compare' (duration: 140.972675ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:11.784143Z","caller":"traceutil/trace.go:171","msg":"trace[538696677] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"308.037679ms","start":"2026-05-12T17:55:11.476065Z","end":"2026-05-12T17:55:11.784102Z","steps":["trace[538696677] 'process raft request' (duration: 307.666342ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:11.784258Z","caller":"traceutil/trace.go:171","msg":"trace[1370415977] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"308.13827ms","start":"2026-05-12T17:55:11.476093Z","end":"2026-05-12T17:55:11.784232Z","steps":["trace[1370415977] 'process raft request' (duration: 307.777624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:11.784345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:11.476048Z","time spent":"308.181121ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:11.784403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:11.476071Z","time spent":"308.255173ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:55:11.784385Z","caller":"traceutil/trace.go:171","msg":"trace[1111335767] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"308.251993ms","start":"2026-05-12T17:55:11.476105Z","end":"2026-05-12T17:55:11.784357Z","steps":["trace[1111335767] 'process raft request' (duration: 308.079579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:11.784576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:11.476082Z","time spent":"308.445177ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:55:12.12358Z","caller":"traceutil/trace.go:171","msg":"trace[1907827101] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"357.164131ms","start":"2026-05-12T17:55:11.766389Z","end":"2026-05-12T17:55:12.123553Z","steps":["trace[1907827101] 'process raft request' (duration: 356.827265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:12.123726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.623314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:55:12.123771Z","caller":"traceutil/trace.go:171","msg":"trace[811012706] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:25722; }","duration":"353.719746ms","start":"2026-05-12T17:55:11.770041Z","end":"2026-05-12T17:55:12.123761Z","steps":["trace[811012706] 'agreement among raft nodes before linearized reading' (duration: 353.625654ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:12.123633Z","caller":"traceutil/trace.go:171","msg":"trace[1579155066] linearizableReadLoop","detail":"{readStateIndex:26566; appliedIndex:26561; }","duration":"353.536982ms","start":"2026-05-12T17:55:11.770077Z","end":"2026-05-12T17:55:12.123614Z","steps":["trace[1579155066] 'read index received' (duration: 13.808619ms)","trace[1579155066] 'applied index is now lower than readState.Index' (duration: 339.727173ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:12.12377Z","caller":"traceutil/trace.go:171","msg":"trace[86482809] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"357.064458ms","start":"2026-05-12T17:55:11.766664Z","end":"2026-05-12T17:55:12.123728Z","steps":["trace[86482809] 'process raft request' (duration: 356.848375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:12.123803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:11.76637Z","time spent":"357.325894ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:12.123805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:11.770028Z","time spent":"353.767647ms","remote":"127.0.0.1:46944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":32,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:55:12.123949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.134576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:12.124013Z","caller":"traceutil/trace.go:171","msg":"trace[110778477] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25722; }","duration":"166.239018ms","start":"2026-05-12T17:55:11.957762Z","end":"2026-05-12T17:55:12.124001Z","steps":["trace[110778477] 'agreement among raft nodes before linearized reading' (duration: 166.137187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:12.123964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:11.76665Z","time spent":"357.205711ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:55:12.124474Z","caller":"traceutil/trace.go:171","msg":"trace[1785418048] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"358.011137ms","start":"2026-05-12T17:55:11.766441Z","end":"2026-05-12T17:55:12.124452Z","steps":["trace[1785418048] 'process raft request' (duration: 357.022499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:12.124558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:11.766422Z","time spent":"358.095758ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:12.638369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.45019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:12.638443Z","caller":"traceutil/trace.go:171","msg":"trace[58939371] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25723; }","duration":"120.579712ms","start":"2026-05-12T17:55:12.517849Z","end":"2026-05-12T17:55:12.638429Z","steps":["trace[58939371] 'count revisions from in-memory index tree' (duration: 120.370508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:14.651775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.781661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:14.651842Z","caller":"traceutil/trace.go:171","msg":"trace[1213543831] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25739; }","duration":"140.896623ms","start":"2026-05-12T17:55:14.510927Z","end":"2026-05-12T17:55:14.651824Z","steps":["trace[1213543831] 'count revisions from in-memory index tree' (duration: 140.702539ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:14.885592Z","caller":"traceutil/trace.go:171","msg":"trace[2011163762] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"113.51551ms","start":"2026-05-12T17:55:14.77205Z","end":"2026-05-12T17:55:14.885565Z","steps":["trace[2011163762] 'process raft request' (duration: 35.985486ms)","trace[2011163762] 'compare' (duration: 77.27472ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:14.984227Z","caller":"traceutil/trace.go:171","msg":"trace[630718781] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"195.57672ms","start":"2026-05-12T17:55:14.78863Z","end":"2026-05-12T17:55:14.984206Z","steps":["trace[630718781] 'process raft request' (duration: 195.423247ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:14.984396Z","caller":"traceutil/trace.go:171","msg":"trace[1253765685] linearizableReadLoop","detail":"{readStateIndex:26587; appliedIndex:26585; }","duration":"150.926561ms","start":"2026-05-12T17:55:14.833416Z","end":"2026-05-12T17:55:14.984342Z","steps":["trace[1253765685] 'read index received' (duration: 150.720028ms)","trace[1253765685] 'applied index is now lower than readState.Index' (duration: 204.443µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:14.984433Z","caller":"traceutil/trace.go:171","msg":"trace[472551503] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"182.430623ms","start":"2026-05-12T17:55:14.801991Z","end":"2026-05-12T17:55:14.984422Z","steps":["trace[472551503] 'process raft request' (duration: 182.178838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:14.984514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.081585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:14.98456Z","caller":"traceutil/trace.go:171","msg":"trace[1564144067] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25743; }","duration":"151.167096ms","start":"2026-05-12T17:55:14.833381Z","end":"2026-05-12T17:55:14.984548Z","steps":["trace[1564144067] 'agreement among raft nodes before linearized reading' (duration: 151.089165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:15.125472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.207675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:55:15.1256Z","caller":"traceutil/trace.go:171","msg":"trace[259262455] linearizableReadLoop","detail":"{readStateIndex:26588; appliedIndex:26587; }","duration":"141.109488ms","start":"2026-05-12T17:55:14.984467Z","end":"2026-05-12T17:55:15.125577Z","steps":["trace[259262455] 'read index received' (duration: 36.648267ms)","trace[259262455] 'applied index is now lower than readState.Index' (duration: 104.45899ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:15.125652Z","caller":"traceutil/trace.go:171","msg":"trace[565536142] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"268.002528ms","start":"2026-05-12T17:55:14.857613Z","end":"2026-05-12T17:55:15.125615Z","steps":["trace[565536142] 'process raft request' (duration: 163.570258ms)","trace[565536142] 'compare' (duration: 104.098333ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:55:15.125715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.903468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:55:15.125749Z","caller":"traceutil/trace.go:171","msg":"trace[1431123985] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25744; }","duration":"145.952968ms","start":"2026-05-12T17:55:14.979782Z","end":"2026-05-12T17:55:15.125735Z","steps":["trace[1431123985] 'agreement among raft nodes before linearized reading' (duration: 145.871577ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:16.138462Z","caller":"traceutil/trace.go:171","msg":"trace[850603157] linearizableReadLoop","detail":"{readStateIndex:26594; appliedIndex:26593; }","duration":"341.035528ms","start":"2026-05-12T17:55:15.797408Z","end":"2026-05-12T17:55:16.138444Z","steps":["trace[850603157] 'read index received' (duration: 340.883805ms)","trace[850603157] 'applied index is now lower than readState.Index' (duration: 150.863µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:16.138527Z","caller":"traceutil/trace.go:171","msg":"trace[322529615] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"354.256005ms","start":"2026-05-12T17:55:15.784255Z","end":"2026-05-12T17:55:16.138511Z","steps":["trace[322529615] 'process raft request' (duration: 354.056462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:16.138672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:15.784237Z","time spent":"354.326747ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:16.138688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.275802ms","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-12T17:55:16.138724Z","caller":"traceutil/trace.go:171","msg":"trace[1812761020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25749; }","duration":"341.340924ms","start":"2026-05-12T17:55:15.797372Z","end":"2026-05-12T17:55:16.138713Z","steps":["trace[1812761020] 'agreement among raft nodes before linearized reading' (duration: 341.171751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:16.138753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:15.797358Z","time spent":"341.388435ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T17:55:16.13984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.703645ms","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-12T17:55:16.139882Z","caller":"traceutil/trace.go:171","msg":"trace[1282454976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25752; }","duration":"338.762845ms","start":"2026-05-12T17:55:15.801111Z","end":"2026-05-12T17:55:16.139874Z","steps":["trace[1282454976] 'agreement among raft nodes before linearized reading' (duration: 338.625633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:16.139907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:15.801106Z","time spent":"338.795016ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T17:55:16.140359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.079341ms","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-12T17:55:16.140398Z","caller":"traceutil/trace.go:171","msg":"trace[604287168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25752; }","duration":"339.151183ms","start":"2026-05-12T17:55:15.801237Z","end":"2026-05-12T17:55:16.140388Z","steps":["trace[604287168] 'agreement among raft nodes before linearized reading' (duration: 339.057511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:16.140433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:15.80122Z","time spent":"339.207294ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T17:55:16.140622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.870574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:16.140664Z","caller":"traceutil/trace.go:171","msg":"trace[583590426] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25752; }","duration":"204.943255ms","start":"2026-05-12T17:55:15.935707Z","end":"2026-05-12T17:55:16.14065Z","steps":["trace[583590426] 'agreement among raft nodes before linearized reading' (duration: 204.882694ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:18.245742Z","caller":"traceutil/trace.go:171","msg":"trace[1795018717] transaction","detail":"{read_only:false; response_revision:25831; number_of_response:1; }","duration":"100.27952ms","start":"2026-05-12T17:55:18.145425Z","end":"2026-05-12T17:55:18.245705Z","steps":["trace[1795018717] 'process raft request' (duration: 80.108322ms)","trace[1795018717] 'compare' (duration: 19.674969ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:18.297001Z","caller":"traceutil/trace.go:171","msg":"trace[10629113] linearizableReadLoop","detail":"{readStateIndex:26678; appliedIndex:26675; }","duration":"149.984744ms","start":"2026-05-12T17:55:18.146992Z","end":"2026-05-12T17:55:18.296976Z","steps":["trace[10629113] 'read index received' (duration: 78.528923ms)","trace[10629113] 'applied index is now lower than readState.Index' (duration: 71.454661ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:18.297147Z","caller":"traceutil/trace.go:171","msg":"trace[1719839576] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"150.738138ms","start":"2026-05-12T17:55:18.146365Z","end":"2026-05-12T17:55:18.297103Z","steps":["trace[1719839576] 'process raft request' (duration: 150.465233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:18.297252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.710852ms","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-12T17:55:18.297236Z","caller":"traceutil/trace.go:171","msg":"trace[77674835] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"150.81138ms","start":"2026-05-12T17:55:18.146381Z","end":"2026-05-12T17:55:18.297192Z","steps":["trace[77674835] 'process raft request' (duration: 150.551315ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:18.297302Z","caller":"traceutil/trace.go:171","msg":"trace[1504138633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25833; }","duration":"147.809823ms","start":"2026-05-12T17:55:18.149478Z","end":"2026-05-12T17:55:18.297288Z","steps":["trace[1504138633] 'agreement among raft nodes before linearized reading' (duration: 147.631709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:18.297329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.295ms","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-12T17:55:18.297405Z","caller":"traceutil/trace.go:171","msg":"trace[298827626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25833; }","duration":"150.414752ms","start":"2026-05-12T17:55:18.14697Z","end":"2026-05-12T17:55:18.297385Z","steps":["trace[298827626] 'agreement among raft nodes before linearized reading' (duration: 150.143547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:18.297454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.129251ms","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-12T17:55:18.297521Z","caller":"traceutil/trace.go:171","msg":"trace[2137238141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25833; }","duration":"147.231103ms","start":"2026-05-12T17:55:18.150271Z","end":"2026-05-12T17:55:18.297502Z","steps":["trace[2137238141] 'agreement among raft nodes before linearized reading' (duration: 146.994108ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:25.03162Z","caller":"traceutil/trace.go:171","msg":"trace[574404201] transaction","detail":"{read_only:false; response_revision:25906; number_of_response:1; }","duration":"128.092003ms","start":"2026-05-12T17:55:24.903509Z","end":"2026-05-12T17:55:25.031601Z","steps":["trace[574404201] 'process raft request' (duration: 127.844039ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:25.3579Z","caller":"traceutil/trace.go:171","msg":"trace[14061439] linearizableReadLoop","detail":"{readStateIndex:26755; appliedIndex:26753; }","duration":"275.500918ms","start":"2026-05-12T17:55:25.082374Z","end":"2026-05-12T17:55:25.357875Z","steps":["trace[14061439] 'read index received' (duration: 275.234433ms)","trace[14061439] 'applied index is now lower than readState.Index' (duration: 265.225µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:25.35791Z","caller":"traceutil/trace.go:171","msg":"trace[50641588] transaction","detail":"{read_only:false; response_revision:25907; number_of_response:1; }","duration":"343.230099ms","start":"2026-05-12T17:55:25.014643Z","end":"2026-05-12T17:55:25.357873Z","steps":["trace[50641588] 'process raft request' (duration: 342.966254ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:25.358011Z","caller":"traceutil/trace.go:171","msg":"trace[582333188] transaction","detail":"{read_only:false; response_revision:25908; number_of_response:1; }","duration":"340.831114ms","start":"2026-05-12T17:55:25.017169Z","end":"2026-05-12T17:55:25.358Z","steps":["trace[582333188] 'process raft request' (duration: 340.636881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:25.358075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:25.014622Z","time spent":"343.367791ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:25.358086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:25.017156Z","time spent":"340.878655ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:25.35821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.843275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-12T17:55:25.358255Z","caller":"traceutil/trace.go:171","msg":"trace[1441365103] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25908; }","duration":"275.914956ms","start":"2026-05-12T17:55:25.082326Z","end":"2026-05-12T17:55:25.358241Z","steps":["trace[1441365103] 'agreement among raft nodes before linearized reading' (duration: 275.784373ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:25.369769Z","caller":"traceutil/trace.go:171","msg":"trace[1671151183] transaction","detail":"{read_only:false; response_revision:25909; number_of_response:1; }","duration":"205.935763ms","start":"2026-05-12T17:55:25.163802Z","end":"2026-05-12T17:55:25.369738Z","steps":["trace[1671151183] 'process raft request' (duration: 205.054707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:45.826479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.592951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:55:45.826869Z","caller":"traceutil/trace.go:171","msg":"trace[34272355] transaction","detail":"{read_only:false; response_revision:26076; number_of_response:1; }","duration":"227.931466ms","start":"2026-05-12T17:55:45.598915Z","end":"2026-05-12T17:55:45.826847Z","steps":["trace[34272355] 'process raft request' (duration: 227.881115ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:45.827062Z","caller":"traceutil/trace.go:171","msg":"trace[942158398] transaction","detail":"{read_only:false; response_revision:26073; number_of_response:1; }","duration":"396.835575ms","start":"2026-05-12T17:55:45.430199Z","end":"2026-05-12T17:55:45.827035Z","steps":["trace[942158398] 'process raft request' (duration: 396.387847ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:45.827089Z","caller":"traceutil/trace.go:171","msg":"trace[529056354] transaction","detail":"{read_only:false; response_revision:26075; number_of_response:1; }","duration":"330.031162ms","start":"2026-05-12T17:55:45.497035Z","end":"2026-05-12T17:55:45.827066Z","steps":["trace[529056354] 'process raft request' (duration: 329.716066ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:45.827081Z","caller":"traceutil/trace.go:171","msg":"trace[1100572253] transaction","detail":"{read_only:false; response_revision:26074; number_of_response:1; }","duration":"388.697342ms","start":"2026-05-12T17:55:45.438348Z","end":"2026-05-12T17:55:45.827045Z","steps":["trace[1100572253] 'process raft request' (duration: 388.350626ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:45.827111Z","caller":"traceutil/trace.go:171","msg":"trace[161745886] transaction","detail":"{read_only:false; response_revision:26072; number_of_response:1; }","duration":"398.922904ms","start":"2026-05-12T17:55:45.428152Z","end":"2026-05-12T17:55:45.827075Z","steps":["trace[161745886] 'process raft request' (duration: 204.611289ms)","trace[161745886] 'compare' (duration: 193.365117ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:55:45.827194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:45.430175Z","time spent":"396.948458ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:45.827196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:45.49699Z","time spent":"330.143154ms","remote":"127.0.0.1:46728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:45.827222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:45.438323Z","time spent":"388.822915ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:45.827257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:45.428121Z","time spent":"399.054687ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:46.082328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.768319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-12T17:55:46.082346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.271983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:55:46.082393Z","caller":"traceutil/trace.go:171","msg":"trace[1764763626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26076; }","duration":"134.8685ms","start":"2026-05-12T17:55:45.94751Z","end":"2026-05-12T17:55:46.082379Z","steps":["trace[1764763626] 'range keys from in-memory index tree' (duration: 134.589625ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:46.082448Z","caller":"traceutil/trace.go:171","msg":"trace[857977229] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:26076; }","duration":"115.420185ms","start":"2026-05-12T17:55:45.966999Z","end":"2026-05-12T17:55:46.082419Z","steps":["trace[857977229] 'count revisions from in-memory index tree' (duration: 115.164541ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:46.290715Z","caller":"traceutil/trace.go:171","msg":"trace[639047650] transaction","detail":"{read_only:false; response_revision:26077; number_of_response:1; }","duration":"222.278141ms","start":"2026-05-12T17:55:46.068419Z","end":"2026-05-12T17:55:46.290697Z","steps":["trace[639047650] 'process raft request' (duration: 222.154758ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:46.376519Z","caller":"traceutil/trace.go:171","msg":"trace[2088972793] linearizableReadLoop","detail":"{readStateIndex:26931; appliedIndex:26929; }","duration":"155.842613ms","start":"2026-05-12T17:55:46.220659Z","end":"2026-05-12T17:55:46.376501Z","steps":["trace[2088972793] 'read index received' (duration: 69.8935ms)","trace[2088972793] 'applied index is now lower than readState.Index' (duration: 85.948183ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:46.37664Z","caller":"traceutil/trace.go:171","msg":"trace[1329560253] transaction","detail":"{read_only:false; response_revision:26078; number_of_response:1; }","duration":"291.0259ms","start":"2026-05-12T17:55:46.085582Z","end":"2026-05-12T17:55:46.376608Z","steps":["trace[1329560253] 'process raft request' (duration: 290.813746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:46.376709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.033086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:46.376766Z","caller":"traceutil/trace.go:171","msg":"trace[1260677319] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26078; }","duration":"156.159679ms","start":"2026-05-12T17:55:46.22059Z","end":"2026-05-12T17:55:46.37675Z","steps":["trace[1260677319] 'agreement among raft nodes before linearized reading' (duration: 156.044346ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:46.663811Z","caller":"traceutil/trace.go:171","msg":"trace[1219415060] linearizableReadLoop","detail":"{readStateIndex:26932; appliedIndex:26931; }","duration":"287.160298ms","start":"2026-05-12T17:55:46.376626Z","end":"2026-05-12T17:55:46.663787Z","steps":["trace[1219415060] 'read index received' (duration: 287.019896ms)","trace[1219415060] 'applied index is now lower than readState.Index' (duration: 139.392µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:46.663932Z","caller":"traceutil/trace.go:171","msg":"trace[1912353345] transaction","detail":"{read_only:false; response_revision:26079; number_of_response:1; }","duration":"312.639405ms","start":"2026-05-12T17:55:46.351257Z","end":"2026-05-12T17:55:46.663896Z","steps":["trace[1912353345] 'process raft request' (duration: 312.384701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:46.664049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.928964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18416"} {"level":"info","ts":"2026-05-12T17:55:46.664086Z","caller":"traceutil/trace.go:171","msg":"trace[858769690] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:26079; }","duration":"368.006275ms","start":"2026-05-12T17:55:46.29607Z","end":"2026-05-12T17:55:46.664076Z","steps":["trace[858769690] 'agreement among raft nodes before linearized reading' (duration: 367.828201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:46.664126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:46.296055Z","time spent":"368.057915ms","remote":"127.0.0.1:46830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18440,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-05-12T17:55:46.664182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:46.351163Z","time spent":"312.9065ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:55:46.675474Z","caller":"traceutil/trace.go:171","msg":"trace[1423531221] transaction","detail":"{read_only:false; response_revision:26080; number_of_response:1; }","duration":"268.275083ms","start":"2026-05-12T17:55:46.407182Z","end":"2026-05-12T17:55:46.675457Z","steps":["trace[1423531221] 'process raft request' (duration: 268.055109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:46.675475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.850541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:46.675539Z","caller":"traceutil/trace.go:171","msg":"trace[837043833] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26081; }","duration":"294.961183ms","start":"2026-05-12T17:55:46.380563Z","end":"2026-05-12T17:55:46.675524Z","steps":["trace[837043833] 'agreement among raft nodes before linearized reading' (duration: 294.837181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:46.675568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.033361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-12T17:55:46.675571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.898901ms","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-12T17:55:46.675618Z","caller":"traceutil/trace.go:171","msg":"trace[625705331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26081; }","duration":"145.088142ms","start":"2026-05-12T17:55:46.530518Z","end":"2026-05-12T17:55:46.675607Z","steps":["trace[625705331] 'agreement among raft nodes before linearized reading' (duration: 144.961159ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:46.67563Z","caller":"traceutil/trace.go:171","msg":"trace[916899942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26081; }","duration":"119.003603ms","start":"2026-05-12T17:55:46.55661Z","end":"2026-05-12T17:55:46.675614Z","steps":["trace[916899942] 'agreement among raft nodes before linearized reading' (duration: 118.815069ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:46.675622Z","caller":"traceutil/trace.go:171","msg":"trace[2117829169] transaction","detail":"{read_only:false; response_revision:26081; number_of_response:1; }","duration":"266.666523ms","start":"2026-05-12T17:55:46.408928Z","end":"2026-05-12T17:55:46.675595Z","steps":["trace[2117829169] 'process raft request' (duration: 266.423298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:46.675723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.675815ms","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-12T17:55:46.675741Z","caller":"traceutil/trace.go:171","msg":"trace[1034584770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26081; }","duration":"144.704785ms","start":"2026-05-12T17:55:46.53103Z","end":"2026-05-12T17:55:46.675735Z","steps":["trace[1034584770] 'agreement among raft nodes before linearized reading' (duration: 144.651004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:46.675748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.290782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:46.675795Z","caller":"traceutil/trace.go:171","msg":"trace[2000633882] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26081; }","duration":"293.331233ms","start":"2026-05-12T17:55:46.382449Z","end":"2026-05-12T17:55:46.67578Z","steps":["trace[2000633882] 'agreement among raft nodes before linearized reading' (duration: 293.270122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:46.675896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.467304ms","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-12T17:55:46.675935Z","caller":"traceutil/trace.go:171","msg":"trace[429243008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26081; }","duration":"149.548546ms","start":"2026-05-12T17:55:46.526376Z","end":"2026-05-12T17:55:46.675924Z","steps":["trace[429243008] 'agreement among raft nodes before linearized reading' (duration: 149.401553ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:46.841872Z","caller":"traceutil/trace.go:171","msg":"trace[1725016684] transaction","detail":"{read_only:false; response_revision:26082; number_of_response:1; }","duration":"162.079541ms","start":"2026-05-12T17:55:46.679752Z","end":"2026-05-12T17:55:46.841832Z","steps":["trace[1725016684] 'process raft request' (duration: 130.943287ms)","trace[1725016684] 'compare' (duration: 30.732316ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:47.112072Z","caller":"traceutil/trace.go:171","msg":"trace[2065607626] transaction","detail":"{read_only:false; response_revision:26083; number_of_response:1; }","duration":"431.957184ms","start":"2026-05-12T17:55:46.680079Z","end":"2026-05-12T17:55:47.112036Z","steps":["trace[2065607626] 'process raft request' (duration: 395.974529ms)","trace[2065607626] 'compare' (duration: 35.372963ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:55:47.112086Z","caller":"traceutil/trace.go:171","msg":"trace[693604664] transaction","detail":"{read_only:false; response_revision:26084; number_of_response:1; }","duration":"431.514195ms","start":"2026-05-12T17:55:46.680546Z","end":"2026-05-12T17:55:47.11206Z","steps":["trace[693604664] 'process raft request' (duration: 431.345152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:47.112242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:46.680056Z","time spent":"432.096106ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:55:47.112243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:46.680521Z","time spent":"431.638038ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:55:47.112497Z","caller":"traceutil/trace.go:171","msg":"trace[862128431] transaction","detail":"{read_only:false; response_revision:26087; number_of_response:1; }","duration":"149.3065ms","start":"2026-05-12T17:55:46.962985Z","end":"2026-05-12T17:55:47.112292Z","steps":["trace[862128431] 'process raft request' (duration: 149.26402ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:47.112561Z","caller":"traceutil/trace.go:171","msg":"trace[1167699701] transaction","detail":"{read_only:false; response_revision:26085; number_of_response:1; }","duration":"431.647268ms","start":"2026-05-12T17:55:46.6809Z","end":"2026-05-12T17:55:47.112548Z","steps":["trace[1167699701] 'process raft request' (duration: 431.114208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:47.112622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:46.680884Z","time spent":"431.704109ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:55:47.112671Z","caller":"traceutil/trace.go:171","msg":"trace[428175038] transaction","detail":"{read_only:false; response_revision:26086; number_of_response:1; }","duration":"332.164492ms","start":"2026-05-12T17:55:46.780497Z","end":"2026-05-12T17:55:47.112661Z","steps":["trace[428175038] 'process raft request' (duration: 331.674793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:47.112772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:46.780471Z","time spent":"332.229983ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:55:47.112778Z","caller":"traceutil/trace.go:171","msg":"trace[1868743365] linearizableReadLoop","detail":"{readStateIndex:26938; appliedIndex:26934; }","duration":"430.14525ms","start":"2026-05-12T17:55:46.682623Z","end":"2026-05-12T17:55:47.112768Z","steps":["trace[1868743365] 'read index received' (duration: 128.146354ms)","trace[1868743365] 'applied index is now lower than readState.Index' (duration: 301.997206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:55:47.112871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.242652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:55:47.112919Z","caller":"traceutil/trace.go:171","msg":"trace[2054121827] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26087; }","duration":"430.318544ms","start":"2026-05-12T17:55:46.682591Z","end":"2026-05-12T17:55:47.11291Z","steps":["trace[2054121827] 'agreement among raft nodes before linearized reading' (duration: 430.207672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:47.112955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:46.682572Z","time spent":"430.375854ms","remote":"127.0.0.1:46940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-05-12T17:55:47.113169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.066714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-05-12T17:55:47.113246Z","caller":"traceutil/trace.go:171","msg":"trace[261990659] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26087; }","duration":"377.285558ms","start":"2026-05-12T17:55:46.735936Z","end":"2026-05-12T17:55:47.113221Z","steps":["trace[261990659] 'agreement among raft nodes before linearized reading' (duration: 377.024374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:47.113297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:55:46.735912Z","time spent":"377.37188ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3332,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-12T17:55:47.113346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.722853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-05-12T17:55:47.113426Z","caller":"traceutil/trace.go:171","msg":"trace[882050825] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26087; }","duration":"202.839624ms","start":"2026-05-12T17:55:46.910548Z","end":"2026-05-12T17:55:47.113387Z","steps":["trace[882050825] 'agreement among raft nodes before linearized reading' (duration: 202.655401ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:52.907913Z","caller":"traceutil/trace.go:171","msg":"trace[373875737] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"122.362386ms","start":"2026-05-12T17:55:52.785518Z","end":"2026-05-12T17:55:52.90788Z","steps":["trace[373875737] 'process raft request' (duration: 121.963488ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:55:52.916545Z","caller":"traceutil/trace.go:171","msg":"trace[728062795] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"128.047632ms","start":"2026-05-12T17:55:52.788469Z","end":"2026-05-12T17:55:52.916516Z","steps":["trace[728062795] 'process raft request' (duration: 127.814948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:55:53.080859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.418904ms","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-12T17:55:53.080965Z","caller":"traceutil/trace.go:171","msg":"trace[1329327755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26125; }","duration":"156.637068ms","start":"2026-05-12T17:55:52.924308Z","end":"2026-05-12T17:55:53.080945Z","steps":["trace[1329327755] 'range keys from in-memory index tree' (duration: 156.234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:56:04.138312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.540854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-05-12T17:56:04.138413Z","caller":"traceutil/trace.go:171","msg":"trace[1759278433] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26208; }","duration":"110.690567ms","start":"2026-05-12T17:56:04.027697Z","end":"2026-05-12T17:56:04.138387Z","steps":["trace[1759278433] 'agreement among raft nodes before linearized reading' (duration: 110.472473ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:56:14.415637Z","caller":"traceutil/trace.go:171","msg":"trace[482966396] transaction","detail":"{read_only:false; response_revision:26290; number_of_response:1; }","duration":"248.420691ms","start":"2026-05-12T17:56:14.16693Z","end":"2026-05-12T17:56:14.415351Z","steps":["trace[482966396] 'process raft request' (duration: 248.124986ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:56:14.486344Z","caller":"traceutil/trace.go:171","msg":"trace[188212499] transaction","detail":"{read_only:false; response_revision:26291; number_of_response:1; }","duration":"317.209811ms","start":"2026-05-12T17:56:14.169094Z","end":"2026-05-12T17:56:14.486304Z","steps":["trace[188212499] 'process raft request' (duration: 300.043309ms)","trace[188212499] 'compare' (duration: 16.703104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:56:14.486453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:56:14.169056Z","time spent":"317.336925ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:56:14.486683Z","caller":"traceutil/trace.go:171","msg":"trace[1986871243] transaction","detail":"{read_only:false; response_revision:26292; number_of_response:1; }","duration":"314.560302ms","start":"2026-05-12T17:56:14.172112Z","end":"2026-05-12T17:56:14.486672Z","steps":["trace[1986871243] 'process raft request' (duration: 314.147483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:56:14.48673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:56:14.172087Z","time spent":"314.617983ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:56:14.486723Z","caller":"traceutil/trace.go:171","msg":"trace[1901825680] linearizableReadLoop","detail":"{readStateIndex:27151; appliedIndex:27147; }","duration":"234.044871ms","start":"2026-05-12T17:56:14.25266Z","end":"2026-05-12T17:56:14.486705Z","steps":["trace[1901825680] 'read index received' (duration: 162.534519ms)","trace[1901825680] 'applied index is now lower than readState.Index' (duration: 71.509632ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:56:14.486774Z","caller":"traceutil/trace.go:171","msg":"trace[471445010] transaction","detail":"{read_only:false; response_revision:26293; number_of_response:1; }","duration":"312.34213ms","start":"2026-05-12T17:56:14.174416Z","end":"2026-05-12T17:56:14.486758Z","steps":["trace[471445010] 'process raft request' (duration: 312.187548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:56:14.486808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.157313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:56:14.486829Z","caller":"traceutil/trace.go:171","msg":"trace[793225794] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26293; }","duration":"234.220055ms","start":"2026-05-12T17:56:14.252602Z","end":"2026-05-12T17:56:14.486822Z","steps":["trace[793225794] 'agreement among raft nodes before linearized reading' (duration: 234.182004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:56:14.486876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:56:14.174399Z","time spent":"312.422222ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:56:14.961751Z","caller":"traceutil/trace.go:171","msg":"trace[1416631972] linearizableReadLoop","detail":"{readStateIndex:27153; appliedIndex:27152; }","duration":"127.015792ms","start":"2026-05-12T17:56:14.83471Z","end":"2026-05-12T17:56:14.961726Z","steps":["trace[1416631972] 'read index received' (duration: 126.86166ms)","trace[1416631972] 'applied index is now lower than readState.Index' (duration: 152.972µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:56:14.961902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.161475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:56:14.961876Z","caller":"traceutil/trace.go:171","msg":"trace[1375221700] transaction","detail":"{read_only:false; response_revision:26295; number_of_response:1; }","duration":"156.393474ms","start":"2026-05-12T17:56:14.805411Z","end":"2026-05-12T17:56:14.961804Z","steps":["trace[1375221700] 'process raft request' (duration: 156.141559ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:56:14.962009Z","caller":"traceutil/trace.go:171","msg":"trace[566749767] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:26295; }","duration":"127.295278ms","start":"2026-05-12T17:56:14.834661Z","end":"2026-05-12T17:56:14.961956Z","steps":["trace[566749767] 'agreement among raft nodes before linearized reading' (duration: 127.159085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:56:15.360146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.731627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:56:15.360223Z","caller":"traceutil/trace.go:171","msg":"trace[1101696677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26298; }","duration":"158.85869ms","start":"2026-05-12T17:56:15.201346Z","end":"2026-05-12T17:56:15.360205Z","steps":["trace[1101696677] 'range keys from in-memory index tree' (duration: 158.655696ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:56:15.677319Z","caller":"traceutil/trace.go:171","msg":"trace[973730219] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"311.484614ms","start":"2026-05-12T17:56:15.365803Z","end":"2026-05-12T17:56:15.677287Z","steps":["trace[973730219] 'process raft request' (duration: 311.427393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:56:15.677495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:56:15.365793Z","time spent":"311.610326ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:56:15.67779Z","caller":"traceutil/trace.go:171","msg":"trace[1172003617] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"312.074065ms","start":"2026-05-12T17:56:15.365697Z","end":"2026-05-12T17:56:15.677771Z","steps":["trace[1172003617] 'process raft request' (duration: 311.498075ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:56:15.677786Z","caller":"traceutil/trace.go:171","msg":"trace[612522373] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"312.065275ms","start":"2026-05-12T17:56:15.36568Z","end":"2026-05-12T17:56:15.677745Z","steps":["trace[612522373] 'process raft request' (duration: 311.474974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:56:15.677887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:56:15.365673Z","time spent":"312.162236ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:56:15.677952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:56:15.365659Z","time spent":"312.203508ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:56:15.678089Z","caller":"traceutil/trace.go:171","msg":"trace[438468034] transaction","detail":"{read_only:false; response_revision:26300; number_of_response:1; }","duration":"312.480353ms","start":"2026-05-12T17:56:15.365598Z","end":"2026-05-12T17:56:15.678078Z","steps":["trace[438468034] 'process raft request' (duration: 311.371033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:56:15.678132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:56:15.365579Z","time spent":"312.529383ms","remote":"127.0.0.1:46924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:56:15.838018Z","caller":"traceutil/trace.go:171","msg":"trace[982470265] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"246.263ms","start":"2026-05-12T17:56:15.59173Z","end":"2026-05-12T17:56:15.837993Z","steps":["trace[982470265] 'process raft request' (duration: 215.550544ms)","trace[982470265] 'compare' (duration: 30.39862ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:56:16.559753Z","caller":"traceutil/trace.go:171","msg":"trace[2011223621] transaction","detail":"{read_only:false; response_revision:26305; number_of_response:1; }","duration":"138.602831ms","start":"2026-05-12T17:56:16.421117Z","end":"2026-05-12T17:56:16.55972Z","steps":["trace[2011223621] 'process raft request' (duration: 138.379816ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:56:16.560619Z","caller":"traceutil/trace.go:171","msg":"trace[646960280] linearizableReadLoop","detail":"{readStateIndex:27164; appliedIndex:27164; }","duration":"131.049079ms","start":"2026-05-12T17:56:16.429551Z","end":"2026-05-12T17:56:16.5606Z","steps":["trace[646960280] 'read index received' (duration: 131.041439ms)","trace[646960280] 'applied index is now lower than readState.Index' (duration: 6.16µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:56:16.560743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.159721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:56:16.560791Z","caller":"traceutil/trace.go:171","msg":"trace[973046337] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26305; }","duration":"131.251343ms","start":"2026-05-12T17:56:16.429525Z","end":"2026-05-12T17:56:16.560776Z","steps":["trace[973046337] 'agreement among raft nodes before linearized reading' (duration: 131.146971ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:56:16.615364Z","caller":"traceutil/trace.go:171","msg":"trace[211459536] transaction","detail":"{read_only:false; response_revision:26306; number_of_response:1; }","duration":"122.118921ms","start":"2026-05-12T17:56:16.493216Z","end":"2026-05-12T17:56:16.615335Z","steps":["trace[211459536] 'process raft request' (duration: 121.912827ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:56:16.615759Z","caller":"traceutil/trace.go:171","msg":"trace[1447137420] transaction","detail":"{read_only:false; response_revision:26307; number_of_response:1; }","duration":"120.590872ms","start":"2026-05-12T17:56:16.495156Z","end":"2026-05-12T17:56:16.615747Z","steps":["trace[1447137420] 'process raft request' (duration: 120.123633ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:56:16.615714Z","caller":"traceutil/trace.go:171","msg":"trace[836295105] transaction","detail":"{read_only:false; response_revision:26308; number_of_response:1; }","duration":"120.236706ms","start":"2026-05-12T17:56:16.495467Z","end":"2026-05-12T17:56:16.615704Z","steps":["trace[836295105] 'process raft request' (duration: 120.084013ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:56:41.898386Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b90.wal"} {"level":"info","ts":"2026-05-12T17:59:22.294739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25210} {"level":"info","ts":"2026-05-12T17:59:22.433484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25210,"took":"133.707971ms","hash":2207993478,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":37552128,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-12T17:59:22.43355Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2207993478,"revision":25210,"compact-revision":21958} {"level":"info","ts":"2026-05-12T18:00:52.404174Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T18:00:52.409978Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-12T18:00:52.410162Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-12T18:04:22.300082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28155} {"level":"info","ts":"2026-05-12T18:04:22.448125Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28155,"took":"143.26955ms","hash":143464054,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":32403456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T18:04:22.448187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":143464054,"revision":28155,"compact-revision":25210} {"level":"info","ts":"2026-05-12T18:09:22.306009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30606} {"level":"info","ts":"2026-05-12T18:09:22.447182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30606,"took":"136.574245ms","hash":3762835963,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":30322688,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T18:09:22.44725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3762835963,"revision":30606,"compact-revision":28155} {"level":"info","ts":"2026-05-12T18:14:22.311486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32897} {"level":"info","ts":"2026-05-12T18:14:22.475575Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32897,"took":"160.338301ms","hash":1810796668,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":30769152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-12T18:14:22.475649Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1810796668,"revision":32897,"compact-revision":30606} {"level":"info","ts":"2026-05-12T18:19:22.31858Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35193} {"level":"info","ts":"2026-05-12T18:19:22.467084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35193,"took":"143.617042ms","hash":166141045,"current-db-size-bytes":57892864,"current-db-size":"58 MB","current-db-size-in-use-bytes":31408128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-12T18:19:22.467172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":166141045,"revision":35193,"compact-revision":32897} {"level":"info","ts":"2026-05-12T18:22:16.853128Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T18:22:16.85825Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-12T18:22:16.858348Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-12T18:24:22.325533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37426} {"level":"info","ts":"2026-05-12T18:24:22.453257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37426,"took":"123.533183ms","hash":2397756188,"current-db-size-bytes":57892864,"current-db-size":"58 MB","current-db-size-in-use-bytes":31358976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-12T18:24:22.453326Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2397756188,"revision":37426,"compact-revision":35193}