{"level":"warn","ts":"2026-10-15T14:18:58.142723Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:18:58.142846Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T14:18:58.142935Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T14:18:58.142946Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-10-15T14:18:58.14297Z","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-10-15T14:18:58.143865Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-10-15T14:18:58.143964Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T14:18:58.148228Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.949914ms"} {"level":"info","ts":"2026-10-15T14:18:58.154502Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-10-15T14:18:58.154652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:18:58.154806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-10-15T14:18:58.15483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:18:58.154855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-10-15T14:18:58.154962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-10-15T14:18:58.160896Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:18:58.163121Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:18:58.164988Z","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-10-15T14:18:58.167727Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:18:58.167905Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:18:58.168069Z","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-10-15T14:18:58.168806Z","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-10-15T14:18:58.168897Z","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-10-15T14:18:58.170132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-10-15T14:18:58.170312Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-10-15T14:18:58.171922Z","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-10-15T14:18:58.172042Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-10-15T14:18:58.172139Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-10-15T14:18:58.172232Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:18:58.172282Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:18:58.65598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:18:58.656093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:18:58.656186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-10-15T14:18:58.656218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:18:58.656234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-10-15T14:18:58.656257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-10-15T14:18:58.656285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-10-15T14:18:58.657689Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:18:58.659018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:18:58.659149Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:18:58.658971Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:18:58.659682Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:18:58.659801Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:18:58.659793Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:18:58.660026Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:18:58.660099Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:18:58.662142Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"info","ts":"2026-10-15T14:18:58.662691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:19:24.791493Z","caller":"traceutil/trace.go:171","msg":"trace[1758843174] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"100.227068ms","start":"2026-10-15T14:19:24.691239Z","end":"2026-10-15T14:19:24.791466Z","steps":["trace[1758843174] 'process raft request' (duration: 100.068404ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:24.905474Z","caller":"traceutil/trace.go:171","msg":"trace[1572942967] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"109.940388ms","start":"2026-10-15T14:19:24.79551Z","end":"2026-10-15T14:19:24.905451Z","steps":["trace[1572942967] 'process raft request' (duration: 100.947934ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:24.905691Z","caller":"traceutil/trace.go:171","msg":"trace[275151291] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"109.689122ms","start":"2026-10-15T14:19:24.79599Z","end":"2026-10-15T14:19:24.90568Z","steps":["trace[275151291] 'process raft request' (duration: 109.421246ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:51.053609Z","caller":"traceutil/trace.go:171","msg":"trace[1289787394] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"123.965736ms","start":"2026-10-15T14:19:50.929621Z","end":"2026-10-15T14:19:51.053586Z","steps":["trace[1289787394] 'read index received' (duration: 123.747671ms)","trace[1289787394] 'applied index is now lower than readState.Index' (duration: 217.155µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:51.053683Z","caller":"traceutil/trace.go:171","msg":"trace[729555992] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"136.442391ms","start":"2026-10-15T14:19:50.917213Z","end":"2026-10-15T14:19:51.053655Z","steps":["trace[729555992] 'process raft request' (duration: 136.211955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:51.054379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.757685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T14:19:51.054449Z","caller":"traceutil/trace.go:171","msg":"trace[367120191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"124.880558ms","start":"2026-10-15T14:19:50.929556Z","end":"2026-10-15T14:19:51.054437Z","steps":["trace[367120191] 'agreement among raft nodes before linearized reading' (duration: 124.152701ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:51.511475Z","caller":"traceutil/trace.go:171","msg":"trace[37559992] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"343.380326ms","start":"2026-10-15T14:19:51.168065Z","end":"2026-10-15T14:19:51.511445Z","steps":["trace[37559992] 'process raft request' (duration: 342.72168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:51.512159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:51.168038Z","time spent":"343.53967ms","remote":"127.0.0.1:37872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:19:52.068501Z","caller":"traceutil/trace.go:171","msg":"trace[1123083119] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"481.846284ms","start":"2026-10-15T14:19:51.586627Z","end":"2026-10-15T14:19:52.068473Z","steps":["trace[1123083119] 'process raft request' (duration: 481.714851ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:52.06852Z","caller":"traceutil/trace.go:171","msg":"trace[562033979] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:654; }","duration":"477.612124ms","start":"2026-10-15T14:19:51.590882Z","end":"2026-10-15T14:19:52.068494Z","steps":["trace[562033979] 'read index received' (duration: 477.604983ms)","trace[562033979] 'applied index is now lower than readState.Index' (duration: 5.961µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:52.068649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:51.586595Z","time spent":"481.988088ms","remote":"127.0.0.1:38274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:19:52.068901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.015174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dkkq5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-15T14:19:52.068973Z","caller":"traceutil/trace.go:171","msg":"trace[801915692] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dkkq5; range_end:; response_count:1; response_revision:631; }","duration":"478.139237ms","start":"2026-10-15T14:19:51.590821Z","end":"2026-10-15T14:19:52.06896Z","steps":["trace[801915692] 'agreement among raft nodes before linearized reading' (duration: 477.791248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:52.069074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:51.59079Z","time spent":"478.27265ms","remote":"127.0.0.1:37958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-dkkq5\" "} {"level":"info","ts":"2026-10-15T14:19:52.120381Z","caller":"traceutil/trace.go:171","msg":"trace[1450200354] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"519.471352ms","start":"2026-10-15T14:19:51.600892Z","end":"2026-10-15T14:19:52.120363Z","steps":["trace[1450200354] 'process raft request' (duration: 519.361199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:52.120487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:19:51.600871Z","time spent":"519.562054ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:19:52.120526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.533909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:19:52.120635Z","caller":"traceutil/trace.go:171","msg":"trace[673031588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"110.736414ms","start":"2026-10-15T14:19:52.00988Z","end":"2026-10-15T14:19:52.120616Z","steps":["trace[673031588] 'agreement among raft nodes before linearized reading' (duration: 110.535379ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:04.840127Z","caller":"traceutil/trace.go:171","msg":"trace[1984132976] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"111.457611ms","start":"2026-10-15T14:20:04.728624Z","end":"2026-10-15T14:20:04.840081Z","steps":["trace[1984132976] 'process raft request' (duration: 93.693752ms)","trace[1984132976] 'compare' (duration: 17.646226ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:20:07.24447Z","caller":"traceutil/trace.go:171","msg":"trace[1972327978] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"110.727314ms","start":"2026-10-15T14:20:07.133702Z","end":"2026-10-15T14:20:07.244429Z","steps":["trace[1972327978] 'process raft request' (duration: 110.352765ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:07.397767Z","caller":"traceutil/trace.go:171","msg":"trace[1460850148] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"148.343731ms","start":"2026-10-15T14:20:07.249385Z","end":"2026-10-15T14:20:07.397728Z","steps":["trace[1460850148] 'process raft request' (duration: 147.740317ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:07.404434Z","caller":"traceutil/trace.go:171","msg":"trace[255319917] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"154.674361ms","start":"2026-10-15T14:20:07.249731Z","end":"2026-10-15T14:20:07.404406Z","steps":["trace[255319917] 'process raft request' (duration: 154.483217ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:20:07.404588Z","caller":"traceutil/trace.go:171","msg":"trace[205110706] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"153.557035ms","start":"2026-10-15T14:20:07.251007Z","end":"2026-10-15T14:20:07.404564Z","steps":["trace[205110706] 'process raft request' (duration: 153.34862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:20:37.240408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.334176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:20:37.240826Z","caller":"traceutil/trace.go:171","msg":"trace[223972633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"230.769136ms","start":"2026-10-15T14:20:37.010002Z","end":"2026-10-15T14:20:37.240771Z","steps":["trace[223972633] 'range keys from in-memory index tree' (duration: 230.188663ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:02.323334Z","caller":"traceutil/trace.go:171","msg":"trace[559426669] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"249.344835ms","start":"2026-10-15T14:21:02.073946Z","end":"2026-10-15T14:21:02.323291Z","steps":["trace[559426669] 'process raft request' (duration: 204.13677ms)","trace[559426669] 'compare' (duration: 44.955389ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:21:29.581862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.978918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-22b0d6fe-3919-4392-ade5-32cf8dc067c7\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-10-15T14:21:29.581958Z","caller":"traceutil/trace.go:171","msg":"trace[1274380318] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-22b0d6fe-3919-4392-ade5-32cf8dc067c7; range_end:; response_count:1; response_revision:1898; }","duration":"216.197994ms","start":"2026-10-15T14:21:29.365741Z","end":"2026-10-15T14:21:29.581939Z","steps":["trace[1274380318] 'range keys from in-memory index tree' (duration: 215.609989ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:21:41.724463Z","caller":"traceutil/trace.go:171","msg":"trace[1353128606] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"187.813079ms","start":"2026-10-15T14:21:41.536618Z","end":"2026-10-15T14:21:41.724431Z","steps":["trace[1353128606] 'process raft request' (duration: 187.601384ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:20.09971Z","caller":"traceutil/trace.go:171","msg":"trace[1236238929] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"219.559867ms","start":"2026-10-15T14:22:19.880123Z","end":"2026-10-15T14:22:20.099682Z","steps":["trace[1236238929] 'process raft request' (duration: 219.379733ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:20.158086Z","caller":"traceutil/trace.go:171","msg":"trace[2106193260] linearizableReadLoop","detail":"{readStateIndex:2307; appliedIndex:2305; }","duration":"147.59752ms","start":"2026-10-15T14:22:20.010466Z","end":"2026-10-15T14:22:20.158063Z","steps":["trace[2106193260] 'read index received' (duration: 89.06056ms)","trace[2106193260] 'applied index is now lower than readState.Index' (duration: 58.53484ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:22:20.158426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.95303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:22:20.158455Z","caller":"traceutil/trace.go:171","msg":"trace[970729416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2222; }","duration":"148.029822ms","start":"2026-10-15T14:22:20.010416Z","end":"2026-10-15T14:22:20.158446Z","steps":["trace[970729416] 'agreement among raft nodes before linearized reading' (duration: 147.96969ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:22:20.158447Z","caller":"traceutil/trace.go:171","msg":"trace[1123657946] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"217.587442ms","start":"2026-10-15T14:22:19.940823Z","end":"2026-10-15T14:22:20.158411Z","steps":["trace[1123657946] 'process raft request' (duration: 217.11021ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:15.217336Z","caller":"traceutil/trace.go:171","msg":"trace[15553091] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"169.416468ms","start":"2026-10-15T14:23:15.047895Z","end":"2026-10-15T14:23:15.217311Z","steps":["trace[15553091] 'process raft request' (duration: 169.07606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:23:48.825987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.749683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:23:48.826138Z","caller":"traceutil/trace.go:171","msg":"trace[1076972206] linearizableReadLoop","detail":"{readStateIndex:3001; appliedIndex:3000; }","duration":"147.399521ms","start":"2026-10-15T14:23:48.678721Z","end":"2026-10-15T14:23:48.82612Z","steps":["trace[1076972206] 'read index received' (duration: 44.238888ms)","trace[1076972206] 'applied index is now lower than readState.Index' (duration: 103.159383ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:23:48.826252Z","caller":"traceutil/trace.go:171","msg":"trace[1867662405] transaction","detail":"{read_only:false; response_revision:2891; number_of_response:1; }","duration":"300.292341ms","start":"2026-10-15T14:23:48.525918Z","end":"2026-10-15T14:23:48.826211Z","steps":["trace[1867662405] 'process raft request' (duration: 197.070857ms)","trace[1867662405] 'compare' (duration: 102.593739ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:23:48.826436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:23:48.525884Z","time spent":"300.458865ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:23:48.826546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.828812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T14:23:48.826695Z","caller":"traceutil/trace.go:171","msg":"trace[912406178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2891; }","duration":"147.971975ms","start":"2026-10-15T14:23:48.678648Z","end":"2026-10-15T14:23:48.82662Z","steps":["trace[912406178] 'agreement among raft nodes before linearized reading' (duration: 147.552485ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:40.847807Z","caller":"traceutil/trace.go:171","msg":"trace[608188790] transaction","detail":"{read_only:false; response_revision:3936; number_of_response:1; }","duration":"128.65709ms","start":"2026-10-15T14:26:40.719105Z","end":"2026-10-15T14:26:40.847762Z","steps":["trace[608188790] 'process raft request' (duration: 122.040845ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:40.848044Z","caller":"traceutil/trace.go:171","msg":"trace[1126198421] transaction","detail":"{read_only:false; response_revision:3937; number_of_response:1; }","duration":"128.756882ms","start":"2026-10-15T14:26:40.719262Z","end":"2026-10-15T14:26:40.848019Z","steps":["trace[1126198421] 'process raft request' (duration: 128.565907ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:40.84831Z","caller":"traceutil/trace.go:171","msg":"trace[1830244033] transaction","detail":"{read_only:false; response_revision:3938; number_of_response:1; }","duration":"125.514846ms","start":"2026-10-15T14:26:40.722764Z","end":"2026-10-15T14:26:40.848279Z","steps":["trace[1830244033] 'process raft request' (duration: 125.189408ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:40.848471Z","caller":"traceutil/trace.go:171","msg":"trace[74443332] transaction","detail":"{read_only:false; response_revision:3939; number_of_response:1; }","duration":"122.627088ms","start":"2026-10-15T14:26:40.725809Z","end":"2026-10-15T14:26:40.848436Z","steps":["trace[74443332] 'process raft request' (duration: 122.358502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:28:50.999998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.171261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:28:51.000202Z","caller":"traceutil/trace.go:171","msg":"trace[802505699] linearizableReadLoop","detail":"{readStateIndex:5592; appliedIndex:5591; }","duration":"178.122981ms","start":"2026-10-15T14:28:50.822048Z","end":"2026-10-15T14:28:51.000171Z","steps":["trace[802505699] 'read index received' (duration: 69.625262ms)","trace[802505699] 'applied index is now lower than readState.Index' (duration: 108.494939ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:28:51.000342Z","caller":"traceutil/trace.go:171","msg":"trace[161530234] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"188.647809ms","start":"2026-10-15T14:28:50.811653Z","end":"2026-10-15T14:28:51.0003Z","steps":["trace[161530234] 'process raft request' (duration: 79.973476ms)","trace[161530234] 'compare' (duration: 107.779522ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:28:51.000515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.041586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T14:28:51.000569Z","caller":"traceutil/trace.go:171","msg":"trace[1799545328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5388; }","duration":"177.122348ms","start":"2026-10-15T14:28:50.823434Z","end":"2026-10-15T14:28:51.000556Z","steps":["trace[1799545328] 'agreement among raft nodes before linearized reading' (duration: 176.893623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:28:51.000658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.593413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T14:28:51.000945Z","caller":"traceutil/trace.go:171","msg":"trace[1222247724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5388; }","duration":"178.960042ms","start":"2026-10-15T14:28:50.821949Z","end":"2026-10-15T14:28:51.000909Z","steps":["trace[1222247724] 'agreement among raft nodes before linearized reading' (duration: 178.358938ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:28:51.176587Z","caller":"traceutil/trace.go:171","msg":"trace[57913479] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"165.850272ms","start":"2026-10-15T14:28:51.010702Z","end":"2026-10-15T14:28:51.176552Z","steps":["trace[57913479] 'process raft request' (duration: 156.092772ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:28:51.239664Z","caller":"traceutil/trace.go:171","msg":"trace[105191101] linearizableReadLoop","detail":"{readStateIndex:5594; appliedIndex:5592; }","duration":"218.281339ms","start":"2026-10-15T14:28:51.021348Z","end":"2026-10-15T14:28:51.23963Z","steps":["trace[105191101] 'read index received' (duration: 145.589224ms)","trace[105191101] 'applied index is now lower than readState.Index' (duration: 72.690725ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:28:51.239753Z","caller":"traceutil/trace.go:171","msg":"trace[517829748] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"219.288693ms","start":"2026-10-15T14:28:51.020419Z","end":"2026-10-15T14:28:51.239708Z","steps":["trace[517829748] 'process raft request' (duration: 219.009996ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:28:51.239856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.471363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:28:51.239901Z","caller":"traceutil/trace.go:171","msg":"trace[1981944990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5390; }","duration":"218.574236ms","start":"2026-10-15T14:28:51.021313Z","end":"2026-10-15T14:28:51.239887Z","steps":["trace[1981944990] 'agreement among raft nodes before linearized reading' (duration: 218.468044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:28:51.240345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.639806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728727"} {"level":"info","ts":"2026-10-15T14:28:51.240439Z","caller":"traceutil/trace.go:171","msg":"trace[1489280030] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5390; }","duration":"134.747439ms","start":"2026-10-15T14:28:51.105671Z","end":"2026-10-15T14:28:51.240418Z","steps":["trace[1489280030] 'agreement among raft nodes before linearized reading' (duration: 134.149714ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:28:58.938277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2948} {"level":"info","ts":"2026-10-15T14:28:59.036555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2948,"took":"96.281521ms","hash":2683419785,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":30679040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T14:28:59.036655Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2683419785,"revision":2948,"compact-revision":-1} {"level":"warn","ts":"2026-10-15T14:29:03.528176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.076344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:29:03.528339Z","caller":"traceutil/trace.go:171","msg":"trace[300111921] transaction","detail":"{read_only:false; response_revision:5609; number_of_response:1; }","duration":"213.363923ms","start":"2026-10-15T14:29:03.314949Z","end":"2026-10-15T14:29:03.528313Z","steps":["trace[300111921] 'process raft request' (duration: 101.056694ms)","trace[300111921] 'compare' (duration: 111.88909ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:29:03.528467Z","caller":"traceutil/trace.go:171","msg":"trace[1820133495] linearizableReadLoop","detail":"{readStateIndex:5821; appliedIndex:5820; }","duration":"102.899167ms","start":"2026-10-15T14:29:03.42556Z","end":"2026-10-15T14:29:03.528459Z","steps":["trace[1820133495] 'read index received' (duration: 34.71µs)","trace[1820133495] 'applied index is now lower than readState.Index' (duration: 102.863577ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:29:03.528598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.024431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T14:29:03.52862Z","caller":"traceutil/trace.go:171","msg":"trace[2019625304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5610; }","duration":"103.113742ms","start":"2026-10-15T14:29:03.425498Z","end":"2026-10-15T14:29:03.528612Z","steps":["trace[2019625304] 'agreement among raft nodes before linearized reading' (duration: 102.99087ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:07.795413Z","caller":"traceutil/trace.go:171","msg":"trace[1817098682] linearizableReadLoop","detail":"{readStateIndex:5888; appliedIndex:5887; }","duration":"250.463928ms","start":"2026-10-15T14:29:07.544925Z","end":"2026-10-15T14:29:07.795389Z","steps":["trace[1817098682] 'read index received' (duration: 250.171701ms)","trace[1817098682] 'applied index is now lower than readState.Index' (duration: 290.957µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:29:07.795504Z","caller":"traceutil/trace.go:171","msg":"trace[1871727120] transaction","detail":"{read_only:false; response_revision:5675; number_of_response:1; }","duration":"251.241596ms","start":"2026-10-15T14:29:07.544237Z","end":"2026-10-15T14:29:07.795479Z","steps":["trace[1871727120] 'process raft request' (duration: 250.964759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:07.795704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.764955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T14:29:07.79577Z","caller":"traceutil/trace.go:171","msg":"trace[1359740015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5675; }","duration":"250.882668ms","start":"2026-10-15T14:29:07.544868Z","end":"2026-10-15T14:29:07.795751Z","steps":["trace[1359740015] 'agreement among raft nodes before linearized reading' (duration: 250.683333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:07.795904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.406647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T14:29:07.795999Z","caller":"traceutil/trace.go:171","msg":"trace[199522172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5675; }","duration":"239.54074ms","start":"2026-10-15T14:29:07.556435Z","end":"2026-10-15T14:29:07.795976Z","steps":["trace[199522172] 'agreement among raft nodes before linearized reading' (duration: 239.272944ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:16.2495Z","caller":"traceutil/trace.go:171","msg":"trace[781933346] transaction","detail":"{read_only:false; response_revision:5838; number_of_response:1; }","duration":"101.351908ms","start":"2026-10-15T14:29:16.148088Z","end":"2026-10-15T14:29:16.24944Z","steps":["trace[781933346] 'process raft request' (duration: 94.67498ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:16.397469Z","caller":"traceutil/trace.go:171","msg":"trace[844351156] linearizableReadLoop","detail":"{readStateIndex:6055; appliedIndex:6053; }","duration":"191.8491ms","start":"2026-10-15T14:29:16.205557Z","end":"2026-10-15T14:29:16.397406Z","steps":["trace[844351156] 'read index received' (duration: 37.227021ms)","trace[844351156] 'applied index is now lower than readState.Index' (duration: 154.620099ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:29:16.397632Z","caller":"traceutil/trace.go:171","msg":"trace[217616908] transaction","detail":"{read_only:false; response_revision:5840; number_of_response:1; }","duration":"190.464818ms","start":"2026-10-15T14:29:16.20713Z","end":"2026-10-15T14:29:16.397595Z","steps":["trace[217616908] 'process raft request' (duration: 190.177801ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:16.397706Z","caller":"traceutil/trace.go:171","msg":"trace[923357604] transaction","detail":"{read_only:false; response_revision:5839; number_of_response:1; }","duration":"248.108232ms","start":"2026-10-15T14:29:16.149544Z","end":"2026-10-15T14:29:16.397652Z","steps":["trace[923357604] 'process raft request' (duration: 235.862253ms)","trace[923357604] 'compare' (duration: 11.657475ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:29:16.397802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.21962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-78314450-2282-475d-904d-96b15585f555\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-15T14:29:16.397851Z","caller":"traceutil/trace.go:171","msg":"trace[1255034342] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-78314450-2282-475d-904d-96b15585f555; range_end:; response_count:1; response_revision:5840; }","duration":"192.376274ms","start":"2026-10-15T14:29:16.205459Z","end":"2026-10-15T14:29:16.397836Z","steps":["trace[1255034342] 'agreement among raft nodes before linearized reading' (duration: 192.117707ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:16.598473Z","caller":"traceutil/trace.go:171","msg":"trace[754202307] transaction","detail":"{read_only:false; response_revision:5842; number_of_response:1; }","duration":"194.916273ms","start":"2026-10-15T14:29:16.403503Z","end":"2026-10-15T14:29:16.59842Z","steps":["trace[754202307] 'process raft request' (duration: 125.146282ms)","trace[754202307] 'compare' (duration: 69.465254ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:29:16.598485Z","caller":"traceutil/trace.go:171","msg":"trace[1264007751] linearizableReadLoop","detail":"{readStateIndex:6059; appliedIndex:6057; }","duration":"183.049172ms","start":"2026-10-15T14:29:16.415406Z","end":"2026-10-15T14:29:16.598455Z","steps":["trace[1264007751] 'read index received' (duration: 113.203299ms)","trace[1264007751] 'applied index is now lower than readState.Index' (duration: 69.844443ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:29:16.598803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.746903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-15T14:29:16.598837Z","caller":"traceutil/trace.go:171","msg":"trace[78041080] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5843; }","duration":"195.838705ms","start":"2026-10-15T14:29:16.402988Z","end":"2026-10-15T14:29:16.598827Z","steps":["trace[78041080] 'agreement among raft nodes before linearized reading' (duration: 195.597739ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:16.599129Z","caller":"traceutil/trace.go:171","msg":"trace[1481783748] transaction","detail":"{read_only:false; response_revision:5843; number_of_response:1; }","duration":"185.881599ms","start":"2026-10-15T14:29:16.413225Z","end":"2026-10-15T14:29:16.599107Z","steps":["trace[1481783748] 'process raft request' (duration: 185.125352ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:17.043902Z","caller":"traceutil/trace.go:171","msg":"trace[1704592826] transaction","detail":"{read_only:false; response_revision:5845; number_of_response:1; }","duration":"398.545593ms","start":"2026-10-15T14:29:16.645319Z","end":"2026-10-15T14:29:17.043865Z","steps":["trace[1704592826] 'process raft request' (duration: 398.325117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:17.044128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:16.645287Z","time spent":"398.734967ms","remote":"127.0.0.1:37854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":919,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:29:17.051538Z","caller":"traceutil/trace.go:171","msg":"trace[271821367] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"405.342994ms","start":"2026-10-15T14:29:16.646171Z","end":"2026-10-15T14:29:17.051514Z","steps":["trace[271821367] 'process raft request' (duration: 405.078608ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:17.051633Z","caller":"traceutil/trace.go:171","msg":"trace[953460468] transaction","detail":"{read_only:false; response_revision:5847; number_of_response:1; }","duration":"405.21702ms","start":"2026-10-15T14:29:16.646395Z","end":"2026-10-15T14:29:17.051612Z","steps":["trace[953460468] 'process raft request' (duration: 404.956564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:17.051661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:16.646153Z","time spent":"405.442686ms","remote":"127.0.0.1:37854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":919,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:29:17.051743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:16.646386Z","time spent":"405.287873ms","remote":"127.0.0.1:37854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":922,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:29:17.052051Z","caller":"traceutil/trace.go:171","msg":"trace[294734562] transaction","detail":"{read_only:false; response_revision:5848; number_of_response:1; }","duration":"405.530438ms","start":"2026-10-15T14:29:16.646502Z","end":"2026-10-15T14:29:17.052032Z","steps":["trace[294734562] 'process raft request' (duration: 405.351664ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:17.052065Z","caller":"traceutil/trace.go:171","msg":"trace[144390499] transaction","detail":"{read_only:false; response_revision:5849; number_of_response:1; }","duration":"405.331264ms","start":"2026-10-15T14:29:16.64672Z","end":"2026-10-15T14:29:17.052051Z","steps":["trace[144390499] 'process raft request' (duration: 405.1833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:17.052129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:16.646494Z","time spent":"405.59979ms","remote":"127.0.0.1:37854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":916,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T14:29:17.052127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:16.646689Z","time spent":"405.410205ms","remote":"127.0.0.1:37854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":934,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:29:17.052188Z","caller":"traceutil/trace.go:171","msg":"trace[376985203] transaction","detail":"{read_only:false; response_revision:5850; number_of_response:1; }","duration":"405.300653ms","start":"2026-10-15T14:29:16.646849Z","end":"2026-10-15T14:29:17.05215Z","steps":["trace[376985203] 'process raft request' (duration: 405.096788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:17.052372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:16.646844Z","time spent":"405.429796ms","remote":"127.0.0.1:37854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":910,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:29:17.05236Z","caller":"traceutil/trace.go:171","msg":"trace[1751132795] linearizableReadLoop","detail":"{readStateIndex:6066; appliedIndex:6060; }","duration":"404.130804ms","start":"2026-10-15T14:29:16.648206Z","end":"2026-10-15T14:29:17.052337Z","steps":["trace[1751132795] 'read index received' (duration: 395.50957ms)","trace[1751132795] 'applied index is now lower than readState.Index' (duration: 8.619814ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:29:17.052424Z","caller":"traceutil/trace.go:171","msg":"trace[1182691525] transaction","detail":"{read_only:false; response_revision:5851; number_of_response:1; }","duration":"389.745485ms","start":"2026-10-15T14:29:16.662643Z","end":"2026-10-15T14:29:17.052388Z","steps":["trace[1182691525] 'process raft request' (duration: 389.362235ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:17.052647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.414592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-15T14:29:17.052694Z","caller":"traceutil/trace.go:171","msg":"trace[1135648021] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5851; }","duration":"404.499603ms","start":"2026-10-15T14:29:16.648179Z","end":"2026-10-15T14:29:17.052679Z","steps":["trace[1135648021] 'agreement among raft nodes before linearized reading' (duration: 404.291959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:17.052709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:16.662626Z","time spent":"389.886138ms","remote":"127.0.0.1:53090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4226,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:29:17.05273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:29:16.648168Z","time spent":"404.551024ms","remote":"127.0.0.1:37872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":672,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"warn","ts":"2026-10-15T14:29:17.052996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.839931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-results-cache-0\" ","response":"range_response_count:1 size:4474"} {"level":"info","ts":"2026-10-15T14:29:17.053045Z","caller":"traceutil/trace.go:171","msg":"trace[1483659427] range","detail":"{range_begin:/registry/pods/monitoring/loki-results-cache-0; range_end:; response_count:1; response_revision:5851; }","duration":"123.953454ms","start":"2026-10-15T14:29:16.929079Z","end":"2026-10-15T14:29:17.053032Z","steps":["trace[1483659427] 'agreement among raft nodes before linearized reading' (duration: 123.701658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:17.053225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.214269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T14:29:17.053364Z","caller":"traceutil/trace.go:171","msg":"trace[1398275364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5851; }","duration":"249.412993ms","start":"2026-10-15T14:29:16.803913Z","end":"2026-10-15T14:29:17.053326Z","steps":["trace[1398275364] 'agreement among raft nodes before linearized reading' (duration: 248.88271ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:29:17.053927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.198285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-10-15T14:29:17.054017Z","caller":"traceutil/trace.go:171","msg":"trace[1032881489] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5851; }","duration":"278.333518ms","start":"2026-10-15T14:29:16.775668Z","end":"2026-10-15T14:29:17.054001Z","steps":["trace[1032881489] 'agreement among raft nodes before linearized reading' (duration: 276.990756ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:29:29.421998Z","caller":"traceutil/trace.go:171","msg":"trace[1313941427] transaction","detail":"{read_only:false; response_revision:6006; number_of_response:1; }","duration":"116.585549ms","start":"2026-10-15T14:29:29.305361Z","end":"2026-10-15T14:29:29.421946Z","steps":["trace[1313941427] 'process raft request' (duration: 116.408995ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:58.945574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5551} {"level":"info","ts":"2026-10-15T14:33:59.050145Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5551,"took":"101.945047ms","hash":162900076,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":28807168,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T14:33:59.050231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":162900076,"revision":5551,"compact-revision":2948} {"level":"warn","ts":"2026-10-15T14:35:44.107921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.678379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:35:44.108349Z","caller":"traceutil/trace.go:171","msg":"trace[938242] transaction","detail":"{read_only:false; response_revision:8783; number_of_response:1; }","duration":"539.057753ms","start":"2026-10-15T14:35:43.569263Z","end":"2026-10-15T14:35:44.10832Z","steps":["trace[938242] 'process raft request' (duration: 404.847101ms)","trace[938242] 'compare' (duration: 133.224479ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:35:44.108438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:35:43.569228Z","time spent":"539.168016ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:35:44.113429Z","caller":"traceutil/trace.go:171","msg":"trace[1630320933] transaction","detail":"{read_only:false; response_revision:8784; number_of_response:1; }","duration":"469.701888ms","start":"2026-10-15T14:35:43.64368Z","end":"2026-10-15T14:35:44.113381Z","steps":["trace[1630320933] 'process raft request' (duration: 469.271838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:35:44.113665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:35:43.643631Z","time spent":"469.931044ms","remote":"127.0.0.1:43138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415339,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:35:44.113812Z","caller":"traceutil/trace.go:171","msg":"trace[488896419] linearizableReadLoop","detail":"{readStateIndex:9096; appliedIndex:9093; }","duration":"350.270804ms","start":"2026-10-15T14:35:43.76349Z","end":"2026-10-15T14:35:44.113761Z","steps":["trace[488896419] 'read index received' (duration: 210.703336ms)","trace[488896419] 'applied index is now lower than readState.Index' (duration: 139.563448ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:35:44.113864Z","caller":"traceutil/trace.go:171","msg":"trace[1900278886] transaction","detail":"{read_only:false; response_revision:8785; number_of_response:1; }","duration":"406.432277ms","start":"2026-10-15T14:35:43.707361Z","end":"2026-10-15T14:35:44.113794Z","steps":["trace[1900278886] 'process raft request' (duration: 405.948416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:35:44.114251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:35:43.707346Z","time spent":"406.751905ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:35:44.11426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.738705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T14:35:44.114329Z","caller":"traceutil/trace.go:171","msg":"trace[411865435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8785; }","duration":"350.827877ms","start":"2026-10-15T14:35:43.76348Z","end":"2026-10-15T14:35:44.114308Z","steps":["trace[411865435] 'agreement among raft nodes before linearized reading' (duration: 350.400057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:35:44.114375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.116686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T14:35:44.114401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:35:43.763382Z","time spent":"351.005441ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T14:35:44.114444Z","caller":"traceutil/trace.go:171","msg":"trace[930089455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8787; }","duration":"105.206739ms","start":"2026-10-15T14:35:44.009218Z","end":"2026-10-15T14:35:44.114425Z","steps":["trace[930089455] 'agreement among raft nodes before linearized reading' (duration: 105.057766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:35:44.114455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.251689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T14:35:44.114503Z","caller":"traceutil/trace.go:171","msg":"trace[943593858] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8787; }","duration":"133.321161ms","start":"2026-10-15T14:35:43.981167Z","end":"2026-10-15T14:35:44.114489Z","steps":["trace[943593858] 'agreement among raft nodes before linearized reading' (duration: 133.144196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:35:44.114506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.587076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T14:35:44.114614Z","caller":"traceutil/trace.go:171","msg":"trace[1278700495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8787; }","duration":"326.730319ms","start":"2026-10-15T14:35:43.787859Z","end":"2026-10-15T14:35:44.114589Z","steps":["trace[1278700495] 'agreement among raft nodes before linearized reading' (duration: 326.400621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:35:44.11466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:35:43.787848Z","time spent":"326.803081ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T14:35:44.1398Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002390.wal"} {"level":"info","ts":"2026-10-15T14:35:55.653232Z","caller":"traceutil/trace.go:171","msg":"trace[434393252] transaction","detail":"{read_only:false; response_revision:8845; number_of_response:1; }","duration":"208.294549ms","start":"2026-10-15T14:35:55.444902Z","end":"2026-10-15T14:35:55.653196Z","steps":["trace[434393252] 'process raft request' (duration: 208.077434ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:35:55.689602Z","caller":"traceutil/trace.go:171","msg":"trace[1951815966] transaction","detail":"{read_only:false; response_revision:8846; number_of_response:1; }","duration":"179.184543ms","start":"2026-10-15T14:35:55.510372Z","end":"2026-10-15T14:35:55.689556Z","steps":["trace[1951815966] 'process raft request' (duration: 178.964488ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:36:26.811256Z","caller":"traceutil/trace.go:171","msg":"trace[1642131199] transaction","detail":"{read_only:false; response_revision:9063; number_of_response:1; }","duration":"470.736434ms","start":"2026-10-15T14:36:26.340487Z","end":"2026-10-15T14:36:26.811224Z","steps":["trace[1642131199] 'process raft request' (duration: 470.516338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:36:26.811463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:36:26.340451Z","time spent":"470.898998ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:36:26.817666Z","caller":"traceutil/trace.go:171","msg":"trace[1213321462] linearizableReadLoop","detail":"{readStateIndex:9387; appliedIndex:9384; }","duration":"451.301925ms","start":"2026-10-15T14:36:26.366325Z","end":"2026-10-15T14:36:26.817627Z","steps":["trace[1213321462] 'read index received' (duration: 444.735161ms)","trace[1213321462] 'applied index is now lower than readState.Index' (duration: 6.565714ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:36:26.818097Z","caller":"traceutil/trace.go:171","msg":"trace[369579729] transaction","detail":"{read_only:false; response_revision:9064; number_of_response:1; }","duration":"476.11216ms","start":"2026-10-15T14:36:26.341969Z","end":"2026-10-15T14:36:26.818081Z","steps":["trace[369579729] 'process raft request' (duration: 475.456205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:36:26.818479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:36:26.341944Z","time spent":"476.224393ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:36:26.818618Z","caller":"traceutil/trace.go:171","msg":"trace[1852005250] transaction","detail":"{read_only:false; response_revision:9065; number_of_response:1; }","duration":"475.497396ms","start":"2026-10-15T14:36:26.343087Z","end":"2026-10-15T14:36:26.818584Z","steps":["trace[1852005250] 'process raft request' (duration: 474.498572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:36:26.818618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.593354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-15T14:36:26.818671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.129959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-v6cjf\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T14:36:26.818721Z","caller":"traceutil/trace.go:171","msg":"trace[1015764153] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:9065; }","duration":"185.723657ms","start":"2026-10-15T14:36:26.632973Z","end":"2026-10-15T14:36:26.818697Z","steps":["trace[1015764153] 'agreement among raft nodes before linearized reading' (duration: 185.494161ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:36:26.818736Z","caller":"traceutil/trace.go:171","msg":"trace[504008203] range","detail":"{range_begin:/registry/pods/openstack/keepalived-v6cjf; range_end:; response_count:1; response_revision:9065; }","duration":"100.251122ms","start":"2026-10-15T14:36:26.718465Z","end":"2026-10-15T14:36:26.818716Z","steps":["trace[504008203] 'agreement among raft nodes before linearized reading' (duration: 100.103669ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:36:26.818753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:36:26.343077Z","time spent":"475.602188ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:36:26.818815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.369406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-15T14:36:26.818788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.998391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T14:36:26.818869Z","caller":"traceutil/trace.go:171","msg":"trace[658645864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9065; }","duration":"403.439527ms","start":"2026-10-15T14:36:26.415413Z","end":"2026-10-15T14:36:26.818852Z","steps":["trace[658645864] 'agreement among raft nodes before linearized reading' (duration: 403.238983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:36:26.818912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:36:26.415405Z","time spent":"403.493329ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T14:36:26.818921Z","caller":"traceutil/trace.go:171","msg":"trace[2060415523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9065; }","duration":"415.169084ms","start":"2026-10-15T14:36:26.403711Z","end":"2026-10-15T14:36:26.818881Z","steps":["trace[2060415523] 'agreement among raft nodes before linearized reading' (duration: 414.752474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:36:26.818992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:36:26.4037Z","time spent":"415.275917ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T14:36:26.819138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.765623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-10-15T14:36:26.819176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.842693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T14:36:26.819202Z","caller":"traceutil/trace.go:171","msg":"trace[2086047247] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9065; }","duration":"217.873925ms","start":"2026-10-15T14:36:26.601308Z","end":"2026-10-15T14:36:26.819182Z","steps":["trace[2086047247] 'agreement among raft nodes before linearized reading' (duration: 217.64221ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:36:26.819227Z","caller":"traceutil/trace.go:171","msg":"trace[1978630210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9065; }","duration":"452.926154ms","start":"2026-10-15T14:36:26.366287Z","end":"2026-10-15T14:36:26.819213Z","steps":["trace[1978630210] 'agreement among raft nodes before linearized reading' (duration: 452.232488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:36:26.819271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:36:26.366275Z","time spent":"452.985416ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T14:36:26.818967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.264797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-15T14:36:26.819657Z","caller":"traceutil/trace.go:171","msg":"trace[1576613380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9065; }","duration":"404.972584ms","start":"2026-10-15T14:36:26.414669Z","end":"2026-10-15T14:36:26.819641Z","steps":["trace[1576613380] 'agreement among raft nodes before linearized reading' (duration: 404.168265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:36:26.819729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:36:26.414658Z","time spent":"405.052806ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-15T14:36:29.2593Z","caller":"traceutil/trace.go:171","msg":"trace[584814108] transaction","detail":"{read_only:false; response_revision:9079; number_of_response:1; }","duration":"102.712411ms","start":"2026-10-15T14:36:29.156562Z","end":"2026-10-15T14:36:29.259274Z","steps":["trace[584814108] 'process raft request' (duration: 102.523206ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:36:29.267351Z","caller":"traceutil/trace.go:171","msg":"trace[569856217] transaction","detail":"{read_only:false; response_revision:9080; number_of_response:1; }","duration":"110.091705ms","start":"2026-10-15T14:36:29.157232Z","end":"2026-10-15T14:36:29.267324Z","steps":["trace[569856217] 'process raft request' (duration: 109.91681ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:36:40.404656Z","caller":"traceutil/trace.go:171","msg":"trace[1146326175] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9145; }","duration":"173.779876ms","start":"2026-10-15T14:36:40.230844Z","end":"2026-10-15T14:36:40.404624Z","steps":["trace[1146326175] 'process raft request' (duration: 173.585091ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:36:40.447964Z","caller":"traceutil/trace.go:171","msg":"trace[101231444] linearizableReadLoop","detail":"{readStateIndex:9471; appliedIndex:9469; }","duration":"207.724906ms","start":"2026-10-15T14:36:40.240222Z","end":"2026-10-15T14:36:40.447947Z","steps":["trace[101231444] 'read index received' (duration: 164.318153ms)","trace[101231444] 'applied index is now lower than readState.Index' (duration: 43.405173ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:36:40.447931Z","caller":"traceutil/trace.go:171","msg":"trace[1754846295] transaction","detail":"{read_only:false; response_revision:9146; number_of_response:1; }","duration":"215.991701ms","start":"2026-10-15T14:36:40.231897Z","end":"2026-10-15T14:36:40.447888Z","steps":["trace[1754846295] 'process raft request' (duration: 215.686583ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:36:40.448018Z","caller":"traceutil/trace.go:171","msg":"trace[2127670430] transaction","detail":"{read_only:false; response_revision:9147; number_of_response:1; }","duration":"206.819894ms","start":"2026-10-15T14:36:40.241189Z","end":"2026-10-15T14:36:40.448009Z","steps":["trace[2127670430] 'process raft request' (duration: 206.540898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:36:40.448534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.293399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-99fjl\" ","response":"range_response_count:1 size:4642"} {"level":"info","ts":"2026-10-15T14:36:40.448636Z","caller":"traceutil/trace.go:171","msg":"trace[1469890854] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-99fjl; range_end:; response_count:1; response_revision:9147; }","duration":"208.456273ms","start":"2026-10-15T14:36:40.240156Z","end":"2026-10-15T14:36:40.448612Z","steps":["trace[1469890854] 'agreement among raft nodes before linearized reading' (duration: 207.93446ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:36:53.503189Z","caller":"traceutil/trace.go:171","msg":"trace[1150151416] transaction","detail":"{read_only:false; response_revision:9284; number_of_response:1; }","duration":"126.980217ms","start":"2026-10-15T14:36:53.376179Z","end":"2026-10-15T14:36:53.503159Z","steps":["trace[1150151416] 'process raft request' (duration: 126.751992ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:37:39.12227Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:37:39.128862Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T14:37:39.129019Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T14:37:56.380517Z","caller":"traceutil/trace.go:171","msg":"trace[766963092] transaction","detail":"{read_only:false; response_revision:9922; number_of_response:1; }","duration":"133.221469ms","start":"2026-10-15T14:37:56.247262Z","end":"2026-10-15T14:37:56.380484Z","steps":["trace[766963092] 'process raft request' (duration: 132.980743ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:38:58.952344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7952} {"level":"info","ts":"2026-10-15T14:38:59.04903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7952,"took":"93.678343ms","hash":587484497,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":24801280,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-15T14:38:59.04915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":587484497,"revision":7952,"compact-revision":5551} {"level":"info","ts":"2026-10-15T14:43:33.316631Z","caller":"traceutil/trace.go:171","msg":"trace[1149356210] transaction","detail":"{read_only:false; response_revision:12346; number_of_response:1; }","duration":"108.569784ms","start":"2026-10-15T14:43:33.208024Z","end":"2026-10-15T14:43:33.316594Z","steps":["trace[1149356210] 'process raft request' (duration: 92.668713ms)","trace[1149356210] 'compare' (duration: 15.725877ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:43:56.093981Z","caller":"traceutil/trace.go:171","msg":"trace[302499345] transaction","detail":"{read_only:false; response_revision:12473; number_of_response:1; }","duration":"109.205969ms","start":"2026-10-15T14:43:55.984748Z","end":"2026-10-15T14:43:56.093954Z","steps":["trace[302499345] 'process raft request' (duration: 109.085066ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:43:58.959097Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10348} {"level":"info","ts":"2026-10-15T14:43:59.060328Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10348,"took":"98.456261ms","hash":3095546111,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":24932352,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-15T14:43:59.060437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3095546111,"revision":10348,"compact-revision":7952} {"level":"info","ts":"2026-10-15T14:48:59.017809Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12488} {"level":"info","ts":"2026-10-15T14:48:59.13839Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12488,"took":"117.796596ms","hash":3462856494,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":28106752,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T14:48:59.138476Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3462856494,"revision":12488,"compact-revision":10348} {"level":"info","ts":"2026-10-15T14:49:55.92102Z","caller":"traceutil/trace.go:171","msg":"trace[2104443897] transaction","detail":"{read_only:false; response_revision:14911; number_of_response:1; }","duration":"167.829004ms","start":"2026-10-15T14:49:55.753166Z","end":"2026-10-15T14:49:55.920995Z","steps":["trace[2104443897] 'process raft request' (duration: 167.68787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:37.171572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.664309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:50:37.171714Z","caller":"traceutil/trace.go:171","msg":"trace[327666409] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:15145; }","duration":"109.880874ms","start":"2026-10-15T14:50:37.061813Z","end":"2026-10-15T14:50:37.171694Z","steps":["trace[327666409] 'count revisions from in-memory index tree' (duration: 109.551046ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:37.17213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.050004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:50:37.172518Z","caller":"traceutil/trace.go:171","msg":"trace[833082349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15145; }","duration":"162.502365ms","start":"2026-10-15T14:50:37.009985Z","end":"2026-10-15T14:50:37.172487Z","steps":["trace[833082349] 'range keys from in-memory index tree' (duration: 161.937371ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:50:37.878746Z","caller":"traceutil/trace.go:171","msg":"trace[2090523238] linearizableReadLoop","detail":"{readStateIndex:15672; appliedIndex:15671; }","duration":"215.668198ms","start":"2026-10-15T14:50:37.663049Z","end":"2026-10-15T14:50:37.878717Z","steps":["trace[2090523238] 'read index received' (duration: 215.518084ms)","trace[2090523238] 'applied index is now lower than readState.Index' (duration: 148.714µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:50:37.878795Z","caller":"traceutil/trace.go:171","msg":"trace[1441331250] transaction","detail":"{read_only:false; response_revision:15146; number_of_response:1; }","duration":"221.875419ms","start":"2026-10-15T14:50:37.656873Z","end":"2026-10-15T14:50:37.878748Z","steps":["trace[1441331250] 'process raft request' (duration: 221.616243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:37.879666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.594571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:50:37.879741Z","caller":"traceutil/trace.go:171","msg":"trace[444664313] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:15146; }","duration":"216.736944ms","start":"2026-10-15T14:50:37.662981Z","end":"2026-10-15T14:50:37.879718Z","steps":["trace[444664313] 'agreement among raft nodes before linearized reading' (duration: 215.863783ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:50:38.05024Z","caller":"traceutil/trace.go:171","msg":"trace[1430551069] transaction","detail":"{read_only:false; response_revision:15147; number_of_response:1; }","duration":"328.834602ms","start":"2026-10-15T14:50:37.721378Z","end":"2026-10-15T14:50:38.050213Z","steps":["trace[1430551069] 'process raft request' (duration: 324.936827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:38.050379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:50:37.721355Z","time spent":"328.946205ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:50:38.050613Z","caller":"traceutil/trace.go:171","msg":"trace[780377535] linearizableReadLoop","detail":"{readStateIndex:15673; appliedIndex:15672; }","duration":"171.728159ms","start":"2026-10-15T14:50:37.878821Z","end":"2026-10-15T14:50:38.050549Z","steps":["trace[780377535] 'read index received' (duration: 167.509756ms)","trace[780377535] 'applied index is now lower than readState.Index' (duration: 4.211573ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:50:38.05991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.415283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-k8lqt\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-10-15T14:50:38.059993Z","caller":"traceutil/trace.go:171","msg":"trace[883267376] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-k8lqt; range_end:; response_count:1; response_revision:15147; }","duration":"213.602608ms","start":"2026-10-15T14:50:37.846371Z","end":"2026-10-15T14:50:38.059974Z","steps":["trace[883267376] 'agreement among raft nodes before linearized reading' (duration: 204.291942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:38.059932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.251002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T14:50:38.060103Z","caller":"traceutil/trace.go:171","msg":"trace[1799657745] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15147; }","duration":"366.419507ms","start":"2026-10-15T14:50:37.69366Z","end":"2026-10-15T14:50:38.060079Z","steps":["trace[1799657745] 'agreement among raft nodes before linearized reading' (duration: 357.022498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:38.060172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:50:37.693568Z","time spent":"366.586631ms","remote":"127.0.0.1:37958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-15T14:50:38.060826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.647125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T14:50:38.060875Z","caller":"traceutil/trace.go:171","msg":"trace[846813316] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:15147; }","duration":"265.752577ms","start":"2026-10-15T14:50:37.795108Z","end":"2026-10-15T14:50:38.06086Z","steps":["trace[846813316] 'agreement among raft nodes before linearized reading' (duration: 255.566989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:48.838439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.230742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:50:48.838745Z","caller":"traceutil/trace.go:171","msg":"trace[439130450] transaction","detail":"{read_only:false; response_revision:15322; number_of_response:1; }","duration":"401.07887ms","start":"2026-10-15T14:50:48.43763Z","end":"2026-10-15T14:50:48.838709Z","steps":["trace[439130450] 'process raft request' (duration: 400.941217ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:50:48.838873Z","caller":"traceutil/trace.go:171","msg":"trace[459891839] linearizableReadLoop","detail":"{readStateIndex:15853; appliedIndex:15851; }","duration":"371.0777ms","start":"2026-10-15T14:50:48.467758Z","end":"2026-10-15T14:50:48.838836Z","steps":["trace[459891839] 'read index received' (duration: 4.267724ms)","trace[459891839] 'applied index is now lower than readState.Index' (duration: 366.806186ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:50:48.838921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:50:48.437596Z","time spent":"401.234334ms","remote":"127.0.0.1:37854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T14:50:48.838778Z","caller":"traceutil/trace.go:171","msg":"trace[199281496] transaction","detail":"{read_only:false; response_revision:15325; number_of_response:1; }","duration":"248.578489ms","start":"2026-10-15T14:50:48.59018Z","end":"2026-10-15T14:50:48.838759Z","steps":["trace[199281496] 'process raft request' (duration: 248.535608ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:50:48.838921Z","caller":"traceutil/trace.go:171","msg":"trace[138332704] transaction","detail":"{read_only:false; response_revision:15324; number_of_response:1; }","duration":"306.112819ms","start":"2026-10-15T14:50:48.532769Z","end":"2026-10-15T14:50:48.838881Z","steps":["trace[138332704] 'process raft request' (duration: 305.913404ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:50:48.838927Z","caller":"traceutil/trace.go:171","msg":"trace[2116059901] transaction","detail":"{read_only:false; response_revision:15321; number_of_response:1; }","duration":"419.712883ms","start":"2026-10-15T14:50:48.419172Z","end":"2026-10-15T14:50:48.838885Z","steps":["trace[2116059901] 'process raft request' (duration: 52.913558ms)","trace[2116059901] 'compare' (duration: 366.016626ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:50:48.838922Z","caller":"traceutil/trace.go:171","msg":"trace[1270900995] transaction","detail":"{read_only:false; response_revision:15323; number_of_response:1; }","duration":"370.953257ms","start":"2026-10-15T14:50:48.467914Z","end":"2026-10-15T14:50:48.838867Z","steps":["trace[1270900995] 'process raft request' (duration: 370.713221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:48.839088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.310315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-15T14:50:48.839117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:50:48.532753Z","time spent":"306.253382ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:50:48.839122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:50:48.419135Z","time spent":"419.881737ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:50:48.839154Z","caller":"traceutil/trace.go:171","msg":"trace[1128984813] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:15325; }","duration":"371.441449ms","start":"2026-10-15T14:50:48.467696Z","end":"2026-10-15T14:50:48.839137Z","steps":["trace[1128984813] 'agreement among raft nodes before linearized reading' (duration: 371.266805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:48.83919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:50:48.467672Z","time spent":"371.50916ms","remote":"127.0.0.1:38014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":38,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-10-15T14:50:48.839187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:50:48.467873Z","time spent":"371.185082ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:50:48.839234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.999436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T14:50:48.839283Z","caller":"traceutil/trace.go:171","msg":"trace[1486076732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15325; }","duration":"366.090749ms","start":"2026-10-15T14:50:48.47318Z","end":"2026-10-15T14:50:48.839271Z","steps":["trace[1486076732] 'agreement among raft nodes before linearized reading' (duration: 365.833302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:48.839321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:50:48.473168Z","time spent":"366.14477ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T14:50:48.839334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.967468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:50:48.83939Z","caller":"traceutil/trace.go:171","msg":"trace[1698649662] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15325; }","duration":"332.03645ms","start":"2026-10-15T14:50:48.507334Z","end":"2026-10-15T14:50:48.839371Z","steps":["trace[1698649662] 'agreement among raft nodes before linearized reading' (duration: 331.939508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:48.839439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.252137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T14:50:48.839476Z","caller":"traceutil/trace.go:171","msg":"trace[1126069084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15325; }","duration":"360.308837ms","start":"2026-10-15T14:50:48.479155Z","end":"2026-10-15T14:50:48.839464Z","steps":["trace[1126069084] 'agreement among raft nodes before linearized reading' (duration: 360.121983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:50:48.839483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:50:48.507328Z","time spent":"332.139212ms","remote":"127.0.0.1:38206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-15T14:50:48.839512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:50:48.479145Z","time spent":"360.360939ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-15T14:52:30.640426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.734277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:52:30.640543Z","caller":"traceutil/trace.go:171","msg":"trace[1516046185] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16417; }","duration":"109.875571ms","start":"2026-10-15T14:52:30.53065Z","end":"2026-10-15T14:52:30.640525Z","steps":["trace[1516046185] 'range keys from in-memory index tree' (duration: 109.702337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:52:30.640614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.023984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-10-15T14:52:30.640664Z","caller":"traceutil/trace.go:171","msg":"trace[1126781600] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16417; }","duration":"110.110837ms","start":"2026-10-15T14:52:30.530541Z","end":"2026-10-15T14:52:30.640652Z","steps":["trace[1126781600] 'range keys from in-memory index tree' (duration: 109.941973ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:52:30.907294Z","caller":"traceutil/trace.go:171","msg":"trace[1956767616] transaction","detail":"{read_only:false; response_revision:16419; number_of_response:1; }","duration":"139.907493ms","start":"2026-10-15T14:52:30.767342Z","end":"2026-10-15T14:52:30.907249Z","steps":["trace[1956767616] 'process raft request' (duration: 102.3419ms)","trace[1956767616] 'compare' (duration: 37.404699ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:52:31.10091Z","caller":"traceutil/trace.go:171","msg":"trace[439750060] transaction","detail":"{read_only:false; response_revision:16420; number_of_response:1; }","duration":"162.530406ms","start":"2026-10-15T14:52:30.938333Z","end":"2026-10-15T14:52:31.100863Z","steps":["trace[439750060] 'process raft request' (duration: 151.826349ms)","trace[439750060] 'compare' (duration: 10.417501ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:52:31.329068Z","caller":"traceutil/trace.go:171","msg":"trace[134378461] transaction","detail":"{read_only:false; response_revision:16421; number_of_response:1; }","duration":"155.592079ms","start":"2026-10-15T14:52:31.173435Z","end":"2026-10-15T14:52:31.329027Z","steps":["trace[134378461] 'process raft request' (duration: 155.373084ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:52:31.570708Z","caller":"traceutil/trace.go:171","msg":"trace[844447702] transaction","detail":"{read_only:false; response_revision:16425; number_of_response:1; }","duration":"140.951878ms","start":"2026-10-15T14:52:31.429705Z","end":"2026-10-15T14:52:31.570657Z","steps":["trace[844447702] 'process raft request' (duration: 117.690309ms)","trace[844447702] 'compare' (duration: 22.634854ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:52:31.570749Z","caller":"traceutil/trace.go:171","msg":"trace[524002844] linearizableReadLoop","detail":"{readStateIndex:16989; appliedIndex:16988; }","duration":"139.730189ms","start":"2026-10-15T14:52:31.430972Z","end":"2026-10-15T14:52:31.570702Z","steps":["trace[524002844] 'read index received' (duration: 116.440439ms)","trace[524002844] 'applied index is now lower than readState.Index' (duration: 23.28193ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:52:31.57035Z","caller":"traceutil/trace.go:171","msg":"trace[769369425] transaction","detail":"{read_only:false; response_revision:16426; number_of_response:1; }","duration":"138.441157ms","start":"2026-10-15T14:52:31.431873Z","end":"2026-10-15T14:52:31.570314Z","steps":["trace[769369425] 'process raft request' (duration: 138.369596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:52:31.571016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.015516ms","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-10-15T14:52:31.571115Z","caller":"traceutil/trace.go:171","msg":"trace[673367103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16426; }","duration":"140.172119ms","start":"2026-10-15T14:52:31.43092Z","end":"2026-10-15T14:52:31.571092Z","steps":["trace[673367103] 'agreement among raft nodes before linearized reading' (duration: 139.877492ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:52:31.571115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.884048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T14:52:31.571174Z","caller":"traceutil/trace.go:171","msg":"trace[949798053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16426; }","duration":"138.990851ms","start":"2026-10-15T14:52:31.432165Z","end":"2026-10-15T14:52:31.571156Z","steps":["trace[949798053] 'agreement among raft nodes before linearized reading' (duration: 138.756045ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:53:59.024577Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14541} {"level":"info","ts":"2026-10-15T14:53:59.114738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14541,"took":"86.319105ms","hash":3703904652,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":31752192,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:53:59.11481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3703904652,"revision":14541,"compact-revision":12488} {"level":"info","ts":"2026-10-15T14:57:04.498745Z","caller":"traceutil/trace.go:171","msg":"trace[324457363] transaction","detail":"{read_only:false; response_revision:18430; number_of_response:1; }","duration":"141.546817ms","start":"2026-10-15T14:57:04.357172Z","end":"2026-10-15T14:57:04.498719Z","steps":["trace[324457363] 'process raft request' (duration: 141.394804ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:57:04.507989Z","caller":"traceutil/trace.go:171","msg":"trace[1151952562] transaction","detail":"{read_only:false; response_revision:18431; number_of_response:1; }","duration":"103.848372ms","start":"2026-10-15T14:57:04.404126Z","end":"2026-10-15T14:57:04.507974Z","steps":["trace[1151952562] 'process raft request' (duration: 103.735229ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:05.656548Z","caller":"traceutil/trace.go:171","msg":"trace[2069064620] linearizableReadLoop","detail":"{readStateIndex:19524; appliedIndex:19523; }","duration":"104.508438ms","start":"2026-10-15T14:58:05.552007Z","end":"2026-10-15T14:58:05.656515Z","steps":["trace[2069064620] 'read index received' (duration: 104.273672ms)","trace[2069064620] 'applied index is now lower than readState.Index' (duration: 233.026µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:58:05.657144Z","caller":"traceutil/trace.go:171","msg":"trace[45676393] transaction","detail":"{read_only:false; response_revision:18880; number_of_response:1; }","duration":"148.090887ms","start":"2026-10-15T14:58:05.509033Z","end":"2026-10-15T14:58:05.657124Z","steps":["trace[45676393] 'process raft request' (duration: 147.265106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:05.657644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.606355ms","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-10-15T14:58:05.658029Z","caller":"traceutil/trace.go:171","msg":"trace[1018482263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18880; }","duration":"106.051476ms","start":"2026-10-15T14:58:05.551953Z","end":"2026-10-15T14:58:05.658005Z","steps":["trace[1018482263] 'agreement among raft nodes before linearized reading' (duration: 105.40877ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:12.296247Z","caller":"traceutil/trace.go:171","msg":"trace[240322063] linearizableReadLoop","detail":"{readStateIndex:19565; appliedIndex:19564; }","duration":"139.310393ms","start":"2026-10-15T14:58:12.156911Z","end":"2026-10-15T14:58:12.296222Z","steps":["trace[240322063] 'read index received' (duration: 139.017036ms)","trace[240322063] 'applied index is now lower than readState.Index' (duration: 292.277µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:58:12.296313Z","caller":"traceutil/trace.go:171","msg":"trace[1915100190] transaction","detail":"{read_only:false; response_revision:18920; number_of_response:1; }","duration":"162.358553ms","start":"2026-10-15T14:58:12.133902Z","end":"2026-10-15T14:58:12.296261Z","steps":["trace[1915100190] 'process raft request' (duration: 162.122217ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:12.29646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.531679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-10-15T14:58:12.296517Z","caller":"traceutil/trace.go:171","msg":"trace[771001154] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18920; }","duration":"139.645452ms","start":"2026-10-15T14:58:12.156859Z","end":"2026-10-15T14:58:12.296504Z","steps":["trace[771001154] 'agreement among raft nodes before linearized reading' (duration: 139.466187ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:12.429575Z","caller":"traceutil/trace.go:171","msg":"trace[1817637166] transaction","detail":"{read_only:false; response_revision:18921; number_of_response:1; }","duration":"125.287673ms","start":"2026-10-15T14:58:12.304255Z","end":"2026-10-15T14:58:12.429543Z","steps":["trace[1817637166] 'process raft request' (duration: 81.465178ms)","trace[1817637166] 'compare' (duration: 43.609569ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:58:12.443358Z","caller":"traceutil/trace.go:171","msg":"trace[862393504] transaction","detail":"{read_only:false; response_revision:18922; number_of_response:1; }","duration":"138.843292ms","start":"2026-10-15T14:58:12.304485Z","end":"2026-10-15T14:58:12.443329Z","steps":["trace[862393504] 'process raft request' (duration: 138.636807ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:12.443638Z","caller":"traceutil/trace.go:171","msg":"trace[85717668] transaction","detail":"{read_only:false; response_revision:18923; number_of_response:1; }","duration":"139.113778ms","start":"2026-10-15T14:58:12.304509Z","end":"2026-10-15T14:58:12.443623Z","steps":["trace[85717668] 'process raft request' (duration: 138.76022ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:12.44368Z","caller":"traceutil/trace.go:171","msg":"trace[134148051] transaction","detail":"{read_only:false; response_revision:18925; number_of_response:1; }","duration":"120.402774ms","start":"2026-10-15T14:58:12.323262Z","end":"2026-10-15T14:58:12.443665Z","steps":["trace[134148051] 'process raft request' (duration: 120.329403ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:12.4437Z","caller":"traceutil/trace.go:171","msg":"trace[965643719] transaction","detail":"{read_only:false; response_revision:18924; number_of_response:1; }","duration":"134.480876ms","start":"2026-10-15T14:58:12.309183Z","end":"2026-10-15T14:58:12.443664Z","steps":["trace[965643719] 'process raft request' (duration: 134.277452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:12.826301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.061957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:58:12.826427Z","caller":"traceutil/trace.go:171","msg":"trace[805792702] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:18926; }","duration":"174.216801ms","start":"2026-10-15T14:58:12.652189Z","end":"2026-10-15T14:58:12.826405Z","steps":["trace[805792702] 'count revisions from in-memory index tree' (duration: 173.954924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:12.826626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.203054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-15T14:58:12.826702Z","caller":"traceutil/trace.go:171","msg":"trace[1909247285] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:18926; }","duration":"275.326957ms","start":"2026-10-15T14:58:12.551355Z","end":"2026-10-15T14:58:12.826682Z","steps":["trace[1909247285] 'range keys from in-memory index tree' (duration: 275.023359ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:12.997722Z","caller":"traceutil/trace.go:171","msg":"trace[815678948] transaction","detail":"{read_only:false; response_revision:18928; number_of_response:1; }","duration":"123.164581ms","start":"2026-10-15T14:58:12.87453Z","end":"2026-10-15T14:58:12.997695Z","steps":["trace[815678948] 'process raft request' (duration: 122.906485ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:12.998005Z","caller":"traceutil/trace.go:171","msg":"trace[1715000481] transaction","detail":"{read_only:false; response_revision:18929; number_of_response:1; }","duration":"122.891585ms","start":"2026-10-15T14:58:12.875086Z","end":"2026-10-15T14:58:12.997977Z","steps":["trace[1715000481] 'process raft request' (duration: 122.550096ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:12.998199Z","caller":"traceutil/trace.go:171","msg":"trace[1319939177] transaction","detail":"{read_only:false; response_revision:18930; number_of_response:1; }","duration":"122.445903ms","start":"2026-10-15T14:58:12.875725Z","end":"2026-10-15T14:58:12.998171Z","steps":["trace[1319939177] 'process raft request' (duration: 122.175497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:13.591925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.801185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T14:58:13.592018Z","caller":"traceutil/trace.go:171","msg":"trace[645568807] linearizableReadLoop","detail":"{readStateIndex:19577; appliedIndex:19576; }","duration":"581.92234ms","start":"2026-10-15T14:58:13.010079Z","end":"2026-10-15T14:58:13.592001Z","steps":["trace[645568807] 'read index received' (duration: 269.008163ms)","trace[645568807] 'applied index is now lower than readState.Index' (duration: 312.912967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:58:13.592195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.116155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:58:13.592248Z","caller":"traceutil/trace.go:171","msg":"trace[1839012734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18931; }","duration":"582.228178ms","start":"2026-10-15T14:58:13.010006Z","end":"2026-10-15T14:58:13.592234Z","steps":["trace[1839012734] 'agreement among raft nodes before linearized reading' (duration: 582.126116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:13.592284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:58:13.009984Z","time spent":"582.29184ms","remote":"127.0.0.1:37788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T14:58:13.592345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.519252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-w7zxc\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-10-15T14:58:13.592384Z","caller":"traceutil/trace.go:171","msg":"trace[1033917993] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-w7zxc; range_end:; response_count:1; response_revision:18931; }","duration":"411.602044ms","start":"2026-10-15T14:58:13.180772Z","end":"2026-10-15T14:58:13.592374Z","steps":["trace[1033917993] 'agreement among raft nodes before linearized reading' (duration: 411.466331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:13.59241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:58:13.180748Z","time spent":"411.655996ms","remote":"127.0.0.1:37958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22148,"request content":"key:\"/registry/pods/openstack/nova-compute-default-w7zxc\" "} {"level":"info","ts":"2026-10-15T14:58:13.592399Z","caller":"traceutil/trace.go:171","msg":"trace[210169912] transaction","detail":"{read_only:false; response_revision:18931; number_of_response:1; }","duration":"589.935675ms","start":"2026-10-15T14:58:13.002451Z","end":"2026-10-15T14:58:13.592387Z","steps":["trace[210169912] 'process raft request' (duration: 276.591026ms)","trace[210169912] 'compare' (duration: 312.229922ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:58:13.592605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.365751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:58:13.592706Z","caller":"traceutil/trace.go:171","msg":"trace[1060202527] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:18931; }","duration":"101.486054ms","start":"2026-10-15T14:58:13.491198Z","end":"2026-10-15T14:58:13.592685Z","steps":["trace[1060202527] 'agreement among raft nodes before linearized reading' (duration: 101.30862ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:13.592981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:58:13.002438Z","time spent":"590.428887ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:58:27.67851Z","caller":"traceutil/trace.go:171","msg":"trace[1943585268] transaction","detail":"{read_only:false; response_revision:19151; number_of_response:1; }","duration":"104.865747ms","start":"2026-10-15T14:58:27.573618Z","end":"2026-10-15T14:58:27.678483Z","steps":["trace[1943585268] 'process raft request' (duration: 104.632681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:28.142755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.9085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:58:28.142843Z","caller":"traceutil/trace.go:171","msg":"trace[830101014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19159; }","duration":"132.006642ms","start":"2026-10-15T14:58:28.010815Z","end":"2026-10-15T14:58:28.142822Z","steps":["trace[830101014] 'range keys from in-memory index tree' (duration: 131.805098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:58:28.143071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.478163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:58:28.143196Z","caller":"traceutil/trace.go:171","msg":"trace[626494147] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:19159; }","duration":"139.031767ms","start":"2026-10-15T14:58:28.004139Z","end":"2026-10-15T14:58:28.143171Z","steps":["trace[626494147] 'count revisions from in-memory index tree' (duration: 138.303009ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:58:54.12202Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:58:54.129498Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T14:58:54.129681Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T14:58:59.03557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17004} {"level":"info","ts":"2026-10-15T14:58:59.131822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17004,"took":"94.099999ms","hash":2641852051,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":32833536,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T14:58:59.131914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2641852051,"revision":17004,"compact-revision":14541} {"level":"info","ts":"2026-10-15T15:03:58.084535Z","caller":"traceutil/trace.go:171","msg":"trace[1538055976] transaction","detail":"{read_only:false; response_revision:21684; number_of_response:1; }","duration":"168.914904ms","start":"2026-10-15T15:03:57.915594Z","end":"2026-10-15T15:03:58.084509Z","steps":["trace[1538055976] 'process raft request' (duration: 168.699909ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:58.227848Z","caller":"traceutil/trace.go:171","msg":"trace[56298372] transaction","detail":"{read_only:false; response_revision:21685; number_of_response:1; }","duration":"311.564077ms","start":"2026-10-15T15:03:57.916242Z","end":"2026-10-15T15:03:58.227806Z","steps":["trace[56298372] 'process raft request' (duration: 293.610852ms)","trace[56298372] 'compare' (duration: 17.498374ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:03:58.228035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:57.916227Z","time spent":"311.71392ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:03:58.229877Z","caller":"traceutil/trace.go:171","msg":"trace[726320563] linearizableReadLoop","detail":"{readStateIndex:22415; appliedIndex:22413; }","duration":"311.388142ms","start":"2026-10-15T15:03:57.918463Z","end":"2026-10-15T15:03:58.229852Z","steps":["trace[726320563] 'read index received' (duration: 165.927153ms)","trace[726320563] 'applied index is now lower than readState.Index' (duration: 145.459699ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:03:58.230351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.494954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T15:03:58.230393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.907744ms","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-10-15T15:03:58.230425Z","caller":"traceutil/trace.go:171","msg":"trace[1838251290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21685; }","duration":"220.637966ms","start":"2026-10-15T15:03:58.009769Z","end":"2026-10-15T15:03:58.230407Z","steps":["trace[1838251290] 'agreement among raft nodes before linearized reading' (duration: 220.477473ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:03:58.230454Z","caller":"traceutil/trace.go:171","msg":"trace[1173771180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21685; }","duration":"312.010407ms","start":"2026-10-15T15:03:57.918422Z","end":"2026-10-15T15:03:58.230433Z","steps":["trace[1173771180] 'agreement among raft nodes before linearized reading' (duration: 311.799442ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:58.230511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:57.918403Z","time spent":"312.093519ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T15:03:58.231292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.155494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-15T15:03:58.231404Z","caller":"traceutil/trace.go:171","msg":"trace[986243023] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:21685; }","duration":"302.319988ms","start":"2026-10-15T15:03:57.929063Z","end":"2026-10-15T15:03:58.231383Z","steps":["trace[986243023] 'agreement among raft nodes before linearized reading' (duration: 301.13618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:03:58.231452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:03:57.92904Z","time spent":"302.40148ms","remote":"127.0.0.1:37854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2190,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-10-15T15:03:59.041688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19367} {"level":"info","ts":"2026-10-15T15:03:59.161427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19367,"took":"116.433733ms","hash":767044035,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":30461952,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T15:03:59.161537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":767044035,"revision":19367,"compact-revision":17004} {"level":"info","ts":"2026-10-15T15:04:14.461388Z","caller":"traceutil/trace.go:171","msg":"trace[840535779] transaction","detail":"{read_only:false; response_revision:21770; number_of_response:1; }","duration":"144.421455ms","start":"2026-10-15T15:04:14.316934Z","end":"2026-10-15T15:04:14.461356Z","steps":["trace[840535779] 'process raft request' (duration: 113.841741ms)","trace[840535779] 'compare' (duration: 30.450531ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:04:14.824969Z","caller":"traceutil/trace.go:171","msg":"trace[425000332] transaction","detail":"{read_only:false; response_revision:21771; number_of_response:1; }","duration":"506.486106ms","start":"2026-10-15T15:04:14.318443Z","end":"2026-10-15T15:04:14.82493Z","steps":["trace[425000332] 'process raft request' (duration: 408.171221ms)","trace[425000332] 'compare' (duration: 98.028568ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:04:14.824984Z","caller":"traceutil/trace.go:171","msg":"trace[1245414016] transaction","detail":"{read_only:false; response_revision:21772; number_of_response:1; }","duration":"453.832081ms","start":"2026-10-15T15:04:14.371128Z","end":"2026-10-15T15:04:14.82496Z","steps":["trace[1245414016] 'process raft request' (duration: 453.704258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:04:14.825126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:04:14.371114Z","time spent":"453.935363ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:04:14.825136Z","caller":"traceutil/trace.go:171","msg":"trace[1561868011] transaction","detail":"{read_only:false; response_revision:21773; number_of_response:1; }","duration":"451.189588ms","start":"2026-10-15T15:04:14.373928Z","end":"2026-10-15T15:04:14.825118Z","steps":["trace[1561868011] 'process raft request' (duration: 450.990614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:04:14.825145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:04:14.318429Z","time spent":"506.614028ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:04:14.825235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:04:14.373904Z","time spent":"451.28174ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:04:14.825476Z","caller":"traceutil/trace.go:171","msg":"trace[1543073013] transaction","detail":"{read_only:false; response_revision:21774; number_of_response:1; }","duration":"449.871257ms","start":"2026-10-15T15:04:14.375561Z","end":"2026-10-15T15:04:14.825432Z","steps":["trace[1543073013] 'process raft request' (duration: 449.505198ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:04:14.825546Z","caller":"traceutil/trace.go:171","msg":"trace[782167921] linearizableReadLoop","detail":"{readStateIndex:22510; appliedIndex:22505; }","duration":"443.964857ms","start":"2026-10-15T15:04:14.381457Z","end":"2026-10-15T15:04:14.825422Z","steps":["trace[782167921] 'read index received' (duration: 49.333947ms)","trace[782167921] 'applied index is now lower than readState.Index' (duration: 394.62724ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:04:14.825621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:04:14.375546Z","time spent":"450.011611ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:04:14.825678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.186993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:04:14.825763Z","caller":"traceutil/trace.go:171","msg":"trace[1358086505] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:21774; }","duration":"444.314725ms","start":"2026-10-15T15:04:14.381427Z","end":"2026-10-15T15:04:14.825742Z","steps":["trace[1358086505] 'agreement among raft nodes before linearized reading' (duration: 444.12219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:04:14.825802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:04:14.381415Z","time spent":"444.377387ms","remote":"127.0.0.1:38060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":15,"response size":30,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:04:14.832572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.389944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-10-15T15:04:14.832633Z","caller":"traceutil/trace.go:171","msg":"trace[325511516] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:21775; }","duration":"369.489497ms","start":"2026-10-15T15:04:14.463126Z","end":"2026-10-15T15:04:14.832616Z","steps":["trace[325511516] 'agreement among raft nodes before linearized reading' (duration: 369.299612ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:04:14.832651Z","caller":"traceutil/trace.go:171","msg":"trace[576723379] transaction","detail":"{read_only:false; response_revision:21775; number_of_response:1; }","duration":"190.593417ms","start":"2026-10-15T15:04:14.642038Z","end":"2026-10-15T15:04:14.832632Z","steps":["trace[576723379] 'process raft request' (duration: 190.276199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:04:14.832665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:04:14.463106Z","time spent":"369.551958ms","remote":"127.0.0.1:37928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1872,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"info","ts":"2026-10-15T15:04:15.478292Z","caller":"traceutil/trace.go:171","msg":"trace[615641010] transaction","detail":"{read_only:false; response_revision:21776; number_of_response:1; }","duration":"180.050427ms","start":"2026-10-15T15:04:15.298218Z","end":"2026-10-15T15:04:15.478268Z","steps":["trace[615641010] 'process raft request' (duration: 179.892584ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:04:15.741184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.92692ms","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-10-15T15:04:15.741288Z","caller":"traceutil/trace.go:171","msg":"trace[684330096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21776; }","duration":"165.081413ms","start":"2026-10-15T15:04:15.576182Z","end":"2026-10-15T15:04:15.741263Z","steps":["trace[684330096] 'range keys from in-memory index tree' (duration: 164.696194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:04:15.742143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.225718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-10-15T15:04:15.742276Z","caller":"traceutil/trace.go:171","msg":"trace[930149871] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:21776; }","duration":"105.415413ms","start":"2026-10-15T15:04:15.636831Z","end":"2026-10-15T15:04:15.742247Z","steps":["trace[930149871] 'range keys from in-memory index tree' (duration: 103.640671ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:16.495129Z","caller":"traceutil/trace.go:171","msg":"trace[266468589] transaction","detail":"{read_only:false; response_revision:24297; number_of_response:1; }","duration":"108.03095ms","start":"2026-10-15T15:08:16.387068Z","end":"2026-10-15T15:08:16.495099Z","steps":["trace[266468589] 'process raft request' (duration: 107.800985ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:16.498401Z","caller":"traceutil/trace.go:171","msg":"trace[986114518] transaction","detail":"{read_only:false; response_revision:24298; number_of_response:1; }","duration":"107.62365ms","start":"2026-10-15T15:08:16.390757Z","end":"2026-10-15T15:08:16.498381Z","steps":["trace[986114518] 'process raft request' (duration: 107.439106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:08:16.87978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.680307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-15T15:08:16.879857Z","caller":"traceutil/trace.go:171","msg":"trace[2004326037] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24298; }","duration":"274.8141ms","start":"2026-10-15T15:08:16.605021Z","end":"2026-10-15T15:08:16.879835Z","steps":["trace[2004326037] 'range keys from in-memory index tree' (duration: 274.497992ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:16.898171Z","caller":"traceutil/trace.go:171","msg":"trace[1898775514] transaction","detail":"{read_only:false; response_revision:24299; number_of_response:1; }","duration":"181.757329ms","start":"2026-10-15T15:08:16.716386Z","end":"2026-10-15T15:08:16.898143Z","steps":["trace[1898775514] 'process raft request' (duration: 181.584275ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:16.921041Z","caller":"traceutil/trace.go:171","msg":"trace[1808987333] transaction","detail":"{read_only:false; response_revision:24300; number_of_response:1; }","duration":"202.146165ms","start":"2026-10-15T15:08:16.71887Z","end":"2026-10-15T15:08:16.921016Z","steps":["trace[1808987333] 'process raft request' (duration: 201.962061ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:16.921306Z","caller":"traceutil/trace.go:171","msg":"trace[708208314] transaction","detail":"{read_only:false; response_revision:24301; number_of_response:1; }","duration":"173.683901ms","start":"2026-10-15T15:08:16.747603Z","end":"2026-10-15T15:08:16.921287Z","steps":["trace[708208314] 'process raft request' (duration: 173.367103ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:16.921411Z","caller":"traceutil/trace.go:171","msg":"trace[1693128878] linearizableReadLoop","detail":"{readStateIndex:25111; appliedIndex:25109; }","duration":"200.536047ms","start":"2026-10-15T15:08:16.720844Z","end":"2026-10-15T15:08:16.92138Z","steps":["trace[1693128878] 'read index received' (duration: 177.142111ms)","trace[1693128878] 'applied index is now lower than readState.Index' (duration: 23.392676ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:08:16.921631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.773843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:08:16.921679Z","caller":"traceutil/trace.go:171","msg":"trace[819328282] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24302; }","duration":"200.872825ms","start":"2026-10-15T15:08:16.720791Z","end":"2026-10-15T15:08:16.921664Z","steps":["trace[819328282] 'agreement among raft nodes before linearized reading' (duration: 200.692761ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:59.048013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21690} {"level":"info","ts":"2026-10-15T15:08:59.158232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21690,"took":"107.339804ms","hash":1359443323,"current-db-size-bytes":55287808,"current-db-size":"55 MB","current-db-size-in-use-bytes":42610688,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-15T15:08:59.158298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1359443323,"revision":21690,"compact-revision":19367} {"level":"info","ts":"2026-10-15T15:09:56.98405Z","caller":"traceutil/trace.go:171","msg":"trace[1028275985] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"138.436912ms","start":"2026-10-15T15:09:56.845587Z","end":"2026-10-15T15:09:56.984024Z","steps":["trace[1028275985] 'process raft request' (duration: 138.255117ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:41.871446Z","caller":"traceutil/trace.go:171","msg":"trace[1024716389] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"128.9542ms","start":"2026-10-15T15:10:41.742466Z","end":"2026-10-15T15:10:41.87142Z","steps":["trace[1024716389] 'process raft request' (duration: 128.781166ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:41.883092Z","caller":"traceutil/trace.go:171","msg":"trace[1381203809] linearizableReadLoop","detail":"{readStateIndex:26608; appliedIndex:26606; }","duration":"112.768575ms","start":"2026-10-15T15:10:41.770305Z","end":"2026-10-15T15:10:41.883074Z","steps":["trace[1381203809] 'read index received' (duration: 100.957905ms)","trace[1381203809] 'applied index is now lower than readState.Index' (duration: 11.80961ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:10:41.883355Z","caller":"traceutil/trace.go:171","msg":"trace[1021336402] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"118.342968ms","start":"2026-10-15T15:10:41.764997Z","end":"2026-10-15T15:10:41.88334Z","steps":["trace[1021336402] 'process raft request' (duration: 117.992739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:10:41.883644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.304528ms","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-10-15T15:10:41.883683Z","caller":"traceutil/trace.go:171","msg":"trace[1871641190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25759; }","duration":"113.411671ms","start":"2026-10-15T15:10:41.770262Z","end":"2026-10-15T15:10:41.883674Z","steps":["trace[1871641190] 'agreement among raft nodes before linearized reading' (duration: 113.233286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:10:41.883866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.611937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T15:10:41.883898Z","caller":"traceutil/trace.go:171","msg":"trace[882189948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25759; }","duration":"108.664228ms","start":"2026-10-15T15:10:41.775225Z","end":"2026-10-15T15:10:41.883889Z","steps":["trace[882189948] 'agreement among raft nodes before linearized reading' (duration: 108.584366ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:41.991935Z","caller":"traceutil/trace.go:171","msg":"trace[363083321] linearizableReadLoop","detail":"{readStateIndex:26610; appliedIndex:26608; }","duration":"103.323232ms","start":"2026-10-15T15:10:41.88859Z","end":"2026-10-15T15:10:41.991913Z","steps":["trace[363083321] 'read index received' (duration: 39.785754ms)","trace[363083321] 'applied index is now lower than readState.Index' (duration: 63.536468ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:10:41.992017Z","caller":"traceutil/trace.go:171","msg":"trace[1080230601] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"103.71086ms","start":"2026-10-15T15:10:41.888264Z","end":"2026-10-15T15:10:41.991975Z","steps":["trace[1080230601] 'process raft request' (duration: 54.315398ms)","trace[1080230601] 'compare' (duration: 48.87084ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:10:41.992223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.620879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19234"} {"level":"info","ts":"2026-10-15T15:10:41.992262Z","caller":"traceutil/trace.go:171","msg":"trace[1048955678] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25762; }","duration":"103.704511ms","start":"2026-10-15T15:10:41.888548Z","end":"2026-10-15T15:10:41.992252Z","steps":["trace[1048955678] 'agreement among raft nodes before linearized reading' (duration: 103.485026ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:10:43.680635Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006801.wal"} {"level":"info","ts":"2026-10-15T15:11:38.231546Z","caller":"traceutil/trace.go:171","msg":"trace[1023018231] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"105.881674ms","start":"2026-10-15T15:11:38.125639Z","end":"2026-10-15T15:11:38.231521Z","steps":["trace[1023018231] 'process raft request' (duration: 105.73353ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:38.244557Z","caller":"traceutil/trace.go:171","msg":"trace[1242504874] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"105.497414ms","start":"2026-10-15T15:11:38.139031Z","end":"2026-10-15T15:11:38.244529Z","steps":["trace[1242504874] 'process raft request' (duration: 105.233237ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:43.77848Z","caller":"traceutil/trace.go:171","msg":"trace[1778126156] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"105.080944ms","start":"2026-10-15T15:11:43.673376Z","end":"2026-10-15T15:11:43.778457Z","steps":["trace[1778126156] 'process raft request' (duration: 104.469989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:44.691266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.060293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:11:44.691623Z","caller":"traceutil/trace.go:171","msg":"trace[660209872] linearizableReadLoop","detail":"{readStateIndex:27152; appliedIndex:27150; }","duration":"454.011841ms","start":"2026-10-15T15:11:44.237588Z","end":"2026-10-15T15:11:44.6916Z","steps":["trace[660209872] 'read index received' (duration: 275.166692ms)","trace[660209872] 'applied index is now lower than readState.Index' (duration: 178.843889ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:11:44.691632Z","caller":"traceutil/trace.go:171","msg":"trace[415093251] transaction","detail":"{read_only:false; response_revision:26288; number_of_response:1; }","duration":"495.824472ms","start":"2026-10-15T15:11:44.195758Z","end":"2026-10-15T15:11:44.691582Z","steps":["trace[415093251] 'process raft request' (duration: 28.333738ms)","trace[415093251] 'compare' (duration: 466.161112ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:11:44.691726Z","caller":"traceutil/trace.go:171","msg":"trace[384990748] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"483.960018ms","start":"2026-10-15T15:11:44.207752Z","end":"2026-10-15T15:11:44.691712Z","steps":["trace[384990748] 'process raft request' (duration: 483.734362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:44.691884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.347739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19234"} {"level":"warn","ts":"2026-10-15T15:11:44.693058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.195739Z","time spent":"497.237365ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:11:44.69326Z","caller":"traceutil/trace.go:171","msg":"trace[1824242967] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26289; }","duration":"455.747623ms","start":"2026-10-15T15:11:44.237473Z","end":"2026-10-15T15:11:44.69322Z","steps":["trace[1824242967] 'agreement among raft nodes before linearized reading' (duration: 454.241977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:44.693244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.207733Z","time spent":"485.412473ms","remote":"127.0.0.1:52892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2333,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T15:11:44.693346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.237455Z","time spent":"455.867385ms","remote":"127.0.0.1:37956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19256,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-15T15:11:44.998133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.017125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:11:44.998384Z","caller":"traceutil/trace.go:171","msg":"trace[1606314584] transaction","detail":"{read_only:false; response_revision:26290; number_of_response:1; }","duration":"737.553815ms","start":"2026-10-15T15:11:44.260748Z","end":"2026-10-15T15:11:44.998302Z","steps":["trace[1606314584] 'process raft request' (duration: 583.277774ms)","trace[1606314584] 'compare' (duration: 153.770389ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:11:44.998477Z","caller":"traceutil/trace.go:171","msg":"trace[473149193] transaction","detail":"{read_only:false; response_revision:26292; number_of_response:1; }","duration":"681.812621ms","start":"2026-10-15T15:11:44.316647Z","end":"2026-10-15T15:11:44.99846Z","steps":["trace[473149193] 'process raft request' (duration: 681.719369ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:44.998477Z","caller":"traceutil/trace.go:171","msg":"trace[465737087] transaction","detail":"{read_only:false; response_revision:26291; number_of_response:1; }","duration":"696.244446ms","start":"2026-10-15T15:11:44.302203Z","end":"2026-10-15T15:11:44.998448Z","steps":["trace[465737087] 'process raft request' (duration: 696.073662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:44.998545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.316631Z","time spent":"681.881543ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:11:44.998615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.302179Z","time spent":"696.359719ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:11:44.998773Z","caller":"traceutil/trace.go:171","msg":"trace[433200809] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"258.806731ms","start":"2026-10-15T15:11:44.739941Z","end":"2026-10-15T15:11:44.998747Z","steps":["trace[433200809] 'process raft request' (duration: 258.705039ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:44.998803Z","caller":"traceutil/trace.go:171","msg":"trace[1176406511] transaction","detail":"{read_only:false; response_revision:26293; number_of_response:1; }","duration":"359.680525ms","start":"2026-10-15T15:11:44.639066Z","end":"2026-10-15T15:11:44.998746Z","steps":["trace[1176406511] 'process raft request' (duration: 359.363208ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:44.998852Z","caller":"traceutil/trace.go:171","msg":"trace[865710773] transaction","detail":"{read_only:false; response_revision:26295; number_of_response:1; }","duration":"246.582199ms","start":"2026-10-15T15:11:44.752253Z","end":"2026-10-15T15:11:44.998835Z","steps":["trace[865710773] 'process raft request' (duration: 246.456786ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:44.998808Z","caller":"traceutil/trace.go:171","msg":"trace[382046949] linearizableReadLoop","detail":"{readStateIndex:27156; appliedIndex:27152; }","duration":"307.115087ms","start":"2026-10-15T15:11:44.691673Z","end":"2026-10-15T15:11:44.998788Z","steps":["trace[382046949] 'read index received' (duration: 152.57634ms)","trace[382046949] 'applied index is now lower than readState.Index' (duration: 154.537827ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:11:44.998992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.651142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-15T15:11:44.999012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.639043Z","time spent":"359.845289ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:11:44.999036Z","caller":"traceutil/trace.go:171","msg":"trace[1831356063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26295; }","duration":"672.735674ms","start":"2026-10-15T15:11:44.326289Z","end":"2026-10-15T15:11:44.999024Z","steps":["trace[1831356063] 'agreement among raft nodes before linearized reading' (duration: 672.551019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:44.99907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.326276Z","time spent":"672.787046ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T15:11:44.999097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.626409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:11:44.99915Z","caller":"traceutil/trace.go:171","msg":"trace[326399255] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26295; }","duration":"488.704061ms","start":"2026-10-15T15:11:44.510429Z","end":"2026-10-15T15:11:44.999133Z","steps":["trace[326399255] 'agreement among raft nodes before linearized reading' (duration: 488.621009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:44.999187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.510414Z","time spent":"488.764552ms","remote":"127.0.0.1:34260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:11:44.999229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.33718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-15T15:11:44.999239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.367016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19234"} {"level":"info","ts":"2026-10-15T15:11:44.99926Z","caller":"traceutil/trace.go:171","msg":"trace[2055638907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26295; }","duration":"592.407012ms","start":"2026-10-15T15:11:44.406845Z","end":"2026-10-15T15:11:44.999252Z","steps":["trace[2055638907] 'agreement among raft nodes before linearized reading' (duration: 592.32029ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:44.999272Z","caller":"traceutil/trace.go:171","msg":"trace[1672107725] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26295; }","duration":"300.433337ms","start":"2026-10-15T15:11:44.698829Z","end":"2026-10-15T15:11:44.999262Z","steps":["trace[1672107725] 'agreement among raft nodes before linearized reading' (duration: 300.296204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:44.999287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.406826Z","time spent":"592.455964ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T15:11:44.999299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.698819Z","time spent":"300.474148ms","remote":"127.0.0.1:37956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19256,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-15T15:11:44.999448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.96839ms","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-10-15T15:11:44.999479Z","caller":"traceutil/trace.go:171","msg":"trace[891910939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26295; }","duration":"181.039381ms","start":"2026-10-15T15:11:44.818431Z","end":"2026-10-15T15:11:44.999471Z","steps":["trace[891910939] 'agreement among raft nodes before linearized reading' (duration: 180.935759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:44.999668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:44.260728Z","time spent":"738.842346ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:11:45.185122Z","caller":"traceutil/trace.go:171","msg":"trace[1588408672] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"179.588526ms","start":"2026-10-15T15:11:45.005505Z","end":"2026-10-15T15:11:45.185093Z","steps":["trace[1588408672] 'process raft request' (duration: 103.149567ms)","trace[1588408672] 'compare' (duration: 76.216054ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:11:45.271824Z","caller":"traceutil/trace.go:171","msg":"trace[1526629201] transaction","detail":"{read_only:false; response_revision:26298; number_of_response:1; }","duration":"264.978289ms","start":"2026-10-15T15:11:45.006816Z","end":"2026-10-15T15:11:45.271794Z","steps":["trace[1526629201] 'process raft request' (duration: 264.887147ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:45.271839Z","caller":"traceutil/trace.go:171","msg":"trace[407016089] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"265.322507ms","start":"2026-10-15T15:11:45.006466Z","end":"2026-10-15T15:11:45.271789Z","steps":["trace[407016089] 'process raft request' (duration: 265.073371ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:45.272137Z","caller":"traceutil/trace.go:171","msg":"trace[369742962] transaction","detail":"{read_only:false; response_revision:26299; number_of_response:1; }","duration":"264.000605ms","start":"2026-10-15T15:11:45.008098Z","end":"2026-10-15T15:11:45.272098Z","steps":["trace[369742962] 'process raft request' (duration: 263.655107ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:45.448204Z","caller":"traceutil/trace.go:171","msg":"trace[1635450464] transaction","detail":"{read_only:false; response_revision:26300; number_of_response:1; }","duration":"146.669359ms","start":"2026-10-15T15:11:45.301493Z","end":"2026-10-15T15:11:45.448162Z","steps":["trace[1635450464] 'process raft request' (duration: 90.815902ms)","trace[1635450464] 'compare' (duration: 55.57861ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:11:45.579644Z","caller":"traceutil/trace.go:171","msg":"trace[988672692] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"122.242484ms","start":"2026-10-15T15:11:45.457375Z","end":"2026-10-15T15:11:45.579617Z","steps":["trace[988672692] 'process raft request' (duration: 106.455877ms)","trace[988672692] 'compare' (duration: 15.580072ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:11:45.580375Z","caller":"traceutil/trace.go:171","msg":"trace[1170798891] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"117.88104ms","start":"2026-10-15T15:11:45.462481Z","end":"2026-10-15T15:11:45.580362Z","steps":["trace[1170798891] 'process raft request' (duration: 117.086401ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:45.580699Z","caller":"traceutil/trace.go:171","msg":"trace[1532860668] transaction","detail":"{read_only:false; response_revision:26305; number_of_response:1; }","duration":"101.857777ms","start":"2026-10-15T15:11:45.478818Z","end":"2026-10-15T15:11:45.580676Z","steps":["trace[1532860668] 'process raft request' (duration: 101.300083ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:45.738466Z","caller":"traceutil/trace.go:171","msg":"trace[835887237] transaction","detail":"{read_only:false; response_revision:26307; number_of_response:1; }","duration":"143.265927ms","start":"2026-10-15T15:11:45.595175Z","end":"2026-10-15T15:11:45.738441Z","steps":["trace[835887237] 'process raft request' (duration: 143.101893ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:45.884896Z","caller":"traceutil/trace.go:171","msg":"trace[1462836010] linearizableReadLoop","detail":"{readStateIndex:27172; appliedIndex:27169; }","duration":"155.784437ms","start":"2026-10-15T15:11:45.729076Z","end":"2026-10-15T15:11:45.884861Z","steps":["trace[1462836010] 'read index received' (duration: 9.148859ms)","trace[1462836010] 'applied index is now lower than readState.Index' (duration: 146.633648ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:11:45.885057Z","caller":"traceutil/trace.go:171","msg":"trace[51177915] transaction","detail":"{read_only:false; response_revision:26308; number_of_response:1; }","duration":"282.208241ms","start":"2026-10-15T15:11:45.602679Z","end":"2026-10-15T15:11:45.884888Z","steps":["trace[51177915] 'process raft request' (duration: 275.816239ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:45.885082Z","caller":"traceutil/trace.go:171","msg":"trace[717909861] transaction","detail":"{read_only:false; number_of_response:1; response_revision:26308; }","duration":"281.431082ms","start":"2026-10-15T15:11:45.603618Z","end":"2026-10-15T15:11:45.885049Z","steps":["trace[717909861] 'process raft request' (duration: 281.129445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:45.885155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.035792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:11:45.885207Z","caller":"traceutil/trace.go:171","msg":"trace[1360885347] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:26308; }","duration":"156.202497ms","start":"2026-10-15T15:11:45.728992Z","end":"2026-10-15T15:11:45.885195Z","steps":["trace[1360885347] 'agreement among raft nodes before linearized reading' (duration: 156.042243ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:45.901892Z","caller":"traceutil/trace.go:171","msg":"trace[2123496978] transaction","detail":"{read_only:false; response_revision:26309; number_of_response:1; }","duration":"144.486097ms","start":"2026-10-15T15:11:45.757376Z","end":"2026-10-15T15:11:45.901862Z","steps":["trace[2123496978] 'process raft request' (duration: 144.272801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:45.901919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.625417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-conductor-0\" ","response":"range_response_count:1 size:9857"} {"level":"info","ts":"2026-10-15T15:11:45.901981Z","caller":"traceutil/trace.go:171","msg":"trace[415997271] range","detail":"{range_begin:/registry/pods/openstack/magnum-conductor-0; range_end:; response_count:1; response_revision:26309; }","duration":"146.738049ms","start":"2026-10-15T15:11:45.755228Z","end":"2026-10-15T15:11:45.901966Z","steps":["trace[415997271] 'agreement among raft nodes before linearized reading' (duration: 146.548315ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:46.013018Z","caller":"traceutil/trace.go:171","msg":"trace[1287767623] transaction","detail":"{read_only:false; response_revision:26310; number_of_response:1; }","duration":"122.045989ms","start":"2026-10-15T15:11:45.890946Z","end":"2026-10-15T15:11:46.012992Z","steps":["trace[1287767623] 'process raft request' (duration: 121.66243ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:46.013065Z","caller":"traceutil/trace.go:171","msg":"trace[1600737587] transaction","detail":"{read_only:false; response_revision:26311; number_of_response:1; }","duration":"114.428286ms","start":"2026-10-15T15:11:45.898618Z","end":"2026-10-15T15:11:46.013046Z","steps":["trace[1600737587] 'process raft request' (duration: 114.256902ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:46.097431Z","caller":"traceutil/trace.go:171","msg":"trace[1233151337] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"191.810159ms","start":"2026-10-15T15:11:45.90559Z","end":"2026-10-15T15:11:46.097401Z","steps":["trace[1233151337] 'process raft request' (duration: 191.567263ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:46.097682Z","caller":"traceutil/trace.go:171","msg":"trace[941732131] transaction","detail":"{read_only:false; response_revision:26313; number_of_response:1; }","duration":"184.681728ms","start":"2026-10-15T15:11:45.912955Z","end":"2026-10-15T15:11:46.097637Z","steps":["trace[941732131] 'process raft request' (duration: 184.31974ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:46.191101Z","caller":"traceutil/trace.go:171","msg":"trace[543482287] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"142.415996ms","start":"2026-10-15T15:11:46.048656Z","end":"2026-10-15T15:11:46.191072Z","steps":["trace[543482287] 'process raft request' (duration: 128.514264ms)","trace[543482287] 'compare' (duration: 13.675967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:11:46.204343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.58989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-867f898947-lw5h9\" ","response":"range_response_count:1 size:8493"} {"level":"info","ts":"2026-10-15T15:11:46.204356Z","caller":"traceutil/trace.go:171","msg":"trace[132532493] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"100.177697ms","start":"2026-10-15T15:11:46.104154Z","end":"2026-10-15T15:11:46.204331Z","steps":["trace[132532493] 'process raft request' (duration: 99.90344ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:46.204423Z","caller":"traceutil/trace.go:171","msg":"trace[1065401207] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-867f898947-lw5h9; range_end:; response_count:1; response_revision:26315; }","duration":"101.717083ms","start":"2026-10-15T15:11:46.102688Z","end":"2026-10-15T15:11:46.204405Z","steps":["trace[1065401207] 'agreement among raft nodes before linearized reading' (duration: 101.528969ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:46.319502Z","caller":"traceutil/trace.go:171","msg":"trace[1194674807] transaction","detail":"{read_only:false; response_revision:26317; number_of_response:1; }","duration":"110.7904ms","start":"2026-10-15T15:11:46.208691Z","end":"2026-10-15T15:11:46.319481Z","steps":["trace[1194674807] 'process raft request' (duration: 109.365686ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:46.319749Z","caller":"traceutil/trace.go:171","msg":"trace[711198754] transaction","detail":"{read_only:false; response_revision:26318; number_of_response:1; }","duration":"103.777562ms","start":"2026-10-15T15:11:46.215946Z","end":"2026-10-15T15:11:46.319723Z","steps":["trace[711198754] 'process raft request' (duration: 103.476855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:46.495801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.167037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:11:46.495886Z","caller":"traceutil/trace.go:171","msg":"trace[251007804] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:26327; }","duration":"100.291779ms","start":"2026-10-15T15:11:46.395571Z","end":"2026-10-15T15:11:46.495863Z","steps":["trace[251007804] 'agreement among raft nodes before linearized reading' (duration: 100.105745ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:46.630571Z","caller":"traceutil/trace.go:171","msg":"trace[738715306] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"115.228257ms","start":"2026-10-15T15:11:46.515317Z","end":"2026-10-15T15:11:46.630546Z","steps":["trace[738715306] 'process raft request' (duration: 115.004812ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:57.649607Z","caller":"traceutil/trace.go:171","msg":"trace[1717147169] transaction","detail":"{read_only:false; response_revision:26437; number_of_response:1; }","duration":"154.608438ms","start":"2026-10-15T15:11:57.494975Z","end":"2026-10-15T15:11:57.649583Z","steps":["trace[1717147169] 'process raft request' (duration: 154.431564ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:57.870657Z","caller":"traceutil/trace.go:171","msg":"trace[607308427] transaction","detail":"{read_only:false; response_revision:26439; number_of_response:1; }","duration":"193.064409ms","start":"2026-10-15T15:11:57.677558Z","end":"2026-10-15T15:11:57.870622Z","steps":["trace[607308427] 'process raft request' (duration: 155.657744ms)","trace[607308427] 'compare' (duration: 37.284152ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:11:58.470015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.642735ms","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-10-15T15:11:58.470107Z","caller":"traceutil/trace.go:171","msg":"trace[324696892] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26440; }","duration":"275.801558ms","start":"2026-10-15T15:11:58.19428Z","end":"2026-10-15T15:11:58.470082Z","steps":["trace[324696892] 'count revisions from in-memory index tree' (duration: 275.510991ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:58.746754Z","caller":"traceutil/trace.go:171","msg":"trace[1233893174] linearizableReadLoop","detail":"{readStateIndex:27309; appliedIndex:27308; }","duration":"148.322169ms","start":"2026-10-15T15:11:58.598411Z","end":"2026-10-15T15:11:58.746733Z","steps":["trace[1233893174] 'read index received' (duration: 148.197016ms)","trace[1233893174] 'applied index is now lower than readState.Index' (duration: 124.123µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:11:58.746815Z","caller":"traceutil/trace.go:171","msg":"trace[259893163] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"342.489233ms","start":"2026-10-15T15:11:58.404296Z","end":"2026-10-15T15:11:58.746785Z","steps":["trace[259893163] 'process raft request' (duration: 342.290628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:58.746879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.450752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:11:58.746907Z","caller":"traceutil/trace.go:171","msg":"trace[94785896] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:26441; }","duration":"148.535034ms","start":"2026-10-15T15:11:58.598363Z","end":"2026-10-15T15:11:58.746898Z","steps":["trace[94785896] 'agreement among raft nodes before linearized reading' (duration: 148.447782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:11:58.746967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:11:58.404271Z","time spent":"342.610966ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:12:53.722433Z","caller":"traceutil/trace.go:171","msg":"trace[1246626187] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"157.130691ms","start":"2026-10-15T15:12:53.565278Z","end":"2026-10-15T15:12:53.722409Z","steps":["trace[1246626187] 'process raft request' (duration: 156.770132ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:12:59.153458Z","caller":"traceutil/trace.go:171","msg":"trace[1364653755] linearizableReadLoop","detail":"{readStateIndex:27872; appliedIndex:27871; }","duration":"143.161607ms","start":"2026-10-15T15:12:59.010272Z","end":"2026-10-15T15:12:59.153433Z","steps":["trace[1364653755] 'read index received' (duration: 142.915481ms)","trace[1364653755] 'applied index is now lower than readState.Index' (duration: 244.776µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:12:59.15487Z","caller":"traceutil/trace.go:171","msg":"trace[1925444814] transaction","detail":"{read_only:false; response_revision:26990; number_of_response:1; }","duration":"160.796578ms","start":"2026-10-15T15:12:58.994033Z","end":"2026-10-15T15:12:59.15483Z","steps":["trace[1925444814] 'process raft request' (duration: 159.21471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:12:59.155013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.736975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:12:59.155067Z","caller":"traceutil/trace.go:171","msg":"trace[1573371182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26990; }","duration":"144.843298ms","start":"2026-10-15T15:12:59.010209Z","end":"2026-10-15T15:12:59.155052Z","steps":["trace[1573371182] 'agreement among raft nodes before linearized reading' (duration: 143.363352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:12:59.579031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.908624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:12:59.579111Z","caller":"traceutil/trace.go:171","msg":"trace[630418502] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26990; }","duration":"317.040828ms","start":"2026-10-15T15:12:59.262053Z","end":"2026-10-15T15:12:59.579094Z","steps":["trace[630418502] 'count revisions from in-memory index tree' (duration: 316.804642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:12:59.579148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:12:59.262024Z","time spent":"317.11467ms","remote":"127.0.0.1:38114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":126,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:12:59.579147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.891996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:12:59.579373Z","caller":"traceutil/trace.go:171","msg":"trace[1956744399] transaction","detail":"{read_only:false; response_revision:26991; number_of_response:1; }","duration":"272.051634ms","start":"2026-10-15T15:12:59.307219Z","end":"2026-10-15T15:12:59.579271Z","steps":["trace[1956744399] 'process raft request' (duration: 70.945883ms)","trace[1956744399] 'compare' (duration: 200.63532ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:12:59.592721Z","caller":"traceutil/trace.go:171","msg":"trace[1029061247] transaction","detail":"{read_only:false; response_revision:26992; number_of_response:1; }","duration":"257.157938ms","start":"2026-10-15T15:12:59.335529Z","end":"2026-10-15T15:12:59.592687Z","steps":["trace[1029061247] 'process raft request' (duration: 256.933563ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:12:59.592765Z","caller":"traceutil/trace.go:171","msg":"trace[672304438] transaction","detail":"{read_only:false; response_revision:26995; number_of_response:1; }","duration":"248.680736ms","start":"2026-10-15T15:12:59.344062Z","end":"2026-10-15T15:12:59.592743Z","steps":["trace[672304438] 'process raft request' (duration: 248.622325ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:12:59.592811Z","caller":"traceutil/trace.go:171","msg":"trace[1031407445] transaction","detail":"{read_only:false; response_revision:26993; number_of_response:1; }","duration":"255.319214ms","start":"2026-10-15T15:12:59.337452Z","end":"2026-10-15T15:12:59.592771Z","steps":["trace[1031407445] 'process raft request' (duration: 255.1284ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:12:59.592834Z","caller":"traceutil/trace.go:171","msg":"trace[2035623115] transaction","detail":"{read_only:false; response_revision:26994; number_of_response:1; }","duration":"253.699066ms","start":"2026-10-15T15:12:59.339107Z","end":"2026-10-15T15:12:59.592806Z","steps":["trace[2035623115] 'process raft request' (duration: 253.529252ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:12:59.594326Z","caller":"traceutil/trace.go:171","msg":"trace[332812309] linearizableReadLoop","detail":"{readStateIndex:27878; appliedIndex:27874; }","duration":"111.469191ms","start":"2026-10-15T15:12:59.482835Z","end":"2026-10-15T15:12:59.594304Z","steps":["trace[332812309] 'read index received' (duration: 109.78138ms)","trace[332812309] 'applied index is now lower than readState.Index' (duration: 1.686531ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:12:59.594419Z","caller":"traceutil/trace.go:171","msg":"trace[737756672] transaction","detail":"{read_only:false; response_revision:26996; number_of_response:1; }","duration":"182.553618ms","start":"2026-10-15T15:12:59.411847Z","end":"2026-10-15T15:12:59.594401Z","steps":["trace[737756672] 'process raft request' (duration: 182.363693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:12:59.594453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.599054ms","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-10-15T15:12:59.594487Z","caller":"traceutil/trace.go:171","msg":"trace[638047628] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26996; }","duration":"111.678876ms","start":"2026-10-15T15:12:59.482795Z","end":"2026-10-15T15:12:59.594474Z","steps":["trace[638047628] 'agreement among raft nodes before linearized reading' (duration: 111.609074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:12:59.900795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.262316ms","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-10-15T15:12:59.900873Z","caller":"traceutil/trace.go:171","msg":"trace[300537427] 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:26996; }","duration":"119.40805ms","start":"2026-10-15T15:12:59.781444Z","end":"2026-10-15T15:12:59.900852Z","steps":["trace[300537427] 'count revisions from in-memory index tree' (duration: 119.103513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:12:59.90121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.541836ms","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-10-15T15:12:59.901247Z","caller":"traceutil/trace.go:171","msg":"trace[1095707273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26996; }","duration":"148.618068ms","start":"2026-10-15T15:12:59.75262Z","end":"2026-10-15T15:12:59.901238Z","steps":["trace[1095707273] 'range keys from in-memory index tree' (duration: 148.349011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:12:59.901327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.97349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-15T15:12:59.901376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.815493ms","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-10-15T15:12:59.901405Z","caller":"traceutil/trace.go:171","msg":"trace[781481645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26996; }","duration":"158.121095ms","start":"2026-10-15T15:12:59.743266Z","end":"2026-10-15T15:12:59.901387Z","steps":["trace[781481645] 'range keys from in-memory index tree' (duration: 157.772775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:12:59.901395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.870204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T15:12:59.901429Z","caller":"traceutil/trace.go:171","msg":"trace[99998210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26996; }","duration":"143.926195ms","start":"2026-10-15T15:12:59.757489Z","end":"2026-10-15T15:12:59.901415Z","steps":["trace[99998210] 'range keys from in-memory index tree' (duration: 143.647029ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:12:59.90146Z","caller":"traceutil/trace.go:171","msg":"trace[397921161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26996; }","duration":"143.971306ms","start":"2026-10-15T15:12:59.757472Z","end":"2026-10-15T15:12:59.901443Z","steps":["trace[397921161] 'range keys from in-memory index tree' (duration: 143.612538ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:13:00.291004Z","caller":"traceutil/trace.go:171","msg":"trace[1203519040] transaction","detail":"{read_only:false; response_revision:26997; number_of_response:1; }","duration":"393.141094ms","start":"2026-10-15T15:12:59.897833Z","end":"2026-10-15T15:13:00.290974Z","steps":["trace[1203519040] 'process raft request' (duration: 392.96092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:13:00.291202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:12:59.897811Z","time spent":"393.277458ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:13:00.293791Z","caller":"traceutil/trace.go:171","msg":"trace[1469360709] transaction","detail":"{read_only:false; response_revision:26998; number_of_response:1; }","duration":"392.870548ms","start":"2026-10-15T15:12:59.900893Z","end":"2026-10-15T15:13:00.293763Z","steps":["trace[1469360709] 'process raft request' (duration: 392.223642ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:13:00.293927Z","caller":"traceutil/trace.go:171","msg":"trace[499731224] linearizableReadLoop","detail":"{readStateIndex:27884; appliedIndex:27879; }","duration":"282.425921ms","start":"2026-10-15T15:13:00.011482Z","end":"2026-10-15T15:13:00.293908Z","steps":["trace[499731224] 'read index received' (duration: 280.040394ms)","trace[499731224] 'applied index is now lower than readState.Index' (duration: 2.384517ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:13:00.293944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:12:59.900867Z","time spent":"392.993291ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:13:00.294144Z","caller":"traceutil/trace.go:171","msg":"trace[376141097] transaction","detail":"{read_only:false; response_revision:27002; number_of_response:1; }","duration":"382.88913ms","start":"2026-10-15T15:12:59.911244Z","end":"2026-10-15T15:13:00.294133Z","steps":["trace[376141097] 'process raft request' (duration: 382.615063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:13:00.294208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:12:59.911224Z","time spent":"382.952331ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:13:00.294307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.793391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:13:00.294367Z","caller":"traceutil/trace.go:171","msg":"trace[1964302803] transaction","detail":"{read_only:false; response_revision:26999; number_of_response:1; }","duration":"387.972951ms","start":"2026-10-15T15:12:59.906382Z","end":"2026-10-15T15:13:00.294355Z","steps":["trace[1964302803] 'process raft request' (duration: 386.846594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:13:00.294428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:12:59.90636Z","time spent":"388.039942ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:13:00.294544Z","caller":"traceutil/trace.go:171","msg":"trace[631633035] transaction","detail":"{read_only:false; response_revision:27000; number_of_response:1; }","duration":"387.384807ms","start":"2026-10-15T15:12:59.907148Z","end":"2026-10-15T15:13:00.294533Z","steps":["trace[631633035] 'process raft request' (duration: 386.633959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:13:00.294602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:12:59.907138Z","time spent":"387.435288ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:13:00.294631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.744594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-15T15:13:00.294691Z","caller":"traceutil/trace.go:171","msg":"trace[1561025602] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:27002; }","duration":"198.848637ms","start":"2026-10-15T15:13:00.095829Z","end":"2026-10-15T15:13:00.294678Z","steps":["trace[1561025602] 'agreement among raft nodes before linearized reading' (duration: 198.57391ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:13:00.294731Z","caller":"traceutil/trace.go:171","msg":"trace[2056547632] transaction","detail":"{read_only:false; response_revision:27001; number_of_response:1; }","duration":"386.789083ms","start":"2026-10-15T15:12:59.907931Z","end":"2026-10-15T15:13:00.29472Z","steps":["trace[2056547632] 'process raft request' (duration: 385.886451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:13:00.294794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:12:59.90792Z","time spent":"386.843004ms","remote":"127.0.0.1:38044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:13:00.294367Z","caller":"traceutil/trace.go:171","msg":"trace[460020329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27002; }","duration":"282.922684ms","start":"2026-10-15T15:13:00.011428Z","end":"2026-10-15T15:13:00.294351Z","steps":["trace[460020329] 'agreement among raft nodes before linearized reading' (duration: 282.802661ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:13:00.581962Z","caller":"traceutil/trace.go:171","msg":"trace[1976217422] linearizableReadLoop","detail":"{readStateIndex:27885; appliedIndex:27884; }","duration":"280.490295ms","start":"2026-10-15T15:13:00.30145Z","end":"2026-10-15T15:13:00.581941Z","steps":["trace[1976217422] 'read index received' (duration: 280.27519ms)","trace[1976217422] 'applied index is now lower than readState.Index' (duration: 214.205µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:13:00.58206Z","caller":"traceutil/trace.go:171","msg":"trace[453482343] transaction","detail":"{read_only:false; response_revision:27003; number_of_response:1; }","duration":"281.009108ms","start":"2026-10-15T15:13:00.30103Z","end":"2026-10-15T15:13:00.582039Z","steps":["trace[453482343] 'process raft request' (duration: 280.747731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:13:00.582193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.723101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-15T15:13:00.582227Z","caller":"traceutil/trace.go:171","msg":"trace[1998324186] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27003; }","duration":"280.791643ms","start":"2026-10-15T15:13:00.301423Z","end":"2026-10-15T15:13:00.582215Z","steps":["trace[1998324186] 'agreement among raft nodes before linearized reading' (duration: 280.641359ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:13:59.054509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24693} {"level":"info","ts":"2026-10-15T15:13:59.195843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24693,"took":"136.837046ms","hash":1772688532,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":36986880,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-15T15:13:59.195887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1772688532,"revision":24693,"compact-revision":21690} {"level":"info","ts":"2026-10-15T15:16:29.567394Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:16:29.574455Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T15:16:29.574572Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T15:16:32.670942Z","caller":"traceutil/trace.go:171","msg":"trace[987234373] transaction","detail":"{read_only:false; response_revision:29098; number_of_response:1; }","duration":"157.494633ms","start":"2026-10-15T15:16:32.513398Z","end":"2026-10-15T15:16:32.670892Z","steps":["trace[987234373] 'process raft request' (duration: 111.068977ms)","trace[987234373] 'compare' (duration: 46.106308ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:18:59.083554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27608} {"level":"warn","ts":"2026-10-15T15:18:59.621367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.115551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:18:59.621457Z","caller":"traceutil/trace.go:171","msg":"trace[203889336] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:30308; }","duration":"101.263014ms","start":"2026-10-15T15:18:59.520172Z","end":"2026-10-15T15:18:59.621435Z","steps":["trace[203889336] 'count revisions from in-memory index tree' (duration: 101.038429ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:18:59.641696Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27608,"took":"553.718847ms","hash":2761635204,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":34668544,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T15:18:59.641785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2761635204,"revision":27608,"compact-revision":24693} {"level":"info","ts":"2026-10-15T15:21:35.833368Z","caller":"traceutil/trace.go:171","msg":"trace[1546089618] transaction","detail":"{read_only:false; response_revision:31513; number_of_response:1; }","duration":"113.836177ms","start":"2026-10-15T15:21:35.719509Z","end":"2026-10-15T15:21:35.833345Z","steps":["trace[1546089618] 'process raft request' (duration: 113.735955ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:21:35.83358Z","caller":"traceutil/trace.go:171","msg":"trace[328297250] transaction","detail":"{read_only:false; response_revision:31512; number_of_response:1; }","duration":"118.751207ms","start":"2026-10-15T15:21:35.714728Z","end":"2026-10-15T15:21:35.833479Z","steps":["trace[328297250] 'process raft request' (duration: 78.004106ms)","trace[328297250] 'compare' (duration: 40.258599ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:21:35.833857Z","caller":"traceutil/trace.go:171","msg":"trace[308252171] transaction","detail":"{read_only:false; response_revision:31514; number_of_response:1; }","duration":"107.868723ms","start":"2026-10-15T15:21:35.725972Z","end":"2026-10-15T15:21:35.833841Z","steps":["trace[308252171] 'process raft request' (duration: 107.33994ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:23:59.090084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30302} {"level":"info","ts":"2026-10-15T15:23:59.236933Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30302,"took":"143.238317ms","hash":3547255977,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":30568448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T15:23:59.237004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3547255977,"revision":30302,"compact-revision":27608} {"level":"warn","ts":"2026-10-15T15:24:02.112664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.177892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T15:24:02.112741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.456564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T15:24:02.112786Z","caller":"traceutil/trace.go:171","msg":"trace[320255296] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:32574; }","duration":"158.362246ms","start":"2026-10-15T15:24:01.954396Z","end":"2026-10-15T15:24:02.112759Z","steps":["trace[320255296] 'count revisions from in-memory index tree' (duration: 157.939186ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:24:02.11279Z","caller":"traceutil/trace.go:171","msg":"trace[758326955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:32574; }","duration":"130.560816ms","start":"2026-10-15T15:24:01.982213Z","end":"2026-10-15T15:24:02.112774Z","steps":["trace[758326955] 'range keys from in-memory index tree' (duration: 130.320581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:24:02.112873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.681116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-cookie-secret\" ","response":"range_response_count:1 size:1230"} {"level":"info","ts":"2026-10-15T15:24:02.113026Z","caller":"traceutil/trace.go:171","msg":"trace[1631688478] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-cookie-secret; range_end:; response_count:1; response_revision:32574; }","duration":"132.894861ms","start":"2026-10-15T15:24:01.980097Z","end":"2026-10-15T15:24:02.112992Z","steps":["trace[1631688478] 'range keys from in-memory index tree' (duration: 132.429011ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:24:02.114019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.525959ms","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-10-15T15:24:02.114082Z","caller":"traceutil/trace.go:171","msg":"trace[578830526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:32574; }","duration":"132.364729ms","start":"2026-10-15T15:24:01.981699Z","end":"2026-10-15T15:24:02.114063Z","steps":["trace[578830526] 'range keys from in-memory index tree' (duration: 131.296564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:24:46.903913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.533241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:24:46.904328Z","caller":"traceutil/trace.go:171","msg":"trace[1582075243] transaction","detail":"{read_only:false; response_revision:32906; number_of_response:1; }","duration":"166.894706ms","start":"2026-10-15T15:24:46.737386Z","end":"2026-10-15T15:24:46.90428Z","steps":["trace[1582075243] 'process raft request' (duration: 33.712029ms)","trace[1582075243] 'compare' (duration: 131.996239ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:24:46.904456Z","caller":"traceutil/trace.go:171","msg":"trace[1671384117] transaction","detail":"{read_only:false; response_revision:32907; number_of_response:1; }","duration":"120.887755ms","start":"2026-10-15T15:24:46.783522Z","end":"2026-10-15T15:24:46.90441Z","steps":["trace[1671384117] 'process raft request' (duration: 120.757022ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:24:47.380966Z","caller":"traceutil/trace.go:171","msg":"trace[1487710899] transaction","detail":"{read_only:false; response_revision:32910; number_of_response:1; }","duration":"105.631894ms","start":"2026-10-15T15:24:47.2753Z","end":"2026-10-15T15:24:47.380931Z","steps":["trace[1487710899] 'process raft request' (duration: 105.44206ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:25:02.660736Z","caller":"traceutil/trace.go:171","msg":"trace[1835863800] transaction","detail":"{read_only:false; response_revision:33031; number_of_response:1; }","duration":"107.538469ms","start":"2026-10-15T15:25:02.553171Z","end":"2026-10-15T15:25:02.66071Z","steps":["trace[1835863800] 'process raft request' (duration: 107.443997ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:25:02.660823Z","caller":"traceutil/trace.go:171","msg":"trace[1572741374] transaction","detail":"{read_only:false; response_revision:33030; number_of_response:1; }","duration":"134.648551ms","start":"2026-10-15T15:25:02.52615Z","end":"2026-10-15T15:25:02.660799Z","steps":["trace[1572741374] 'process raft request' (duration: 45.234952ms)","trace[1572741374] 'compare' (duration: 89.091102ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:25:03.477233Z","caller":"traceutil/trace.go:171","msg":"trace[926335176] transaction","detail":"{read_only:false; response_revision:33041; number_of_response:1; }","duration":"172.882368ms","start":"2026-10-15T15:25:03.304329Z","end":"2026-10-15T15:25:03.477212Z","steps":["trace[926335176] 'process raft request' (duration: 172.764375ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:25:03.481003Z","caller":"traceutil/trace.go:171","msg":"trace[1627178589] transaction","detail":"{read_only:false; response_revision:33042; number_of_response:1; }","duration":"101.439184ms","start":"2026-10-15T15:25:03.379541Z","end":"2026-10-15T15:25:03.48098Z","steps":["trace[1627178589] 'process raft request' (duration: 101.312831ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:25:03.971648Z","caller":"traceutil/trace.go:171","msg":"trace[1028106931] transaction","detail":"{read_only:false; response_revision:33046; number_of_response:1; }","duration":"101.506046ms","start":"2026-10-15T15:25:03.870117Z","end":"2026-10-15T15:25:03.971623Z","steps":["trace[1028106931] 'process raft request' (duration: 101.366663ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:25:19.731008Z","caller":"traceutil/trace.go:171","msg":"trace[623670854] transaction","detail":"{read_only:false; response_revision:33179; number_of_response:1; }","duration":"181.257297ms","start":"2026-10-15T15:25:19.549706Z","end":"2026-10-15T15:25:19.730964Z","steps":["trace[623670854] 'process raft request' (duration: 181.011891ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:25:19.733851Z","caller":"traceutil/trace.go:171","msg":"trace[1343236477] transaction","detail":"{read_only:false; response_revision:33180; number_of_response:1; }","duration":"128.826924ms","start":"2026-10-15T15:25:19.604978Z","end":"2026-10-15T15:25:19.733805Z","steps":["trace[1343236477] 'process raft request' (duration: 128.576068ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:26:38.356701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.853229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:26:38.356797Z","caller":"traceutil/trace.go:171","msg":"trace[1409141036] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:33748; }","duration":"109.001162ms","start":"2026-10-15T15:26:38.24778Z","end":"2026-10-15T15:26:38.356781Z","steps":["trace[1409141036] 'count revisions from in-memory index tree' (duration: 108.781387ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:28:59.098813Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32556} {"level":"info","ts":"2026-10-15T15:28:59.262764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32556,"took":"157.789268ms","hash":746918792,"current-db-size-bytes":58023936,"current-db-size":"58 MB","current-db-size-in-use-bytes":30208000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T15:28:59.262834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":746918792,"revision":32556,"compact-revision":30302} {"level":"info","ts":"2026-10-15T15:32:51.280135Z","caller":"traceutil/trace.go:171","msg":"trace[609768683] transaction","detail":"{read_only:false; response_revision:36565; number_of_response:1; }","duration":"100.311928ms","start":"2026-10-15T15:32:51.179789Z","end":"2026-10-15T15:32:51.2801Z","steps":["trace[609768683] 'process raft request' (duration: 19.850443ms)","trace[609768683] 'compare' (duration: 80.222759ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:33:59.108326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34782} {"level":"info","ts":"2026-10-15T15:33:59.239627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34782,"took":"127.544539ms","hash":1557273456,"current-db-size-bytes":58023936,"current-db-size":"58 MB","current-db-size-in-use-bytes":31629312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:33:59.239676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1557273456,"revision":34782,"compact-revision":32556} {"level":"info","ts":"2026-10-15T15:37:43.653586Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"612d5169b08bb96b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:37:43.659781Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T15:37:43.659844Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T15:38:59.116816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37071} {"level":"info","ts":"2026-10-15T15:38:59.256294Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37071,"took":"134.392233ms","hash":3611189402,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":33525760,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T15:38:59.256356Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3611189402,"revision":37071,"compact-revision":34782} {"level":"info","ts":"2026-10-15T15:43:59.122849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39351} {"level":"info","ts":"2026-10-15T15:43:59.259616Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39351,"took":"133.612236ms","hash":1223175695,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":31674368,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:43:59.259685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1223175695,"revision":39351,"compact-revision":37071}