{"level":"warn","ts":"2026-10-26T16:40:20.691762Z","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-26T16:40:20.691868Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-26T16:40:20.691964Z","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-26T16:40:20.691981Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-10-26T16:40:20.692005Z","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-26T16:40:20.693105Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-10-26T16:40:20.693239Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-26T16:40:20.696943Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.322177ms"} {"level":"info","ts":"2026-10-26T16:40:20.703812Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-10-26T16:40:20.70398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T16:40:20.704017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-10-26T16:40:20.704042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T16:40:20.704056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-10-26T16:40:20.704109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-10-26T16:40:20.710382Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T16:40:20.712298Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T16:40:20.713936Z","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-26T16:40:20.717564Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T16:40:20.717836Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T16:40:20.717953Z","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-26T16:40:20.718207Z","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-26T16:40:20.718236Z","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-26T16:40:20.719045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-10-26T16:40:20.71972Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-10-26T16:40:20.730563Z","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-26T16:40:20.730655Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-10-26T16:40:20.731681Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-10-26T16:40:20.732733Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T16:40:20.732857Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T16:40:21.404817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T16:40:21.404971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T16:40:21.40501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-10-26T16:40:21.405036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-10-26T16:40:21.405057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-10-26T16:40:21.405082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-10-26T16:40:21.405103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-10-26T16:40:21.406488Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T16:40:21.406532Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T16:40:21.406549Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T16:40:21.406692Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T16:40:21.407006Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T16:40:21.40707Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T16:40:21.407679Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T16:40:21.407827Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T16:40:21.407864Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T16:40:21.410902Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T16:40:21.410921Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-10-26T16:40:33.388156Z","caller":"traceutil/trace.go:171","msg":"trace[82511201] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"139.889237ms","start":"2026-10-26T16:40:33.248237Z","end":"2026-10-26T16:40:33.388126Z","steps":["trace[82511201] 'process raft request' (duration: 139.712352ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:40:33.404763Z","caller":"traceutil/trace.go:171","msg":"trace[2037332686] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"153.226943ms","start":"2026-10-26T16:40:33.251519Z","end":"2026-10-26T16:40:33.404746Z","steps":["trace[2037332686] 'process raft request' (duration: 153.033518ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:40:33.597633Z","caller":"traceutil/trace.go:171","msg":"trace[1821970888] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"188.161219ms","start":"2026-10-26T16:40:33.40943Z","end":"2026-10-26T16:40:33.597591Z","steps":["trace[1821970888] 'process raft request' (duration: 187.972834ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:40:33.934982Z","caller":"traceutil/trace.go:171","msg":"trace[1997812031] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"521.424159ms","start":"2026-10-26T16:40:33.413532Z","end":"2026-10-26T16:40:33.934956Z","steps":["trace[1997812031] 'process raft request' (duration: 521.266655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:40:33.935707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:40:33.413503Z","time spent":"521.566013ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:40:34.076152Z","caller":"traceutil/trace.go:171","msg":"trace[1492706209] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"133.115461ms","start":"2026-10-26T16:40:33.942997Z","end":"2026-10-26T16:40:34.076113Z","steps":["trace[1492706209] 'process raft request' (duration: 132.765872ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:41:11.45407Z","caller":"traceutil/trace.go:171","msg":"trace[1199518715] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"127.2213ms","start":"2026-10-26T16:41:11.326826Z","end":"2026-10-26T16:41:11.454047Z","steps":["trace[1199518715] 'process raft request' (duration: 127.038855ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:41:11.461573Z","caller":"traceutil/trace.go:171","msg":"trace[789685303] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"134.310434ms","start":"2026-10-26T16:41:11.327235Z","end":"2026-10-26T16:41:11.461545Z","steps":["trace[789685303] 'process raft request' (duration: 134.15452ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:41:11.791312Z","caller":"traceutil/trace.go:171","msg":"trace[704943034] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"231.137085ms","start":"2026-10-26T16:41:11.560154Z","end":"2026-10-26T16:41:11.791291Z","steps":["trace[704943034] 'read index received' (duration: 219.925195ms)","trace[704943034] 'applied index is now lower than readState.Index' (duration: 11.21105ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:41:11.791506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.336401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-26T16:41:11.791565Z","caller":"traceutil/trace.go:171","msg":"trace[1596715683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"231.433794ms","start":"2026-10-26T16:41:11.56011Z","end":"2026-10-26T16:41:11.791544Z","steps":["trace[1596715683] 'agreement among raft nodes before linearized reading' (duration: 231.260328ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:41:11.791867Z","caller":"traceutil/trace.go:171","msg":"trace[946963637] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"275.209488ms","start":"2026-10-26T16:41:11.516646Z","end":"2026-10-26T16:41:11.791855Z","steps":["trace[946963637] 'process raft request' (duration: 263.476034ms)","trace[946963637] 'compare' (duration: 11.077647ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:41:12.391608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.239899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T16:41:12.391708Z","caller":"traceutil/trace.go:171","msg":"trace[1056640347] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"571.181135ms","start":"2026-10-26T16:41:11.820509Z","end":"2026-10-26T16:41:12.39169Z","steps":["trace[1056640347] 'process raft request' (duration: 450.659309ms)","trace[1056640347] 'compare' (duration: 120.144067ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:41:12.391763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:41:11.820489Z","time spent":"571.248738ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:41:12.394776Z","caller":"traceutil/trace.go:171","msg":"trace[1367523874] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"417.475729ms","start":"2026-10-26T16:41:11.977275Z","end":"2026-10-26T16:41:12.394751Z","steps":["trace[1367523874] 'read index received' (duration: 293.973685ms)","trace[1367523874] 'applied index is now lower than readState.Index' (duration: 123.501144ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:41:12.394912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.595712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:41:12.394948Z","caller":"traceutil/trace.go:171","msg":"trace[1335203992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"417.711075ms","start":"2026-10-26T16:41:11.977228Z","end":"2026-10-26T16:41:12.394939Z","steps":["trace[1335203992] 'agreement among raft nodes before linearized reading' (duration: 417.610433ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:41:12.394914Z","caller":"traceutil/trace.go:171","msg":"trace[1957307764] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"521.014035ms","start":"2026-10-26T16:41:11.873841Z","end":"2026-10-26T16:41:12.394855Z","steps":["trace[1957307764] 'process raft request' (duration: 520.4448ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:41:12.394988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:41:11.977209Z","time spent":"417.771836ms","remote":"127.0.0.1:50586","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-26T16:41:12.395086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:41:11.873809Z","time spent":"521.18839ms","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:41:57.96737Z","caller":"traceutil/trace.go:171","msg":"trace[1579467729] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"263.305129ms","start":"2026-10-26T16:41:57.704033Z","end":"2026-10-26T16:41:57.967338Z","steps":["trace[1579467729] 'process raft request' (duration: 263.134765ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:41:57.983169Z","caller":"traceutil/trace.go:171","msg":"trace[1630261130] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"276.80299ms","start":"2026-10-26T16:41:57.706311Z","end":"2026-10-26T16:41:57.983114Z","steps":["trace[1630261130] 'process raft request' (duration: 276.584375ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:42:47.828743Z","caller":"traceutil/trace.go:171","msg":"trace[1826175040] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"285.482152ms","start":"2026-10-26T16:42:47.543229Z","end":"2026-10-26T16:42:47.828711Z","steps":["trace[1826175040] 'process raft request' (duration: 285.323118ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:42:48.394298Z","caller":"traceutil/trace.go:171","msg":"trace[2121933492] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"329.316379ms","start":"2026-10-26T16:42:48.06495Z","end":"2026-10-26T16:42:48.394266Z","steps":["trace[2121933492] 'read index received' (duration: 329.156585ms)","trace[2121933492] 'applied index is now lower than readState.Index' (duration: 158.583µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:42:48.394364Z","caller":"traceutil/trace.go:171","msg":"trace[2129208007] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"363.262208ms","start":"2026-10-26T16:42:48.031072Z","end":"2026-10-26T16:42:48.394334Z","steps":["trace[2129208007] 'process raft request' (duration: 363.019162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:42:48.394475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.512043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-70cc44db-694b-4493-a3b3-4992cd3d9b6c\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-10-26T16:42:48.394539Z","caller":"traceutil/trace.go:171","msg":"trace[474245000] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-70cc44db-694b-4493-a3b3-4992cd3d9b6c; range_end:; response_count:1; response_revision:1906; }","duration":"329.612776ms","start":"2026-10-26T16:42:48.064913Z","end":"2026-10-26T16:42:48.394526Z","steps":["trace[474245000] 'agreement among raft nodes before linearized reading' (duration: 329.470642ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:42:48.394566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:42:48.03105Z","time spent":"363.425113ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:42:48.394577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:42:48.064902Z","time spent":"329.666917ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-70cc44db-694b-4493-a3b3-4992cd3d9b6c\" "} {"level":"info","ts":"2026-10-26T16:42:48.454309Z","caller":"traceutil/trace.go:171","msg":"trace[415774200] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"259.078747ms","start":"2026-10-26T16:42:48.195216Z","end":"2026-10-26T16:42:48.454294Z","steps":["trace[415774200] 'process raft request' (duration: 258.985314ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:42:48.454435Z","caller":"traceutil/trace.go:171","msg":"trace[2011984542] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"349.797959ms","start":"2026-10-26T16:42:48.104581Z","end":"2026-10-26T16:42:48.454379Z","steps":["trace[2011984542] 'process raft request' (duration: 348.817624ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:42:48.454565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.958303ms","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-26T16:42:48.45461Z","caller":"traceutil/trace.go:171","msg":"trace[765504580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1909; }","duration":"149.019094ms","start":"2026-10-26T16:42:48.30558Z","end":"2026-10-26T16:42:48.454599Z","steps":["trace[765504580] 'agreement among raft nodes before linearized reading' (duration: 148.88428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:42:48.45459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.354945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-26T16:42:48.454609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.836839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-26T16:42:48.454624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:42:48.104552Z","time spent":"349.973204ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:42:48.454616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.82755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T16:42:48.454657Z","caller":"traceutil/trace.go:171","msg":"trace[1095342558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1909; }","duration":"197.911821ms","start":"2026-10-26T16:42:48.256732Z","end":"2026-10-26T16:42:48.454644Z","steps":["trace[1095342558] 'agreement among raft nodes before linearized reading' (duration: 197.701035ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:42:48.454664Z","caller":"traceutil/trace.go:171","msg":"trace[398299372] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1909; }","duration":"136.475919ms","start":"2026-10-26T16:42:48.318163Z","end":"2026-10-26T16:42:48.454639Z","steps":["trace[398299372] 'agreement among raft nodes before linearized reading' (duration: 136.325326ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:42:48.454662Z","caller":"traceutil/trace.go:171","msg":"trace[1513502428] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"133.264655ms","start":"2026-10-26T16:42:48.321374Z","end":"2026-10-26T16:42:48.454639Z","steps":["trace[1513502428] 'process raft request' (duration: 132.882365ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:42:48.45469Z","caller":"traceutil/trace.go:171","msg":"trace[1805556520] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1909; }","duration":"182.933283ms","start":"2026-10-26T16:42:48.271734Z","end":"2026-10-26T16:42:48.454667Z","steps":["trace[1805556520] 'agreement among raft nodes before linearized reading' (duration: 182.765079ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:00.680815Z","caller":"traceutil/trace.go:171","msg":"trace[535041164] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"185.347396ms","start":"2026-10-26T16:43:00.495452Z","end":"2026-10-26T16:43:00.6808Z","steps":["trace[535041164] 'process raft request' (duration: 184.887294ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:00.681007Z","caller":"traceutil/trace.go:171","msg":"trace[109745090] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"185.156671ms","start":"2026-10-26T16:43:00.495813Z","end":"2026-10-26T16:43:00.68097Z","steps":["trace[109745090] 'process raft request' (duration: 184.663338ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:00.681254Z","caller":"traceutil/trace.go:171","msg":"trace[912415236] linearizableReadLoop","detail":"{readStateIndex:2088; appliedIndex:2085; }","duration":"168.2018ms","start":"2026-10-26T16:43:00.512477Z","end":"2026-10-26T16:43:00.680679Z","steps":["trace[912415236] 'read index received' (duration: 15.43335ms)","trace[912415236] 'applied index is now lower than readState.Index' (duration: 152.76732ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:43:00.681264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.765916ms","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-26T16:43:00.681332Z","caller":"traceutil/trace.go:171","msg":"trace[1908701082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2011; }","duration":"168.888909ms","start":"2026-10-26T16:43:00.512427Z","end":"2026-10-26T16:43:00.681316Z","steps":["trace[1908701082] 'agreement among raft nodes before linearized reading' (duration: 168.685314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:00.681454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.898844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T16:43:00.681507Z","caller":"traceutil/trace.go:171","msg":"trace[1601508122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2011; }","duration":"157.991476ms","start":"2026-10-26T16:43:00.523501Z","end":"2026-10-26T16:43:00.681493Z","steps":["trace[1601508122] 'agreement among raft nodes before linearized reading' (duration: 157.855613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:00.68154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.182988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-26T16:43:00.681593Z","caller":"traceutil/trace.go:171","msg":"trace[1480075777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2011; }","duration":"149.25913ms","start":"2026-10-26T16:43:00.53232Z","end":"2026-10-26T16:43:00.681579Z","steps":["trace[1480075777] 'agreement among raft nodes before linearized reading' (duration: 149.136546ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:00.681563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.571236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"warn","ts":"2026-10-26T16:43:00.681618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.24008ms","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-26T16:43:00.681677Z","caller":"traceutil/trace.go:171","msg":"trace[951306413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2011; }","duration":"149.340522ms","start":"2026-10-26T16:43:00.53232Z","end":"2026-10-26T16:43:00.68166Z","steps":["trace[951306413] 'agreement among raft nodes before linearized reading' (duration: 149.123946ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:00.681669Z","caller":"traceutil/trace.go:171","msg":"trace[1908734184] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2011; }","duration":"142.703859ms","start":"2026-10-26T16:43:00.538946Z","end":"2026-10-26T16:43:00.68165Z","steps":["trace[1908734184] 'agreement among raft nodes before linearized reading' (duration: 142.431903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:00.964036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.231966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T16:43:00.96425Z","caller":"traceutil/trace.go:171","msg":"trace[919267010] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"277.210738ms","start":"2026-10-26T16:43:00.687006Z","end":"2026-10-26T16:43:00.964216Z","steps":["trace[919267010] 'process raft request' (duration: 131.679425ms)","trace[919267010] 'compare' (duration: 145.00296ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:43:00.964487Z","caller":"traceutil/trace.go:171","msg":"trace[245806884] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"276.681353ms","start":"2026-10-26T16:43:00.687751Z","end":"2026-10-26T16:43:00.964433Z","steps":["trace[245806884] 'process raft request' (duration: 276.571021ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:00.964487Z","caller":"traceutil/trace.go:171","msg":"trace[1022385834] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"277.151916ms","start":"2026-10-26T16:43:00.687311Z","end":"2026-10-26T16:43:00.964462Z","steps":["trace[1022385834] 'process raft request' (duration: 276.830278ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:04.888189Z","caller":"traceutil/trace.go:171","msg":"trace[1818238516] linearizableReadLoop","detail":"{readStateIndex:2121; appliedIndex:2119; }","duration":"176.65943ms","start":"2026-10-26T16:43:04.711504Z","end":"2026-10-26T16:43:04.888164Z","steps":["trace[1818238516] 'read index received' (duration: 157.011021ms)","trace[1818238516] 'applied index is now lower than readState.Index' (duration: 19.646989ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:43:04.888311Z","caller":"traceutil/trace.go:171","msg":"trace[1612362428] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"192.713187ms","start":"2026-10-26T16:43:04.695583Z","end":"2026-10-26T16:43:04.888296Z","steps":["trace[1612362428] 'process raft request' (duration: 192.513442ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:04.88845Z","caller":"traceutil/trace.go:171","msg":"trace[623038231] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"192.957092ms","start":"2026-10-26T16:43:04.695475Z","end":"2026-10-26T16:43:04.888432Z","steps":["trace[623038231] 'process raft request' (duration: 192.45934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:04.88866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.137353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T16:43:04.888699Z","caller":"traceutil/trace.go:171","msg":"trace[1789430129] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2043; }","duration":"177.229565ms","start":"2026-10-26T16:43:04.711458Z","end":"2026-10-26T16:43:04.888688Z","steps":["trace[1789430129] 'agreement among raft nodes before linearized reading' (duration: 177.097402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:04.89975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.583318ms","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-26T16:43:04.899803Z","caller":"traceutil/trace.go:171","msg":"trace[1475237934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2044; }","duration":"132.683071ms","start":"2026-10-26T16:43:04.767108Z","end":"2026-10-26T16:43:04.899791Z","steps":["trace[1475237934] 'agreement among raft nodes before linearized reading' (duration: 132.520126ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:37.104782Z","caller":"traceutil/trace.go:171","msg":"trace[81791655] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"101.011216ms","start":"2026-10-26T16:43:37.003739Z","end":"2026-10-26T16:43:37.10475Z","steps":["trace[81791655] 'process raft request' (duration: 100.791401ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:37.349767Z","caller":"traceutil/trace.go:171","msg":"trace[507682523] linearizableReadLoop","detail":"{readStateIndex:2306; appliedIndex:2304; }","duration":"271.825534ms","start":"2026-10-26T16:43:37.077912Z","end":"2026-10-26T16:43:37.349738Z","steps":["trace[507682523] 'read index received' (duration: 26.688176ms)","trace[507682523] 'applied index is now lower than readState.Index' (duration: 245.135458ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:43:37.349841Z","caller":"traceutil/trace.go:171","msg":"trace[1686240251] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"345.078037ms","start":"2026-10-26T16:43:37.00473Z","end":"2026-10-26T16:43:37.349808Z","steps":["trace[1686240251] 'process raft request' (duration: 261.438483ms)","trace[1686240251] 'compare' (duration: 83.358187ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:43:37.350023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:37.004709Z","time spent":"345.22904ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:43:37.350037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.104092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-26T16:43:37.350138Z","caller":"traceutil/trace.go:171","msg":"trace[1814880790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2221; }","duration":"272.300606ms","start":"2026-10-26T16:43:37.077818Z","end":"2026-10-26T16:43:37.350119Z","steps":["trace[1814880790] 'agreement among raft nodes before linearized reading' (duration: 272.03063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:37.350328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.037751ms","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-26T16:43:37.350424Z","caller":"traceutil/trace.go:171","msg":"trace[1842983586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2221; }","duration":"213.179515ms","start":"2026-10-26T16:43:37.137206Z","end":"2026-10-26T16:43:37.350385Z","steps":["trace[1842983586] 'agreement among raft nodes before linearized reading' (duration: 212.939868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:37.35042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.555447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T16:43:37.350482Z","caller":"traceutil/trace.go:171","msg":"trace[723361340] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2221; }","duration":"248.65814ms","start":"2026-10-26T16:43:37.101807Z","end":"2026-10-26T16:43:37.350465Z","steps":["trace[723361340] 'agreement among raft nodes before linearized reading' (duration: 248.137986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:37.350487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.03997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-10-26T16:43:37.350596Z","caller":"traceutil/trace.go:171","msg":"trace[526979793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2221; }","duration":"203.215125ms","start":"2026-10-26T16:43:37.147353Z","end":"2026-10-26T16:43:37.350568Z","steps":["trace[526979793] 'agreement among raft nodes before linearized reading' (duration: 202.792403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:37.350622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.961478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T16:43:37.350765Z","caller":"traceutil/trace.go:171","msg":"trace[1132971533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2221; }","duration":"195.138743ms","start":"2026-10-26T16:43:37.155592Z","end":"2026-10-26T16:43:37.350731Z","steps":["trace[1132971533] 'agreement among raft nodes before linearized reading' (duration: 194.621729ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:37.509408Z","caller":"traceutil/trace.go:171","msg":"trace[1208805427] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"111.801568ms","start":"2026-10-26T16:43:37.397569Z","end":"2026-10-26T16:43:37.50937Z","steps":["trace[1208805427] 'process raft request' (duration: 57.732627ms)","trace[1208805427] 'compare' (duration: 53.849936ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:43:43.659635Z","caller":"traceutil/trace.go:171","msg":"trace[1715925231] linearizableReadLoop","detail":"{readStateIndex:2338; appliedIndex:2337; }","duration":"268.034126ms","start":"2026-10-26T16:43:43.391575Z","end":"2026-10-26T16:43:43.65961Z","steps":["trace[1715925231] 'read index received' (duration: 267.79938ms)","trace[1715925231] 'applied index is now lower than readState.Index' (duration: 233.936µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:43:43.6597Z","caller":"traceutil/trace.go:171","msg":"trace[1431483736] transaction","detail":"{read_only:false; response_revision:2252; number_of_response:1; }","duration":"289.434974ms","start":"2026-10-26T16:43:43.370224Z","end":"2026-10-26T16:43:43.659659Z","steps":["trace[1431483736] 'process raft request' (duration: 289.255638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:43.659827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.234041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T16:43:43.659855Z","caller":"traceutil/trace.go:171","msg":"trace[1553042407] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2252; }","duration":"268.322553ms","start":"2026-10-26T16:43:43.391522Z","end":"2026-10-26T16:43:43.659844Z","steps":["trace[1553042407] 'agreement among raft nodes before linearized reading' (duration: 268.19748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:43.660164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.51352ms","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-26T16:43:43.660235Z","caller":"traceutil/trace.go:171","msg":"trace[1153888474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2252; }","duration":"201.617082ms","start":"2026-10-26T16:43:43.458601Z","end":"2026-10-26T16:43:43.660218Z","steps":["trace[1153888474] 'agreement among raft nodes before linearized reading' (duration: 201.438117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:43.660195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.449718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-26T16:43:43.660281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.624133ms","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-26T16:43:43.660323Z","caller":"traceutil/trace.go:171","msg":"trace[934392030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2252; }","duration":"201.606402ms","start":"2026-10-26T16:43:43.458694Z","end":"2026-10-26T16:43:43.6603Z","steps":["trace[934392030] 'agreement among raft nodes before linearized reading' (duration: 201.336665ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:43.660359Z","caller":"traceutil/trace.go:171","msg":"trace[1615070722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2252; }","duration":"201.740116ms","start":"2026-10-26T16:43:43.458594Z","end":"2026-10-26T16:43:43.660334Z","steps":["trace[1615070722] 'agreement among raft nodes before linearized reading' (duration: 201.383077ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:43.660365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.875305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-26T16:43:43.660488Z","caller":"traceutil/trace.go:171","msg":"trace[1205494565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2252; }","duration":"208.047069ms","start":"2026-10-26T16:43:43.452415Z","end":"2026-10-26T16:43:43.660462Z","steps":["trace[1205494565] 'agreement among raft nodes before linearized reading' (duration: 207.68941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:43.660824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.727304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T16:43:43.660934Z","caller":"traceutil/trace.go:171","msg":"trace[156090020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2252; }","duration":"268.860167ms","start":"2026-10-26T16:43:43.392058Z","end":"2026-10-26T16:43:43.660918Z","steps":["trace[156090020] 'agreement among raft nodes before linearized reading' (duration: 267.867781ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:43.775561Z","caller":"traceutil/trace.go:171","msg":"trace[2077292706] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"110.287878ms","start":"2026-10-26T16:43:43.665243Z","end":"2026-10-26T16:43:43.775531Z","steps":["trace[2077292706] 'process raft request' (duration: 110.221686ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:43.7756Z","caller":"traceutil/trace.go:171","msg":"trace[1313627298] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"110.328659ms","start":"2026-10-26T16:43:43.665237Z","end":"2026-10-26T16:43:43.775566Z","steps":["trace[1313627298] 'process raft request' (duration: 98.608533ms)","trace[1313627298] 'compare' (duration: 11.465719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:43:44.223041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.976837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T16:43:44.223251Z","caller":"traceutil/trace.go:171","msg":"trace[2108090672] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"557.90939ms","start":"2026-10-26T16:43:43.665314Z","end":"2026-10-26T16:43:44.223223Z","steps":["trace[2108090672] 'process raft request' (duration: 451.622897ms)","trace[2108090672] 'compare' (duration: 105.828022ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:43:44.22335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:43.66529Z","time spent":"558.008742ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:43:44.223802Z","caller":"traceutil/trace.go:171","msg":"trace[166392237] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"558.388773ms","start":"2026-10-26T16:43:43.665382Z","end":"2026-10-26T16:43:44.223771Z","steps":["trace[166392237] 'process raft request' (duration: 557.773687ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:44.223892Z","caller":"traceutil/trace.go:171","msg":"trace[1597815463] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"557.184742ms","start":"2026-10-26T16:43:43.666666Z","end":"2026-10-26T16:43:44.223851Z","steps":["trace[1597815463] 'process raft request' (duration: 557.005048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:44.223976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:43.665366Z","time spent":"558.523896ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:43:44.224081Z","caller":"traceutil/trace.go:171","msg":"trace[165256837] linearizableReadLoop","detail":"{readStateIndex:2343; appliedIndex:2340; }","duration":"437.591681ms","start":"2026-10-26T16:43:43.786395Z","end":"2026-10-26T16:43:44.223987Z","steps":["trace[165256837] 'read index received' (duration: 330.620399ms)","trace[165256837] 'applied index is now lower than readState.Index' (duration: 106.967722ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:43:44.224181Z","caller":"traceutil/trace.go:171","msg":"trace[654457991] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"155.342154ms","start":"2026-10-26T16:43:44.068819Z","end":"2026-10-26T16:43:44.224162Z","steps":["trace[654457991] 'process raft request' (duration: 155.018216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:44.224198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:43.666651Z","time spent":"557.326225ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:43:44.224444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.98844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:43:44.224528Z","caller":"traceutil/trace.go:171","msg":"trace[290984127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2259; }","duration":"246.096053ms","start":"2026-10-26T16:43:43.978399Z","end":"2026-10-26T16:43:44.224495Z","steps":["trace[290984127] 'agreement among raft nodes before linearized reading' (duration: 245.985509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:44.22457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.176117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:43:44.22472Z","caller":"traceutil/trace.go:171","msg":"trace[1638837595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2259; }","duration":"438.369511ms","start":"2026-10-26T16:43:43.786335Z","end":"2026-10-26T16:43:44.224704Z","steps":["trace[1638837595] 'agreement among raft nodes before linearized reading' (duration: 437.842017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:44.224787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:43.786314Z","time spent":"438.461564ms","remote":"127.0.0.1:50574","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-26T16:44:56.841444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.576046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-26T16:44:56.841544Z","caller":"traceutil/trace.go:171","msg":"trace[283153315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2848; }","duration":"123.72919ms","start":"2026-10-26T16:44:56.717794Z","end":"2026-10-26T16:44:56.841524Z","steps":["trace[283153315] 'range keys from in-memory index tree' (duration: 123.349821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:44:56.841447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.483515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-26T16:44:56.841846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.468728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T16:44:56.841853Z","caller":"traceutil/trace.go:171","msg":"trace[460375049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2848; }","duration":"105.932957ms","start":"2026-10-26T16:44:56.735888Z","end":"2026-10-26T16:44:56.841821Z","steps":["trace[460375049] 'range keys from in-memory index tree' (duration: 105.329961ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:44:56.841948Z","caller":"traceutil/trace.go:171","msg":"trace[2033625536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2848; }","duration":"129.608562ms","start":"2026-10-26T16:44:56.712318Z","end":"2026-10-26T16:44:56.841926Z","steps":["trace[2033625536] 'range keys from in-memory index tree' (duration: 128.869464ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:49:35.681075Z","caller":"traceutil/trace.go:171","msg":"trace[2116882619] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"103.803714ms","start":"2026-10-26T16:49:35.577248Z","end":"2026-10-26T16:49:35.681052Z","steps":["trace[2116882619] 'process raft request' (duration: 103.575078ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:49:35.681203Z","caller":"traceutil/trace.go:171","msg":"trace[534709170] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"177.239075ms","start":"2026-10-26T16:49:35.503935Z","end":"2026-10-26T16:49:35.681174Z","steps":["trace[534709170] 'process raft request' (duration: 121.106149ms)","trace[534709170] 'compare' (duration: 55.375887ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:49:35.681691Z","caller":"traceutil/trace.go:171","msg":"trace[362761937] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"103.765423ms","start":"2026-10-26T16:49:35.577911Z","end":"2026-10-26T16:49:35.681676Z","steps":["trace[362761937] 'process raft request' (duration: 103.054295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:49:35.681858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.075558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b55e54a-611e-4211-b13f-582afa150343\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-10-26T16:49:35.681941Z","caller":"traceutil/trace.go:171","msg":"trace[874598767] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b55e54a-611e-4211-b13f-582afa150343; range_end:; response_count:1; response_revision:5049; }","duration":"100.198691ms","start":"2026-10-26T16:49:35.581721Z","end":"2026-10-26T16:49:35.681919Z","steps":["trace[874598767] 'agreement among raft nodes before linearized reading' (duration: 99.949155ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:49:40.793043Z","caller":"traceutil/trace.go:171","msg":"trace[885343148] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"200.119935ms","start":"2026-10-26T16:49:40.59289Z","end":"2026-10-26T16:49:40.79301Z","steps":["trace[885343148] 'process raft request' (duration: 172.258107ms)","trace[885343148] 'compare' (duration: 27.625972ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:49:40.793086Z","caller":"traceutil/trace.go:171","msg":"trace[1293042015] transaction","detail":"{read_only:false; response_revision:5166; number_of_response:1; }","duration":"200.078034ms","start":"2026-10-26T16:49:40.592969Z","end":"2026-10-26T16:49:40.793047Z","steps":["trace[1293042015] 'process raft request' (duration: 199.976332ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:49:40.793163Z","caller":"traceutil/trace.go:171","msg":"trace[93748451] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"199.906299ms","start":"2026-10-26T16:49:40.593233Z","end":"2026-10-26T16:49:40.793139Z","steps":["trace[93748451] 'process raft request' (duration: 199.756756ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:49:40.793284Z","caller":"traceutil/trace.go:171","msg":"trace[785365521] transaction","detail":"{read_only:false; response_revision:5168; number_of_response:1; }","duration":"199.605332ms","start":"2026-10-26T16:49:40.593638Z","end":"2026-10-26T16:49:40.793244Z","steps":["trace[785365521] 'process raft request' (duration: 199.381606ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:49:40.793376Z","caller":"traceutil/trace.go:171","msg":"trace[449964706] linearizableReadLoop","detail":"{readStateIndex:5366; appliedIndex:5362; }","duration":"199.200212ms","start":"2026-10-26T16:49:40.594151Z","end":"2026-10-26T16:49:40.793352Z","steps":["trace[449964706] 'read index received' (duration: 171.011006ms)","trace[449964706] 'applied index is now lower than readState.Index' (duration: 28.188086ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:49:40.793439Z","caller":"traceutil/trace.go:171","msg":"trace[1435764798] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"198.519434ms","start":"2026-10-26T16:49:40.594879Z","end":"2026-10-26T16:49:40.793399Z","steps":["trace[1435764798] 'process raft request' (duration: 198.200306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:49:40.793619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.453098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/loki-memcached-chunks-cache.18e2225c17dfd243\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-10-26T16:49:40.793669Z","caller":"traceutil/trace.go:171","msg":"trace[878008811] range","detail":"{range_begin:/registry/events/monitoring/loki-memcached-chunks-cache.18e2225c17dfd243; range_end:; response_count:1; response_revision:5169; }","duration":"199.555121ms","start":"2026-10-26T16:49:40.594102Z","end":"2026-10-26T16:49:40.793657Z","steps":["trace[878008811] 'agreement among raft nodes before linearized reading' (duration: 199.341345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:49:42.532004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.423433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T16:49:42.532104Z","caller":"traceutil/trace.go:171","msg":"trace[1923071114] linearizableReadLoop","detail":"{readStateIndex:5462; appliedIndex:5461; }","duration":"357.128151ms","start":"2026-10-26T16:49:42.17496Z","end":"2026-10-26T16:49:42.532088Z","steps":["trace[1923071114] 'read index received' (duration: 137.584544ms)","trace[1923071114] 'applied index is now lower than readState.Index' (duration: 219.542077ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:49:42.532184Z","caller":"traceutil/trace.go:171","msg":"trace[514894451] transaction","detail":"{read_only:false; response_revision:5263; number_of_response:1; }","duration":"411.937852ms","start":"2026-10-26T16:49:42.120236Z","end":"2026-10-26T16:49:42.532174Z","steps":["trace[514894451] 'process raft request' (duration: 192.272832ms)","trace[514894451] 'compare' (duration: 218.526481ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:49:42.532235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:49:42.120214Z","time spent":"411.997615ms","remote":"127.0.0.1:50666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":876,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T16:49:42.532513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.550361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e9f53c7-a4d2-4cb6-b43e-703c5acae95f\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-26T16:49:42.532551Z","caller":"traceutil/trace.go:171","msg":"trace[1175928490] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3e9f53c7-a4d2-4cb6-b43e-703c5acae95f; range_end:; response_count:1; response_revision:5263; }","duration":"357.616963ms","start":"2026-10-26T16:49:42.174922Z","end":"2026-10-26T16:49:42.532539Z","steps":["trace[1175928490] 'agreement among raft nodes before linearized reading' (duration: 357.47605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:49:42.532578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:49:42.174908Z","time spent":"357.663524ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e9f53c7-a4d2-4cb6-b43e-703c5acae95f\" "} {"level":"warn","ts":"2026-10-26T16:49:42.532817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.711328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T16:49:42.532872Z","caller":"traceutil/trace.go:171","msg":"trace[613485056] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5263; }","duration":"184.82443ms","start":"2026-10-26T16:49:42.348039Z","end":"2026-10-26T16:49:42.532863Z","steps":["trace[613485056] 'agreement among raft nodes before linearized reading' (duration: 184.667366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:49:42.533526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.870324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-26T16:49:42.533607Z","caller":"traceutil/trace.go:171","msg":"trace[1179976758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5263; }","duration":"172.990367ms","start":"2026-10-26T16:49:42.360598Z","end":"2026-10-26T16:49:42.533589Z","steps":["trace[1179976758] 'agreement among raft nodes before linearized reading' (duration: 172.731111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:49:42.533882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.375659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T16:49:42.533911Z","caller":"traceutil/trace.go:171","msg":"trace[668056026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5263; }","duration":"130.429941ms","start":"2026-10-26T16:49:42.403472Z","end":"2026-10-26T16:49:42.533902Z","steps":["trace[668056026] 'agreement among raft nodes before linearized reading' (duration: 130.347339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:49:42.534088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.182052ms","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-26T16:49:42.534113Z","caller":"traceutil/trace.go:171","msg":"trace[263789258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5263; }","duration":"135.233324ms","start":"2026-10-26T16:49:42.398872Z","end":"2026-10-26T16:49:42.534105Z","steps":["trace[263789258] 'agreement among raft nodes before linearized reading' (duration: 135.158432ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:49:44.700026Z","caller":"traceutil/trace.go:171","msg":"trace[799490091] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"135.595913ms","start":"2026-10-26T16:49:44.564388Z","end":"2026-10-26T16:49:44.699984Z","steps":["trace[799490091] 'process raft request' (duration: 71.588513ms)","trace[799490091] 'compare' (duration: 63.681921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:49:44.700159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.1388ms","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-26T16:49:44.700246Z","caller":"traceutil/trace.go:171","msg":"trace[1392474422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5298; }","duration":"145.289054ms","start":"2026-10-26T16:49:44.55494Z","end":"2026-10-26T16:49:44.700229Z","steps":["trace[1392474422] 'agreement among raft nodes before linearized reading' (duration: 145.046558ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:49:57.52789Z","caller":"traceutil/trace.go:171","msg":"trace[2012051519] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"106.829791ms","start":"2026-10-26T16:49:57.421033Z","end":"2026-10-26T16:49:57.527863Z","steps":["trace[2012051519] 'process raft request' (duration: 99.510133ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:04.948373Z","caller":"traceutil/trace.go:171","msg":"trace[673242741] linearizableReadLoop","detail":"{readStateIndex:5877; appliedIndex:5876; }","duration":"128.413708ms","start":"2026-10-26T16:50:04.819932Z","end":"2026-10-26T16:50:04.948345Z","steps":["trace[673242741] 'read index received' (duration: 117.759103ms)","trace[673242741] 'applied index is now lower than readState.Index' (duration: 10.653135ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:50:04.948422Z","caller":"traceutil/trace.go:171","msg":"trace[859973032] transaction","detail":"{read_only:false; response_revision:5666; number_of_response:1; }","duration":"178.833407ms","start":"2026-10-26T16:50:04.769555Z","end":"2026-10-26T16:50:04.948388Z","steps":["trace[859973032] 'process raft request' (duration: 168.06253ms)","trace[859973032] 'compare' (duration: 10.550631ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:50:04.94859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.701027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-26T16:50:04.948608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.020938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T16:50:04.948635Z","caller":"traceutil/trace.go:171","msg":"trace[1939382089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5666; }","duration":"149.790779ms","start":"2026-10-26T16:50:04.79883Z","end":"2026-10-26T16:50:04.948621Z","steps":["trace[1939382089] 'agreement among raft nodes before linearized reading' (duration: 149.640896ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:04.948678Z","caller":"traceutil/trace.go:171","msg":"trace[779299155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5666; }","duration":"147.137841ms","start":"2026-10-26T16:50:04.801519Z","end":"2026-10-26T16:50:04.948657Z","steps":["trace[779299155] 'agreement among raft nodes before linearized reading' (duration: 146.866924ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:04.95407Z","caller":"traceutil/trace.go:171","msg":"trace[1889705606] transaction","detail":"{read_only:false; response_revision:5668; number_of_response:1; }","duration":"129.556228ms","start":"2026-10-26T16:50:04.824485Z","end":"2026-10-26T16:50:04.954041Z","steps":["trace[1889705606] 'process raft request' (duration: 129.397233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:04.95409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.930186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a678b00a-d7c0-4159-b610-d4b7ee0507e2\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-26T16:50:04.954152Z","caller":"traceutil/trace.go:171","msg":"trace[61406880] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a678b00a-d7c0-4159-b610-d4b7ee0507e2; range_end:; response_count:1; response_revision:5668; }","duration":"121.018049ms","start":"2026-10-26T16:50:04.833115Z","end":"2026-10-26T16:50:04.954133Z","steps":["trace[61406880] 'agreement among raft nodes before linearized reading' (duration: 120.825093ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:04.954222Z","caller":"traceutil/trace.go:171","msg":"trace[1333684470] transaction","detail":"{read_only:false; response_revision:5667; number_of_response:1; }","duration":"133.11657ms","start":"2026-10-26T16:50:04.821075Z","end":"2026-10-26T16:50:04.954191Z","steps":["trace[1333684470] 'process raft request' (duration: 132.657348ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:19.639605Z","caller":"traceutil/trace.go:171","msg":"trace[828215522] transaction","detail":"{read_only:false; response_revision:5860; number_of_response:1; }","duration":"177.119623ms","start":"2026-10-26T16:50:19.46245Z","end":"2026-10-26T16:50:19.63957Z","steps":["trace[828215522] 'process raft request' (duration: 176.929487ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:19.975006Z","caller":"traceutil/trace.go:171","msg":"trace[1523406605] linearizableReadLoop","detail":"{readStateIndex:6077; appliedIndex:6076; }","duration":"206.656103ms","start":"2026-10-26T16:50:19.768328Z","end":"2026-10-26T16:50:19.974984Z","steps":["trace[1523406605] 'read index received' (duration: 206.418258ms)","trace[1523406605] 'applied index is now lower than readState.Index' (duration: 236.845µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:50:19.97518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.842998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a678b00a-d7c0-4159-b610-d4b7ee0507e2\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-10-26T16:50:19.975217Z","caller":"traceutil/trace.go:171","msg":"trace[1656352336] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a678b00a-d7c0-4159-b610-d4b7ee0507e2; range_end:; response_count:1; response_revision:5861; }","duration":"206.93396ms","start":"2026-10-26T16:50:19.768272Z","end":"2026-10-26T16:50:19.975205Z","steps":["trace[1656352336] 'agreement among raft nodes before linearized reading' (duration: 206.798797ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:19.975188Z","caller":"traceutil/trace.go:171","msg":"trace[1502873117] transaction","detail":"{read_only:false; response_revision:5861; number_of_response:1; }","duration":"208.237574ms","start":"2026-10-26T16:50:19.766889Z","end":"2026-10-26T16:50:19.975127Z","steps":["trace[1502873117] 'process raft request' (duration: 207.955567ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:20.086147Z","caller":"traceutil/trace.go:171","msg":"trace[116755279] transaction","detail":"{read_only:false; response_revision:5862; number_of_response:1; }","duration":"208.964223ms","start":"2026-10-26T16:50:19.877146Z","end":"2026-10-26T16:50:20.086111Z","steps":["trace[116755279] 'process raft request' (duration: 166.50716ms)","trace[116755279] 'compare' (duration: 42.244618ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:50:20.134071Z","caller":"traceutil/trace.go:171","msg":"trace[1573839282] transaction","detail":"{read_only:false; response_revision:5863; number_of_response:1; }","duration":"202.805604ms","start":"2026-10-26T16:50:19.931217Z","end":"2026-10-26T16:50:20.134022Z","steps":["trace[1573839282] 'process raft request' (duration: 202.458536ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:20.13417Z","caller":"traceutil/trace.go:171","msg":"trace[916942803] linearizableReadLoop","detail":"{readStateIndex:6080; appliedIndex:6077; }","duration":"155.66611ms","start":"2026-10-26T16:50:19.978473Z","end":"2026-10-26T16:50:20.134139Z","steps":["trace[916942803] 'read index received' (duration: 65.17087ms)","trace[916942803] 'applied index is now lower than readState.Index' (duration: 90.49201ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:50:20.134321Z","caller":"traceutil/trace.go:171","msg":"trace[1554402855] transaction","detail":"{read_only:false; response_revision:5864; number_of_response:1; }","duration":"150.898728ms","start":"2026-10-26T16:50:19.983314Z","end":"2026-10-26T16:50:20.134213Z","steps":["trace[1554402855] 'process raft request' (duration: 150.645801ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:20.134485Z","caller":"traceutil/trace.go:171","msg":"trace[1382446099] transaction","detail":"{read_only:false; response_revision:5865; number_of_response:1; }","duration":"150.807556ms","start":"2026-10-26T16:50:19.983656Z","end":"2026-10-26T16:50:20.134464Z","steps":["trace[1382446099] 'process raft request' (duration: 150.432676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:20.13453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.03332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:50:20.134578Z","caller":"traceutil/trace.go:171","msg":"trace[830220360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5865; }","duration":"156.141443ms","start":"2026-10-26T16:50:19.978423Z","end":"2026-10-26T16:50:20.134565Z","steps":["trace[830220360] 'agreement among raft nodes before linearized reading' (duration: 155.862906ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:20.134845Z","caller":"traceutil/trace.go:171","msg":"trace[205819561] transaction","detail":"{read_only:false; response_revision:5866; number_of_response:1; }","duration":"151.141904ms","start":"2026-10-26T16:50:19.983671Z","end":"2026-10-26T16:50:20.134813Z","steps":["trace[205819561] 'process raft request' (duration: 150.718433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:20.135073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.682468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-26T16:50:20.135103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.424532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16697"} {"level":"warn","ts":"2026-10-26T16:50:20.135124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.025151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-26T16:50:20.13517Z","caller":"traceutil/trace.go:171","msg":"trace[1579039181] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5867; }","duration":"153.327981ms","start":"2026-10-26T16:50:19.981828Z","end":"2026-10-26T16:50:20.135156Z","steps":["trace[1579039181] 'agreement among raft nodes before linearized reading' (duration: 153.203037ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:20.135174Z","caller":"traceutil/trace.go:171","msg":"trace[1719444805] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5867; }","duration":"153.552726ms","start":"2026-10-26T16:50:19.981599Z","end":"2026-10-26T16:50:20.135151Z","steps":["trace[1719444805] 'agreement among raft nodes before linearized reading' (duration: 153.263488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:20.135107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.894679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wd5wr\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-26T16:50:20.135304Z","caller":"traceutil/trace.go:171","msg":"trace[1545537410] transaction","detail":"{read_only:false; response_revision:5867; number_of_response:1; }","duration":"151.278097ms","start":"2026-10-26T16:50:19.983988Z","end":"2026-10-26T16:50:20.135266Z","steps":["trace[1545537410] 'process raft request' (duration: 150.677971ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:20.135316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.839024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T16:50:20.135366Z","caller":"traceutil/trace.go:171","msg":"trace[816861189] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wd5wr; range_end:; response_count:1; response_revision:5867; }","duration":"108.198698ms","start":"2026-10-26T16:50:20.027146Z","end":"2026-10-26T16:50:20.135345Z","steps":["trace[816861189] 'agreement among raft nodes before linearized reading' (duration: 107.745266ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:20.135381Z","caller":"traceutil/trace.go:171","msg":"trace[1335246782] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5867; }","duration":"108.945896ms","start":"2026-10-26T16:50:20.026418Z","end":"2026-10-26T16:50:20.135364Z","steps":["trace[1335246782] 'agreement among raft nodes before linearized reading' (duration: 108.739181ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:20.135128Z","caller":"traceutil/trace.go:171","msg":"trace[2061063639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5867; }","duration":"113.78645ms","start":"2026-10-26T16:50:20.021325Z","end":"2026-10-26T16:50:20.135111Z","steps":["trace[2061063639] 'agreement among raft nodes before linearized reading' (duration: 113.571225ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:21.44199Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2984} {"level":"info","ts":"2026-10-26T16:50:21.52675Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2984,"took":"83.06229ms","hash":1805298073,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":32149504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T16:50:21.526906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1805298073,"revision":2984,"compact-revision":-1} {"level":"info","ts":"2026-10-26T16:55:21.450949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5883} {"level":"info","ts":"2026-10-26T16:55:21.54102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5883,"took":"88.34507ms","hash":2460920661,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":26517504,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T16:55:21.541174Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2460920661,"revision":5883,"compact-revision":2984} {"level":"info","ts":"2026-10-26T16:56:42.615006Z","caller":"traceutil/trace.go:171","msg":"trace[605890952] linearizableReadLoop","detail":"{readStateIndex:8998; appliedIndex:8997; }","duration":"373.650784ms","start":"2026-10-26T16:56:42.24133Z","end":"2026-10-26T16:56:42.614981Z","steps":["trace[605890952] 'read index received' (duration: 373.524011ms)","trace[605890952] 'applied index is now lower than readState.Index' (duration: 125.603µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:56:42.615083Z","caller":"traceutil/trace.go:171","msg":"trace[795762187] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"380.665826ms","start":"2026-10-26T16:56:42.234376Z","end":"2026-10-26T16:56:42.615042Z","steps":["trace[795762187] 'process raft request' (duration: 380.477741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:42.615217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.86719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-26T16:56:42.615257Z","caller":"traceutil/trace.go:171","msg":"trace[2008656163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8689; }","duration":"373.966153ms","start":"2026-10-26T16:56:42.24128Z","end":"2026-10-26T16:56:42.615247Z","steps":["trace[2008656163] 'agreement among raft nodes before linearized reading' (duration: 373.821038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:42.615277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:56:42.234364Z","time spent":"380.809639ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:56:42.615289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:56:42.241259Z","time spent":"374.020423ms","remote":"127.0.0.1:50824","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-26T16:56:42.834301Z","caller":"traceutil/trace.go:171","msg":"trace[1037768604] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"307.198682ms","start":"2026-10-26T16:56:42.527081Z","end":"2026-10-26T16:56:42.83428Z","steps":["trace[1037768604] 'process raft request' (duration: 307.155611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:42.834447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:56:42.527055Z","time spent":"307.310265ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:56:42.834888Z","caller":"traceutil/trace.go:171","msg":"trace[28373472] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"390.397568ms","start":"2026-10-26T16:56:42.444457Z","end":"2026-10-26T16:56:42.834855Z","steps":["trace[28373472] 'process raft request' (duration: 389.652448ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:56:42.834927Z","caller":"traceutil/trace.go:171","msg":"trace[1068092071] linearizableReadLoop","detail":"{readStateIndex:9000; appliedIndex:9000; }","duration":"219.816838ms","start":"2026-10-26T16:56:42.615078Z","end":"2026-10-26T16:56:42.834895Z","steps":["trace[1068092071] 'read index received' (duration: 219.807198ms)","trace[1068092071] 'applied index is now lower than readState.Index' (duration: 8.03µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:56:42.83506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:56:42.444434Z","time spent":"390.521041ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:56:42.835192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.730856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wd5wr\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-26T16:56:42.835226Z","caller":"traceutil/trace.go:171","msg":"trace[2043311075] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wd5wr; range_end:; response_count:1; response_revision:8691; }","duration":"427.824568ms","start":"2026-10-26T16:56:42.40739Z","end":"2026-10-26T16:56:42.835215Z","steps":["trace[2043311075] 'agreement among raft nodes before linearized reading' (duration: 427.641723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:42.835261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:56:42.407369Z","time spent":"427.883779ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-wd5wr\" "} {"level":"info","ts":"2026-10-26T16:56:42.835261Z","caller":"traceutil/trace.go:171","msg":"trace[273610005] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"215.30819ms","start":"2026-10-26T16:56:42.619939Z","end":"2026-10-26T16:56:42.835247Z","steps":["trace[273610005] 'process raft request' (duration: 215.073134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:42.835836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.393984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:415354"} {"level":"info","ts":"2026-10-26T16:56:42.83627Z","caller":"traceutil/trace.go:171","msg":"trace[823265357] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8693; }","duration":"173.867526ms","start":"2026-10-26T16:56:42.662389Z","end":"2026-10-26T16:56:42.836256Z","steps":["trace[823265357] 'agreement among raft nodes before linearized reading' (duration: 173.030324ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:56:53.82724Z","caller":"traceutil/trace.go:171","msg":"trace[745569455] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"131.077906ms","start":"2026-10-26T16:56:53.696131Z","end":"2026-10-26T16:56:53.827209Z","steps":["trace[745569455] 'process raft request' (duration: 130.909522ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:56:54.867394Z","caller":"traceutil/trace.go:171","msg":"trace[1679575734] transaction","detail":"{read_only:false; response_revision:8753; number_of_response:1; }","duration":"129.389213ms","start":"2026-10-26T16:56:54.737969Z","end":"2026-10-26T16:56:54.867358Z","steps":["trace[1679575734] 'process raft request' (duration: 71.085952ms)","trace[1679575734] 'compare' (duration: 58.104466ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:56:55.243677Z","caller":"traceutil/trace.go:171","msg":"trace[721681255] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"366.968901ms","start":"2026-10-26T16:56:54.876683Z","end":"2026-10-26T16:56:55.243652Z","steps":["trace[721681255] 'process raft request' (duration: 366.802807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:55.244012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:56:54.876663Z","time spent":"367.115324ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:56:55.260509Z","caller":"traceutil/trace.go:171","msg":"trace[2115056516] linearizableReadLoop","detail":"{readStateIndex:9067; appliedIndex:9066; }","duration":"376.550009ms","start":"2026-10-26T16:56:54.883934Z","end":"2026-10-26T16:56:55.260484Z","steps":["trace[2115056516] 'read index received' (duration: 360.664377ms)","trace[2115056516] 'applied index is now lower than readState.Index' (duration: 15.884602ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:56:55.261002Z","caller":"traceutil/trace.go:171","msg":"trace[1021163729] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"383.784986ms","start":"2026-10-26T16:56:54.877173Z","end":"2026-10-26T16:56:55.260958Z","steps":["trace[1021163729] 'process raft request' (duration: 383.197951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:55.261016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.58386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-26T16:56:55.261031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.191029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wd5wr\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-10-26T16:56:55.261087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.120474ms","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-26T16:56:55.26112Z","caller":"traceutil/trace.go:171","msg":"trace[1990489710] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wd5wr; range_end:; response_count:1; response_revision:8755; }","duration":"359.316533ms","start":"2026-10-26T16:56:54.901779Z","end":"2026-10-26T16:56:55.261095Z","steps":["trace[1990489710] 'agreement among raft nodes before linearized reading' (duration: 359.039586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:55.261142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:56:54.877152Z","time spent":"383.91539ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:56:55.261141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.055008ms","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-26T16:56:55.261182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:56:54.901768Z","time spent":"359.403265ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-wd5wr\" "} {"level":"info","ts":"2026-10-26T16:56:55.261174Z","caller":"traceutil/trace.go:171","msg":"trace[29706750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8755; }","duration":"377.246477ms","start":"2026-10-26T16:56:54.883905Z","end":"2026-10-26T16:56:55.261152Z","steps":["trace[29706750] 'agreement among raft nodes before linearized reading' (duration: 376.978701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:55.261196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.424084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:56:55.261215Z","caller":"traceutil/trace.go:171","msg":"trace[364473854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8755; }","duration":"116.17271ms","start":"2026-10-26T16:56:55.145024Z","end":"2026-10-26T16:56:55.261197Z","steps":["trace[364473854] 'agreement among raft nodes before linearized reading' (duration: 115.943585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:55.261235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:56:54.883894Z","time spent":"377.327139ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-26T16:56:55.261228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.921953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:56:55.26125Z","caller":"traceutil/trace.go:171","msg":"trace[1332891277] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8755; }","duration":"188.512156ms","start":"2026-10-26T16:56:55.072723Z","end":"2026-10-26T16:56:55.261235Z","steps":["trace[1332891277] 'agreement among raft nodes before linearized reading' (duration: 188.377983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:56:55.261257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.563902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-26T16:56:55.261291Z","caller":"traceutil/trace.go:171","msg":"trace[2089483789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8755; }","duration":"283.090447ms","start":"2026-10-26T16:56:54.978182Z","end":"2026-10-26T16:56:55.261272Z","steps":["trace[2089483789] 'agreement among raft nodes before linearized reading' (duration: 282.984655ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:56:55.261314Z","caller":"traceutil/trace.go:171","msg":"trace[1668225317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8755; }","duration":"160.655934ms","start":"2026-10-26T16:56:55.100643Z","end":"2026-10-26T16:56:55.261299Z","steps":["trace[1668225317] 'agreement among raft nodes before linearized reading' (duration: 160.48968ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:56:55.261082Z","caller":"traceutil/trace.go:171","msg":"trace[42291462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8755; }","duration":"119.715992ms","start":"2026-10-26T16:56:55.14135Z","end":"2026-10-26T16:56:55.261066Z","steps":["trace[42291462] 'agreement among raft nodes before linearized reading' (duration: 119.471856ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:56:55.767541Z","caller":"traceutil/trace.go:171","msg":"trace[1041702598] transaction","detail":"{read_only:false; response_revision:8760; number_of_response:1; }","duration":"129.427834ms","start":"2026-10-26T16:56:55.638088Z","end":"2026-10-26T16:56:55.767516Z","steps":["trace[1041702598] 'process raft request' (duration: 129.27516ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:22.920418Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243d.wal"} {"level":"info","ts":"2026-10-26T16:57:25.821388Z","caller":"traceutil/trace.go:171","msg":"trace[1429244201] transaction","detail":"{read_only:false; response_revision:8970; number_of_response:1; }","duration":"468.739824ms","start":"2026-10-26T16:57:25.35262Z","end":"2026-10-26T16:57:25.82136Z","steps":["trace[1429244201] 'process raft request' (duration: 468.56979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:25.821545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:25.352596Z","time spent":"468.870788ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:57:25.894378Z","caller":"traceutil/trace.go:171","msg":"trace[399609613] linearizableReadLoop","detail":"{readStateIndex:9292; appliedIndex:9290; }","duration":"459.062323ms","start":"2026-10-26T16:57:25.435294Z","end":"2026-10-26T16:57:25.894356Z","steps":["trace[399609613] 'read index received' (duration: 386.023119ms)","trace[399609613] 'applied index is now lower than readState.Index' (duration: 73.038274ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:57:25.89461Z","caller":"traceutil/trace.go:171","msg":"trace[506645229] transaction","detail":"{read_only:false; response_revision:8971; number_of_response:1; }","duration":"529.683255ms","start":"2026-10-26T16:57:25.364915Z","end":"2026-10-26T16:57:25.894599Z","steps":["trace[506645229] 'process raft request' (duration: 525.075576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:25.894731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:25.364894Z","time spent":"529.745547ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:57:25.894976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.687779ms","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-26T16:57:25.895013Z","caller":"traceutil/trace.go:171","msg":"trace[1683397759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8971; }","duration":"459.758422ms","start":"2026-10-26T16:57:25.435244Z","end":"2026-10-26T16:57:25.895002Z","steps":["trace[1683397759] 'agreement among raft nodes before linearized reading' (duration: 459.646459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:25.895039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:25.435226Z","time spent":"459.806614ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-26T16:57:25.914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.81256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-10-26T16:57:25.91405Z","caller":"traceutil/trace.go:171","msg":"trace[1571810502] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8972; }","duration":"241.936083ms","start":"2026-10-26T16:57:25.672097Z","end":"2026-10-26T16:57:25.914033Z","steps":["trace[1571810502] 'agreement among raft nodes before linearized reading' (duration: 241.81878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:25.914032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.099003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T16:57:25.914107Z","caller":"traceutil/trace.go:171","msg":"trace[1893271726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8972; }","duration":"410.255058ms","start":"2026-10-26T16:57:25.503831Z","end":"2026-10-26T16:57:25.914086Z","steps":["trace[1893271726] 'agreement among raft nodes before linearized reading' (duration: 410.025372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:25.914164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:25.503812Z","time spent":"410.34044ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T16:57:25.914322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.873517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-26T16:57:25.91432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.131704ms","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-26T16:57:25.914377Z","caller":"traceutil/trace.go:171","msg":"trace[1451740276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8972; }","duration":"385.966689ms","start":"2026-10-26T16:57:25.528399Z","end":"2026-10-26T16:57:25.914365Z","steps":["trace[1451740276] 'agreement among raft nodes before linearized reading' (duration: 385.849247ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:25.914393Z","caller":"traceutil/trace.go:171","msg":"trace[584355625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8972; }","duration":"344.238947ms","start":"2026-10-26T16:57:25.570138Z","end":"2026-10-26T16:57:25.914377Z","steps":["trace[584355625] 'agreement among raft nodes before linearized reading' (duration: 344.034941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:25.914408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:25.528379Z","time spent":"386.02187ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T16:57:25.914436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:25.570127Z","time spent":"344.301498ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-26T16:57:25.914496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.204153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wd5wr\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-26T16:57:25.914661Z","caller":"traceutil/trace.go:171","msg":"trace[481398476] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wd5wr; range_end:; response_count:1; response_revision:8972; }","duration":"320.401588ms","start":"2026-10-26T16:57:25.594239Z","end":"2026-10-26T16:57:25.914641Z","steps":["trace[481398476] 'agreement among raft nodes before linearized reading' (duration: 320.110961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:25.914742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:25.594225Z","time spent":"320.50075ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6549,"request content":"key:\"/registry/pods/openstack/keepalived-wd5wr\" "} {"level":"info","ts":"2026-10-26T16:57:36.119848Z","caller":"traceutil/trace.go:171","msg":"trace[643292159] transaction","detail":"{read_only:false; response_revision:9027; number_of_response:1; }","duration":"120.507115ms","start":"2026-10-26T16:57:35.999313Z","end":"2026-10-26T16:57:36.11982Z","steps":["trace[643292159] 'process raft request' (duration: 105.066145ms)","trace[643292159] 'compare' (duration: 15.080461ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:57:36.119946Z","caller":"traceutil/trace.go:171","msg":"trace[1395220046] transaction","detail":"{read_only:false; response_revision:9028; number_of_response:1; }","duration":"110.917066ms","start":"2026-10-26T16:57:36.008994Z","end":"2026-10-26T16:57:36.119911Z","steps":["trace[1395220046] 'process raft request' (duration: 110.583358ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:37.251073Z","caller":"traceutil/trace.go:171","msg":"trace[409608728] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"110.29642ms","start":"2026-10-26T16:57:37.140752Z","end":"2026-10-26T16:57:37.251048Z","steps":["trace[409608728] 'process raft request' (duration: 41.323742ms)","trace[409608728] 'compare' (duration: 68.858696ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:57:39.010891Z","caller":"traceutil/trace.go:171","msg":"trace[265459616] transaction","detail":"{read_only:false; response_revision:9054; number_of_response:1; }","duration":"105.783133ms","start":"2026-10-26T16:57:38.905075Z","end":"2026-10-26T16:57:39.010859Z","steps":["trace[265459616] 'process raft request' (duration: 105.579228ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:51.673809Z","caller":"traceutil/trace.go:171","msg":"trace[1539270940] transaction","detail":"{read_only:false; response_revision:9184; number_of_response:1; }","duration":"113.423341ms","start":"2026-10-26T16:57:51.56036Z","end":"2026-10-26T16:57:51.673783Z","steps":["trace[1539270940] 'process raft request' (duration: 113.217646ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:58:39.138022Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T16:58:39.151177Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T16:58:39.151274Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T16:59:07.451312Z","caller":"traceutil/trace.go:171","msg":"trace[1688588534] transaction","detail":"{read_only:false; response_revision:9898; number_of_response:1; }","duration":"125.606327ms","start":"2026-10-26T16:59:07.325682Z","end":"2026-10-26T16:59:07.451288Z","steps":["trace[1688588534] 'process raft request' (duration: 125.453673ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:00:21.457484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8035} {"level":"info","ts":"2026-10-26T17:00:21.576189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8035,"took":"116.763024ms","hash":1107246252,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":27955200,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T17:00:21.576347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1107246252,"revision":8035,"compact-revision":5883} {"level":"info","ts":"2026-10-26T17:03:16.061287Z","caller":"traceutil/trace.go:171","msg":"trace[1324438336] transaction","detail":"{read_only:false; response_revision:11499; number_of_response:1; }","duration":"121.111947ms","start":"2026-10-26T17:03:15.940148Z","end":"2026-10-26T17:03:16.06126Z","steps":["trace[1324438336] 'process raft request' (duration: 120.961573ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:03:16.063265Z","caller":"traceutil/trace.go:171","msg":"trace[1319627519] transaction","detail":"{read_only:false; response_revision:11500; number_of_response:1; }","duration":"119.652381ms","start":"2026-10-26T17:03:15.943591Z","end":"2026-10-26T17:03:16.063243Z","steps":["trace[1319627519] 'process raft request' (duration: 119.509917ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:03:17.607228Z","caller":"traceutil/trace.go:171","msg":"trace[1066126391] transaction","detail":"{read_only:false; response_revision:11505; number_of_response:1; }","duration":"158.011253ms","start":"2026-10-26T17:03:17.449181Z","end":"2026-10-26T17:03:17.607192Z","steps":["trace[1066126391] 'process raft request' (duration: 157.940961ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:03:17.607415Z","caller":"traceutil/trace.go:171","msg":"trace[1552164559] transaction","detail":"{read_only:false; response_revision:11504; number_of_response:1; }","duration":"160.459277ms","start":"2026-10-26T17:03:17.446928Z","end":"2026-10-26T17:03:17.607387Z","steps":["trace[1552164559] 'process raft request' (duration: 159.950793ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:04:26.143457Z","caller":"traceutil/trace.go:171","msg":"trace[261177300] transaction","detail":"{read_only:false; response_revision:12215; number_of_response:1; }","duration":"279.501787ms","start":"2026-10-26T17:04:25.863922Z","end":"2026-10-26T17:04:26.143423Z","steps":["trace[261177300] 'process raft request' (duration: 230.168868ms)","trace[261177300] 'compare' (duration: 49.101843ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:04:26.143569Z","caller":"traceutil/trace.go:171","msg":"trace[552100863] transaction","detail":"{read_only:false; response_revision:12217; number_of_response:1; }","duration":"106.612834ms","start":"2026-10-26T17:04:26.036818Z","end":"2026-10-26T17:04:26.143431Z","steps":["trace[552100863] 'process raft request' (duration: 106.561883ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:04:26.143646Z","caller":"traceutil/trace.go:171","msg":"trace[2105905627] transaction","detail":"{read_only:false; response_revision:12216; number_of_response:1; }","duration":"278.797029ms","start":"2026-10-26T17:04:25.86482Z","end":"2026-10-26T17:04:26.143617Z","steps":["trace[2105905627] 'process raft request' (duration: 278.510582ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:04:45.669215Z","caller":"traceutil/trace.go:171","msg":"trace[301527537] transaction","detail":"{read_only:false; response_revision:12326; number_of_response:1; }","duration":"123.556364ms","start":"2026-10-26T17:04:45.545634Z","end":"2026-10-26T17:04:45.66919Z","steps":["trace[301527537] 'process raft request' (duration: 123.406509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:04:46.807965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.612523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T17:04:46.808075Z","caller":"traceutil/trace.go:171","msg":"trace[225391126] transaction","detail":"{read_only:false; response_revision:12334; number_of_response:1; }","duration":"241.822159ms","start":"2026-10-26T17:04:46.566233Z","end":"2026-10-26T17:04:46.808055Z","steps":["trace[225391126] 'process raft request' (duration: 12.025702ms)","trace[225391126] 'compare' (duration: 229.482449ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:05:21.464079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10402} {"level":"info","ts":"2026-10-26T17:05:21.552281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10402,"took":"85.984699ms","hash":1359103702,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":24858624,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-26T17:05:21.552366Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1359103702,"revision":10402,"compact-revision":8035} {"level":"info","ts":"2026-10-26T17:10:21.472388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12583} {"level":"info","ts":"2026-10-26T17:10:21.552923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12583,"took":"78.162926ms","hash":3434980042,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":27889664,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T17:10:21.552992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3434980042,"revision":12583,"compact-revision":10402} {"level":"info","ts":"2026-10-26T17:11:04.514075Z","caller":"traceutil/trace.go:171","msg":"trace[1937105849] transaction","detail":"{read_only:false; response_revision:14901; number_of_response:1; }","duration":"199.341648ms","start":"2026-10-26T17:11:04.3147Z","end":"2026-10-26T17:11:04.514041Z","steps":["trace[1937105849] 'process raft request' (duration: 198.716721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:11:04.514534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.01791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T17:11:04.514636Z","caller":"traceutil/trace.go:171","msg":"trace[401667595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14901; }","duration":"194.147963ms","start":"2026-10-26T17:11:04.320473Z","end":"2026-10-26T17:11:04.514621Z","steps":["trace[401667595] 'agreement among raft nodes before linearized reading' (duration: 193.657191ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:11:04.514079Z","caller":"traceutil/trace.go:171","msg":"trace[585269275] linearizableReadLoop","detail":"{readStateIndex:15416; appliedIndex:15415; }","duration":"193.532028ms","start":"2026-10-26T17:11:04.320478Z","end":"2026-10-26T17:11:04.51401Z","steps":["trace[585269275] 'read index received' (duration: 192.86544ms)","trace[585269275] 'applied index is now lower than readState.Index' (duration: 665.558µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:11:04.51539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.013936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T17:11:04.519178Z","caller":"traceutil/trace.go:171","msg":"trace[1777704980] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:14901; }","duration":"144.822994ms","start":"2026-10-26T17:11:04.374332Z","end":"2026-10-26T17:11:04.519155Z","steps":["trace[1777704980] 'agreement among raft nodes before linearized reading' (duration: 141.005255ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:11:27.849664Z","caller":"traceutil/trace.go:171","msg":"trace[1773178360] transaction","detail":"{read_only:false; response_revision:15023; number_of_response:1; }","duration":"101.422229ms","start":"2026-10-26T17:11:27.748219Z","end":"2026-10-26T17:11:27.849641Z","steps":["trace[1773178360] 'process raft request' (duration: 101.276565ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:11:28.014354Z","caller":"traceutil/trace.go:171","msg":"trace[715792007] transaction","detail":"{read_only:false; response_revision:15024; number_of_response:1; }","duration":"219.81455ms","start":"2026-10-26T17:11:27.794485Z","end":"2026-10-26T17:11:28.014299Z","steps":["trace[715792007] 'process raft request' (duration: 193.027935ms)","trace[715792007] 'compare' (duration: 26.554448ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:11:28.014481Z","caller":"traceutil/trace.go:171","msg":"trace[1935347193] transaction","detail":"{read_only:false; response_revision:15025; number_of_response:1; }","duration":"207.416618ms","start":"2026-10-26T17:11:27.807041Z","end":"2026-10-26T17:11:28.014458Z","steps":["trace[1935347193] 'process raft request' (duration: 207.13846ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:11:45.897467Z","caller":"traceutil/trace.go:171","msg":"trace[248706182] linearizableReadLoop","detail":"{readStateIndex:15657; appliedIndex:15656; }","duration":"193.416845ms","start":"2026-10-26T17:11:45.704026Z","end":"2026-10-26T17:11:45.897442Z","steps":["trace[248706182] 'read index received' (duration: 166.842345ms)","trace[248706182] 'applied index is now lower than readState.Index' (duration: 26.57336ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:11:45.897514Z","caller":"traceutil/trace.go:171","msg":"trace[1872166137] transaction","detail":"{read_only:false; response_revision:15131; number_of_response:1; }","duration":"338.231759ms","start":"2026-10-26T17:11:45.559248Z","end":"2026-10-26T17:11:45.89748Z","steps":["trace[1872166137] 'process raft request' (duration: 311.627268ms)","trace[1872166137] 'compare' (duration: 26.324974ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:11:45.897564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.529907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T17:11:45.897586Z","caller":"traceutil/trace.go:171","msg":"trace[1438551692] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:15131; }","duration":"193.608359ms","start":"2026-10-26T17:11:45.70397Z","end":"2026-10-26T17:11:45.897578Z","steps":["trace[1438551692] 'agreement among raft nodes before linearized reading' (duration: 193.549938ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:11:45.89764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:11:45.559216Z","time spent":"338.356082ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:12:09.77436Z","caller":"traceutil/trace.go:171","msg":"trace[643850702] linearizableReadLoop","detail":"{readStateIndex:15985; appliedIndex:15984; }","duration":"241.620444ms","start":"2026-10-26T17:12:09.532702Z","end":"2026-10-26T17:12:09.774322Z","steps":["trace[643850702] 'read index received' (duration: 166.113346ms)","trace[643850702] 'applied index is now lower than readState.Index' (duration: 75.505198ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:12:09.774421Z","caller":"traceutil/trace.go:171","msg":"trace[1675089214] transaction","detail":"{read_only:false; response_revision:15447; number_of_response:1; }","duration":"452.536103ms","start":"2026-10-26T17:12:09.321814Z","end":"2026-10-26T17:12:09.77435Z","steps":["trace[1675089214] 'process raft request' (duration: 377.010915ms)","trace[1675089214] 'compare' (duration: 75.274941ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:12:09.774672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:12:09.321788Z","time spent":"452.750327ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:12:09.774702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.964113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-vrwbf\" ","response":"range_response_count:1 size:20787"} {"level":"info","ts":"2026-10-26T17:12:09.774821Z","caller":"traceutil/trace.go:171","msg":"trace[1361207620] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-vrwbf; range_end:; response_count:1; response_revision:15447; }","duration":"242.154748ms","start":"2026-10-26T17:12:09.532628Z","end":"2026-10-26T17:12:09.774783Z","steps":["trace[1361207620] 'agreement among raft nodes before linearized reading' (duration: 241.808769ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:12:09.786659Z","caller":"traceutil/trace.go:171","msg":"trace[1588425331] transaction","detail":"{read_only:false; response_revision:15448; number_of_response:1; }","duration":"176.604949ms","start":"2026-10-26T17:12:09.610024Z","end":"2026-10-26T17:12:09.786629Z","steps":["trace[1588425331] 'process raft request' (duration: 176.021464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:12:09.786643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.337545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9792bf5a-5452-4776-b315-beef7811c2da\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-10-26T17:12:09.787415Z","caller":"traceutil/trace.go:171","msg":"trace[115631203] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9792bf5a-5452-4776-b315-beef7811c2da; range_end:; response_count:1; response_revision:15448; }","duration":"234.53256ms","start":"2026-10-26T17:12:09.552203Z","end":"2026-10-26T17:12:09.786736Z","steps":["trace[115631203] 'agreement among raft nodes before linearized reading' (duration: 234.12981ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:12:09.899489Z","caller":"traceutil/trace.go:171","msg":"trace[819642816] transaction","detail":"{read_only:false; response_revision:15450; number_of_response:1; }","duration":"102.281841ms","start":"2026-10-26T17:12:09.797165Z","end":"2026-10-26T17:12:09.899447Z","steps":["trace[819642816] 'process raft request' (duration: 95.221028ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:13:01.749487Z","caller":"traceutil/trace.go:171","msg":"trace[680976997] linearizableReadLoop","detail":"{readStateIndex:16631; appliedIndex:16630; }","duration":"164.810541ms","start":"2026-10-26T17:13:01.584638Z","end":"2026-10-26T17:13:01.749448Z","steps":["trace[680976997] 'read index received' (duration: 164.450602ms)","trace[680976997] 'applied index is now lower than readState.Index' (duration: 358.299µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:13:01.749592Z","caller":"traceutil/trace.go:171","msg":"trace[1262150252] transaction","detail":"{read_only:false; response_revision:16077; number_of_response:1; }","duration":"165.990401ms","start":"2026-10-26T17:13:01.58356Z","end":"2026-10-26T17:13:01.749551Z","steps":["trace[1262150252] 'process raft request' (duration: 165.650012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:13:01.749846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.173681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12428"} {"level":"warn","ts":"2026-10-26T17:13:01.749886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.649104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wd5wr\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-26T17:13:01.749937Z","caller":"traceutil/trace.go:171","msg":"trace[1209628418] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16077; }","duration":"165.305393ms","start":"2026-10-26T17:13:01.584614Z","end":"2026-10-26T17:13:01.74992Z","steps":["trace[1209628418] 'agreement among raft nodes before linearized reading' (duration: 165.048557ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:13:01.749947Z","caller":"traceutil/trace.go:171","msg":"trace[566028457] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wd5wr; range_end:; response_count:1; response_revision:16077; }","duration":"101.757667ms","start":"2026-10-26T17:13:01.648177Z","end":"2026-10-26T17:13:01.749935Z","steps":["trace[566028457] 'agreement among raft nodes before linearized reading' (duration: 101.604363ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:13:20.73105Z","caller":"traceutil/trace.go:171","msg":"trace[724648833] linearizableReadLoop","detail":"{readStateIndex:16733; appliedIndex:16732; }","duration":"359.67601ms","start":"2026-10-26T17:13:20.371334Z","end":"2026-10-26T17:13:20.73101Z","steps":["trace[724648833] 'read index received' (duration: 359.459295ms)","trace[724648833] 'applied index is now lower than readState.Index' (duration: 215.155µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:13:20.731232Z","caller":"traceutil/trace.go:171","msg":"trace[1367714304] transaction","detail":"{read_only:false; response_revision:16175; number_of_response:1; }","duration":"362.523524ms","start":"2026-10-26T17:13:20.368688Z","end":"2026-10-26T17:13:20.731212Z","steps":["trace[1367714304] 'process raft request' (duration: 361.9574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:13:20.731414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:13:20.368654Z","time spent":"362.620797ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:13:20.73147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.124722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-26T17:13:20.731526Z","caller":"traceutil/trace.go:171","msg":"trace[374805109] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16175; }","duration":"360.221615ms","start":"2026-10-26T17:13:20.37129Z","end":"2026-10-26T17:13:20.731512Z","steps":["trace[374805109] 'agreement among raft nodes before linearized reading' (duration: 360.007048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:13:20.73156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:13:20.371278Z","time spent":"360.273617ms","remote":"127.0.0.1:50734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-10-26T17:13:20.809338Z","caller":"traceutil/trace.go:171","msg":"trace[468210565] transaction","detail":"{read_only:false; response_revision:16176; number_of_response:1; }","duration":"361.763004ms","start":"2026-10-26T17:13:20.447531Z","end":"2026-10-26T17:13:20.809294Z","steps":["trace[468210565] 'process raft request' (duration: 304.945061ms)","trace[468210565] 'compare' (duration: 56.544086ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:13:20.809509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:13:20.447503Z","time spent":"361.918478ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:13:20.80951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.72483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T17:13:20.809555Z","caller":"traceutil/trace.go:171","msg":"trace[63627412] transaction","detail":"{read_only:false; response_revision:16178; number_of_response:1; }","duration":"357.570816ms","start":"2026-10-26T17:13:20.45195Z","end":"2026-10-26T17:13:20.809521Z","steps":["trace[63627412] 'process raft request' (duration: 357.271578ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:13:20.809593Z","caller":"traceutil/trace.go:171","msg":"trace[1428187280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16179; }","duration":"423.844264ms","start":"2026-10-26T17:13:20.385727Z","end":"2026-10-26T17:13:20.809571Z","steps":["trace[1428187280] 'agreement among raft nodes before linearized reading' (duration: 423.594318ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:13:20.809588Z","caller":"traceutil/trace.go:171","msg":"trace[1838155067] transaction","detail":"{read_only:false; response_revision:16179; number_of_response:1; }","duration":"185.866067ms","start":"2026-10-26T17:13:20.623699Z","end":"2026-10-26T17:13:20.809565Z","steps":["trace[1838155067] 'process raft request' (duration: 185.562729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:13:20.809633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:13:20.451933Z","time spent":"357.658408ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:13:20.809637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:13:20.385716Z","time spent":"423.912685ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T17:13:20.809693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.8533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T17:13:20.809716Z","caller":"traceutil/trace.go:171","msg":"trace[323688812] transaction","detail":"{read_only:false; response_revision:16177; number_of_response:1; }","duration":"359.849834ms","start":"2026-10-26T17:13:20.449855Z","end":"2026-10-26T17:13:20.809705Z","steps":["trace[323688812] 'process raft request' (duration: 359.325101ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:13:20.809729Z","caller":"traceutil/trace.go:171","msg":"trace[2132563830] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16179; }","duration":"299.954302ms","start":"2026-10-26T17:13:20.509762Z","end":"2026-10-26T17:13:20.809716Z","steps":["trace[2132563830] 'agreement among raft nodes before linearized reading' (duration: 299.885101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:13:20.809755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:13:20.509712Z","time spent":"300.037065ms","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-10-26T17:13:20.809764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.995214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T17:13:20.809767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:13:20.449803Z","time spent":"359.935798ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:13:20.809791Z","caller":"traceutil/trace.go:171","msg":"trace[138532461] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16179; }","duration":"300.052106ms","start":"2026-10-26T17:13:20.50973Z","end":"2026-10-26T17:13:20.809782Z","steps":["trace[138532461] 'agreement among raft nodes before linearized reading' (duration: 300.007375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:13:20.809789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.236491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T17:13:20.809816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:13:20.509714Z","time spent":"300.096927ms","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-10-26T17:13:20.809826Z","caller":"traceutil/trace.go:171","msg":"trace[1360707400] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16179; }","duration":"300.328413ms","start":"2026-10-26T17:13:20.509487Z","end":"2026-10-26T17:13:20.809815Z","steps":["trace[1360707400] 'agreement among raft nodes before linearized reading' (duration: 300.267881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:13:20.809762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.962775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-vrwbf\" ","response":"range_response_count:1 size:20787"} {"level":"warn","ts":"2026-10-26T17:13:20.809859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:13:20.509463Z","time spent":"300.388285ms","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"info","ts":"2026-10-26T17:13:20.809945Z","caller":"traceutil/trace.go:171","msg":"trace[1670334787] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-vrwbf; range_end:; response_count:1; response_revision:16179; }","duration":"248.181362ms","start":"2026-10-26T17:13:20.561702Z","end":"2026-10-26T17:13:20.809883Z","steps":["trace[1670334787] 'agreement among raft nodes before linearized reading' (duration: 247.76898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:13:20.809987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.780433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-10-26T17:13:20.80998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.553741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-f5tfs\" ","response":"range_response_count:1 size:9790"} {"level":"info","ts":"2026-10-26T17:13:20.810092Z","caller":"traceutil/trace.go:171","msg":"trace[1292904060] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16179; }","duration":"293.835025ms","start":"2026-10-26T17:13:20.51617Z","end":"2026-10-26T17:13:20.810005Z","steps":["trace[1292904060] 'agreement among raft nodes before linearized reading' (duration: 293.722842ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:13:20.810122Z","caller":"traceutil/trace.go:171","msg":"trace[1666401928] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-f5tfs; range_end:; response_count:1; response_revision:16179; }","duration":"236.746995ms","start":"2026-10-26T17:13:20.573327Z","end":"2026-10-26T17:13:20.810074Z","steps":["trace[1666401928] 'agreement among raft nodes before linearized reading' (duration: 236.268652ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:13:21.968709Z","caller":"traceutil/trace.go:171","msg":"trace[634755495] linearizableReadLoop","detail":"{readStateIndex:16743; appliedIndex:16742; }","duration":"102.371383ms","start":"2026-10-26T17:13:21.866311Z","end":"2026-10-26T17:13:21.968682Z","steps":["trace[634755495] 'read index received' (duration: 102.161868ms)","trace[634755495] 'applied index is now lower than readState.Index' (duration: 207.535µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:13:21.968794Z","caller":"traceutil/trace.go:171","msg":"trace[1295550517] transaction","detail":"{read_only:false; response_revision:16185; number_of_response:1; }","duration":"102.64873ms","start":"2026-10-26T17:13:21.866099Z","end":"2026-10-26T17:13:21.968747Z","steps":["trace[1295550517] 'process raft request' (duration: 102.397004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:13:21.969062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.727572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-10-26T17:13:21.969108Z","caller":"traceutil/trace.go:171","msg":"trace[1652771555] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16185; }","duration":"102.818885ms","start":"2026-10-26T17:13:21.866278Z","end":"2026-10-26T17:13:21.969097Z","steps":["trace[1652771555] 'agreement among raft nodes before linearized reading' (duration: 102.545978ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:15:21.479299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14602} {"level":"info","ts":"2026-10-26T17:15:21.561779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14602,"took":"79.781097ms","hash":821557204,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":31264768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T17:15:21.56184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":821557204,"revision":14602,"compact-revision":12583} {"level":"info","ts":"2026-10-26T17:19:44.454886Z","caller":"traceutil/trace.go:171","msg":"trace[1843535409] transaction","detail":"{read_only:false; response_revision:18938; number_of_response:1; }","duration":"175.279571ms","start":"2026-10-26T17:19:44.279563Z","end":"2026-10-26T17:19:44.454842Z","steps":["trace[1843535409] 'process raft request' (duration: 175.034365ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:19:44.521098Z","caller":"traceutil/trace.go:171","msg":"trace[33553301] linearizableReadLoop","detail":"{readStateIndex:19588; appliedIndex:19586; }","duration":"101.139311ms","start":"2026-10-26T17:19:44.419931Z","end":"2026-10-26T17:19:44.521071Z","steps":["trace[33553301] 'read index received' (duration: 34.706349ms)","trace[33553301] 'applied index is now lower than readState.Index' (duration: 66.431432ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:19:44.521288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.341826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:19:44.521394Z","caller":"traceutil/trace.go:171","msg":"trace[1609530226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18939; }","duration":"101.492731ms","start":"2026-10-26T17:19:44.419862Z","end":"2026-10-26T17:19:44.521355Z","steps":["trace[1609530226] 'agreement among raft nodes before linearized reading' (duration: 101.366538ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:19:44.521611Z","caller":"traceutil/trace.go:171","msg":"trace[1969667843] transaction","detail":"{read_only:false; response_revision:18939; number_of_response:1; }","duration":"236.962281ms","start":"2026-10-26T17:19:44.284637Z","end":"2026-10-26T17:19:44.5216Z","steps":["trace[1969667843] 'process raft request' (duration: 229.986679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:19:51.930927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.388882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:956444"} {"level":"info","ts":"2026-10-26T17:19:51.931012Z","caller":"traceutil/trace.go:171","msg":"trace[95029547] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18985; }","duration":"134.526806ms","start":"2026-10-26T17:19:51.796465Z","end":"2026-10-26T17:19:51.930992Z","steps":["trace[95029547] 'range keys from in-memory index tree' (duration: 133.548671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:19:52.384223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.218239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:19:52.384298Z","caller":"traceutil/trace.go:171","msg":"trace[165670102] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:18987; }","duration":"244.388593ms","start":"2026-10-26T17:19:52.139893Z","end":"2026-10-26T17:19:52.384281Z","steps":["trace[165670102] 'count revisions from in-memory index tree' (duration: 244.133006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:19:52.384676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.797363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:19:52.384739Z","caller":"traceutil/trace.go:171","msg":"trace[262542705] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:18987; }","duration":"183.931326ms","start":"2026-10-26T17:19:52.200793Z","end":"2026-10-26T17:19:52.384724Z","steps":["trace[262542705] 'count revisions from in-memory index tree' (duration: 183.683679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:19:52.977073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.554815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:19:52.977189Z","caller":"traceutil/trace.go:171","msg":"trace[914264972] linearizableReadLoop","detail":"{readStateIndex:19640; appliedIndex:19639; }","duration":"108.956684ms","start":"2026-10-26T17:19:52.868214Z","end":"2026-10-26T17:19:52.97717Z","steps":["trace[914264972] 'read index received' (duration: 35.731µs)","trace[914264972] 'applied index is now lower than readState.Index' (duration: 108.919363ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:19:52.977516Z","caller":"traceutil/trace.go:171","msg":"trace[1527690905] transaction","detail":"{read_only:false; response_revision:18989; number_of_response:1; }","duration":"426.696007ms","start":"2026-10-26T17:19:52.550585Z","end":"2026-10-26T17:19:52.977281Z","steps":["trace[1527690905] 'process raft request' (duration: 51.841014ms)","trace[1527690905] 'compare' (duration: 374.271977ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:19:52.977566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.348224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-26T17:19:52.97778Z","caller":"traceutil/trace.go:171","msg":"trace[1419786176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18989; }","duration":"109.589351ms","start":"2026-10-26T17:19:52.868177Z","end":"2026-10-26T17:19:52.977766Z","steps":["trace[1419786176] 'agreement among raft nodes before linearized reading' (duration: 109.19997ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:19:52.977868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:19:52.550562Z","time spent":"427.098217ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:19:53.361128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.339899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:19:53.361216Z","caller":"traceutil/trace.go:171","msg":"trace[24546047] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:0; response_revision:18990; }","duration":"103.479801ms","start":"2026-10-26T17:19:53.257715Z","end":"2026-10-26T17:19:53.361195Z","steps":["trace[24546047] 'range keys from in-memory index tree' (duration: 103.260625ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:20:21.485436Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16926} {"level":"info","ts":"2026-10-26T17:20:21.589428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16926,"took":"100.633877ms","hash":4175724781,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":32587776,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T17:20:21.5895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4175724781,"revision":16926,"compact-revision":14602} {"level":"info","ts":"2026-10-26T17:20:25.793603Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T17:20:25.800598Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T17:20:25.800718Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T17:21:14.12221Z","caller":"traceutil/trace.go:171","msg":"trace[1591279423] transaction","detail":"{read_only:false; response_revision:19856; number_of_response:1; }","duration":"130.455621ms","start":"2026-10-26T17:21:13.991723Z","end":"2026-10-26T17:21:14.122178Z","steps":["trace[1591279423] 'process raft request' (duration: 111.445559ms)","trace[1591279423] 'compare' (duration: 18.876799ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:21:14.171286Z","caller":"traceutil/trace.go:171","msg":"trace[306331482] linearizableReadLoop","detail":"{readStateIndex:20533; appliedIndex:20530; }","duration":"132.252017ms","start":"2026-10-26T17:21:14.03901Z","end":"2026-10-26T17:21:14.171262Z","steps":["trace[306331482] 'read index received' (duration: 64.290036ms)","trace[306331482] 'applied index is now lower than readState.Index' (duration: 67.960771ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:21:14.171328Z","caller":"traceutil/trace.go:171","msg":"trace[992825423] transaction","detail":"{read_only:false; response_revision:19857; number_of_response:1; }","duration":"179.0643ms","start":"2026-10-26T17:21:13.992223Z","end":"2026-10-26T17:21:14.171287Z","steps":["trace[992825423] 'process raft request' (duration: 178.849764ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:21:14.171392Z","caller":"traceutil/trace.go:171","msg":"trace[664792587] transaction","detail":"{read_only:false; response_revision:19858; number_of_response:1; }","duration":"152.461941ms","start":"2026-10-26T17:21:14.018881Z","end":"2026-10-26T17:21:14.171343Z","steps":["trace[664792587] 'process raft request' (duration: 152.334278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:21:14.171468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.432532ms","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-26T17:21:14.171511Z","caller":"traceutil/trace.go:171","msg":"trace[1339241289] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19858; }","duration":"132.524064ms","start":"2026-10-26T17:21:14.038973Z","end":"2026-10-26T17:21:14.171497Z","steps":["trace[1339241289] 'agreement among raft nodes before linearized reading' (duration: 132.37973ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:25:21.495365Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19313} {"level":"info","ts":"2026-10-26T17:25:21.608377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19313,"took":"108.522883ms","hash":715507397,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":33456128,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T17:25:21.608457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":715507397,"revision":19313,"compact-revision":16926} {"level":"warn","ts":"2026-10-26T17:25:44.655013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.189811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"info","ts":"2026-10-26T17:25:44.655103Z","caller":"traceutil/trace.go:171","msg":"trace[1775059262] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21703; }","duration":"109.327135ms","start":"2026-10-26T17:25:44.545755Z","end":"2026-10-26T17:25:44.655082Z","steps":["trace[1775059262] 'range keys from in-memory index tree' (duration: 108.994066ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:25:55.358957Z","caller":"traceutil/trace.go:171","msg":"trace[2019614478] transaction","detail":"{read_only:false; response_revision:21758; number_of_response:1; }","duration":"133.715957ms","start":"2026-10-26T17:25:55.225215Z","end":"2026-10-26T17:25:55.358931Z","steps":["trace[2019614478] 'process raft request' (duration: 133.548613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:26:01.504112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.70721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:26:01.504262Z","caller":"traceutil/trace.go:171","msg":"trace[490050030] transaction","detail":"{read_only:false; response_revision:21785; number_of_response:1; }","duration":"357.49999ms","start":"2026-10-26T17:26:01.146732Z","end":"2026-10-26T17:26:01.504232Z","steps":["trace[490050030] 'process raft request' (duration: 91.547445ms)","trace[490050030] 'compare' (duration: 265.492724ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:26:01.504335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:26:01.146706Z","time spent":"357.594603ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:26:01.801061Z","caller":"traceutil/trace.go:171","msg":"trace[1669362445] linearizableReadLoop","detail":"{readStateIndex:22530; appliedIndex:22529; }","duration":"287.355441ms","start":"2026-10-26T17:26:01.513675Z","end":"2026-10-26T17:26:01.80103Z","steps":["trace[1669362445] 'read index received' (duration: 225.517398ms)","trace[1669362445] 'applied index is now lower than readState.Index' (duration: 61.836603ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:26:01.801087Z","caller":"traceutil/trace.go:171","msg":"trace[609584552] transaction","detail":"{read_only:false; response_revision:21786; number_of_response:1; }","duration":"422.065515ms","start":"2026-10-26T17:26:01.378987Z","end":"2026-10-26T17:26:01.801052Z","steps":["trace[609584552] 'process raft request' (duration: 360.318414ms)","trace[609584552] 'compare' (duration: 61.484074ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:26:01.80129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.605958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-26T17:26:01.801344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.148444ms","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-26T17:26:01.801356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.572731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T17:26:01.801373Z","caller":"traceutil/trace.go:171","msg":"trace[1387290422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21786; }","duration":"287.735542ms","start":"2026-10-26T17:26:01.513618Z","end":"2026-10-26T17:26:01.801353Z","steps":["trace[1387290422] 'agreement among raft nodes before linearized reading' (duration: 287.515456ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:26:01.801386Z","caller":"traceutil/trace.go:171","msg":"trace[1392128640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21786; }","duration":"177.244877ms","start":"2026-10-26T17:26:01.624129Z","end":"2026-10-26T17:26:01.801374Z","steps":["trace[1392128640] 'agreement among raft nodes before linearized reading' (duration: 177.059091ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:26:01.801422Z","caller":"traceutil/trace.go:171","msg":"trace[1200352907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21786; }","duration":"173.688054ms","start":"2026-10-26T17:26:01.627715Z","end":"2026-10-26T17:26:01.801403Z","steps":["trace[1200352907] 'agreement among raft nodes before linearized reading' (duration: 173.52708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:26:01.801416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:26:01.378959Z","time spent":"422.229659ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:26:02.187078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.081818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:26:02.187299Z","caller":"traceutil/trace.go:171","msg":"trace[332418533] linearizableReadLoop","detail":"{readStateIndex:22533; appliedIndex:22531; }","duration":"208.263771ms","start":"2026-10-26T17:26:01.979012Z","end":"2026-10-26T17:26:02.187276Z","steps":["trace[332418533] 'read index received' (duration: 73.900606ms)","trace[332418533] 'applied index is now lower than readState.Index' (duration: 134.361865ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:26:02.187423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.431655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:26:02.187471Z","caller":"traceutil/trace.go:171","msg":"trace[33335735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21789; }","duration":"208.528267ms","start":"2026-10-26T17:26:01.978929Z","end":"2026-10-26T17:26:02.187457Z","steps":["trace[33335735] 'agreement among raft nodes before linearized reading' (duration: 208.418895ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:26:02.187588Z","caller":"traceutil/trace.go:171","msg":"trace[623894544] transaction","detail":"{read_only:false; response_revision:21788; number_of_response:1; }","duration":"381.238967ms","start":"2026-10-26T17:26:01.806308Z","end":"2026-10-26T17:26:02.187547Z","steps":["trace[623894544] 'process raft request' (duration: 246.587305ms)","trace[623894544] 'compare' (duration: 133.710197ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:26:02.187656Z","caller":"traceutil/trace.go:171","msg":"trace[1941433766] transaction","detail":"{read_only:false; response_revision:21789; number_of_response:1; }","duration":"380.302932ms","start":"2026-10-26T17:26:01.807324Z","end":"2026-10-26T17:26:02.187627Z","steps":["trace[1941433766] 'process raft request' (duration: 379.867101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:26:02.187766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:26:01.806285Z","time spent":"381.38915ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:26:02.187967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:26:01.807313Z","time spent":"380.515438ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:26:02.258937Z","caller":"traceutil/trace.go:171","msg":"trace[1982093332] transaction","detail":"{read_only:false; response_revision:21790; number_of_response:1; }","duration":"278.169264ms","start":"2026-10-26T17:26:01.980749Z","end":"2026-10-26T17:26:02.258919Z","steps":["trace[1982093332] 'process raft request' (duration: 277.981469ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:27:14.324436Z","caller":"traceutil/trace.go:171","msg":"trace[2077212619] linearizableReadLoop","detail":"{readStateIndex:23221; appliedIndex:23220; }","duration":"115.614738ms","start":"2026-10-26T17:27:14.208796Z","end":"2026-10-26T17:27:14.324411Z","steps":["trace[2077212619] 'read index received' (duration: 115.443453ms)","trace[2077212619] 'applied index is now lower than readState.Index' (duration: 170.615µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:27:14.324521Z","caller":"traceutil/trace.go:171","msg":"trace[80502362] transaction","detail":"{read_only:false; response_revision:22454; number_of_response:1; }","duration":"119.835078ms","start":"2026-10-26T17:27:14.204655Z","end":"2026-10-26T17:27:14.32449Z","steps":["trace[80502362] 'process raft request' (duration: 119.631572ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:27:14.324603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.779352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:27:14.324695Z","caller":"traceutil/trace.go:171","msg":"trace[103525391] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:22454; }","duration":"115.926806ms","start":"2026-10-26T17:27:14.208746Z","end":"2026-10-26T17:27:14.324673Z","steps":["trace[103525391] 'agreement among raft nodes before linearized reading' (duration: 115.763842ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:30:03.67771Z","caller":"traceutil/trace.go:171","msg":"trace[1410086651] transaction","detail":"{read_only:false; response_revision:24184; number_of_response:1; }","duration":"193.566609ms","start":"2026-10-26T17:30:03.484052Z","end":"2026-10-26T17:30:03.677619Z","steps":["trace[1410086651] 'process raft request' (duration: 135.287619ms)","trace[1410086651] 'compare' (duration: 57.87639ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:30:03.771202Z","caller":"traceutil/trace.go:171","msg":"trace[601485244] linearizableReadLoop","detail":"{readStateIndex:24999; appliedIndex:24997; }","duration":"284.872228ms","start":"2026-10-26T17:30:03.486303Z","end":"2026-10-26T17:30:03.771175Z","steps":["trace[601485244] 'read index received' (duration: 133.151143ms)","trace[601485244] 'applied index is now lower than readState.Index' (duration: 151.720195ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:30:03.771208Z","caller":"traceutil/trace.go:171","msg":"trace[321806689] transaction","detail":"{read_only:false; response_revision:24185; number_of_response:1; }","duration":"286.359185ms","start":"2026-10-26T17:30:03.484773Z","end":"2026-10-26T17:30:03.771132Z","steps":["trace[321806689] 'process raft request' (duration: 286.027336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:30:03.771395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.079863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T17:30:03.771371Z","caller":"traceutil/trace.go:171","msg":"trace[564063546] transaction","detail":"{read_only:false; response_revision:24186; number_of_response:1; }","duration":"190.51534ms","start":"2026-10-26T17:30:03.580797Z","end":"2026-10-26T17:30:03.771312Z","steps":["trace[564063546] 'process raft request' (duration: 190.205001ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:30:03.771431Z","caller":"traceutil/trace.go:171","msg":"trace[245517331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24186; }","duration":"285.228147ms","start":"2026-10-26T17:30:03.486192Z","end":"2026-10-26T17:30:03.77142Z","steps":["trace[245517331] 'agreement among raft nodes before linearized reading' (duration: 285.098834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:30:03.783192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.35515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/machinehealthchecks.cluster.x-k8s.io\" ","response":"range_response_count:1 size:36374"} {"level":"info","ts":"2026-10-26T17:30:03.783283Z","caller":"traceutil/trace.go:171","msg":"trace[1685454324] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/machinehealthchecks.cluster.x-k8s.io; range_end:; response_count:1; response_revision:24188; }","duration":"270.514743ms","start":"2026-10-26T17:30:03.512748Z","end":"2026-10-26T17:30:03.783263Z","steps":["trace[1685454324] 'agreement among raft nodes before linearized reading' (duration: 270.253647ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:30:03.783412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.236799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:206"} {"level":"info","ts":"2026-10-26T17:30:03.783456Z","caller":"traceutil/trace.go:171","msg":"trace[872057501] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:24188; }","duration":"120.343491ms","start":"2026-10-26T17:30:03.663097Z","end":"2026-10-26T17:30:03.78344Z","steps":["trace[872057501] 'agreement among raft nodes before linearized reading' (duration: 120.223728ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:30:08.548458Z","caller":"traceutil/trace.go:171","msg":"trace[1438988139] transaction","detail":"{read_only:false; response_revision:24296; number_of_response:1; }","duration":"280.269799ms","start":"2026-10-26T17:30:08.268165Z","end":"2026-10-26T17:30:08.548434Z","steps":["trace[1438988139] 'process raft request' (duration: 280.070763ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:30:08.557166Z","caller":"traceutil/trace.go:171","msg":"trace[594198651] transaction","detail":"{read_only:false; response_revision:24297; number_of_response:1; }","duration":"247.689483ms","start":"2026-10-26T17:30:08.309452Z","end":"2026-10-26T17:30:08.557142Z","steps":["trace[594198651] 'process raft request' (duration: 247.071417ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:30:21.514461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21584} {"level":"info","ts":"2026-10-26T17:30:21.628579Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21584,"took":"110.425403ms","hash":183188083,"current-db-size-bytes":56823808,"current-db-size":"57 MB","current-db-size-in-use-bytes":42549248,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-26T17:30:21.628666Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":183188083,"revision":21584,"compact-revision":19313} {"level":"info","ts":"2026-10-26T17:33:31.745205Z","caller":"traceutil/trace.go:171","msg":"trace[1502581219] transaction","detail":"{read_only:false; response_revision:26313; number_of_response:1; }","duration":"120.526636ms","start":"2026-10-26T17:33:31.624647Z","end":"2026-10-26T17:33:31.745173Z","steps":["trace[1502581219] 'process raft request' (duration: 119.351975ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:33:37.373561Z","caller":"traceutil/trace.go:171","msg":"trace[998419680] linearizableReadLoop","detail":"{readStateIndex:27226; appliedIndex:27225; }","duration":"372.361746ms","start":"2026-10-26T17:33:37.001171Z","end":"2026-10-26T17:33:37.373533Z","steps":["trace[998419680] 'read index received' (duration: 372.222702ms)","trace[998419680] 'applied index is now lower than readState.Index' (duration: 137.724µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:33:37.373605Z","caller":"traceutil/trace.go:171","msg":"trace[75377877] transaction","detail":"{read_only:false; response_revision:26358; number_of_response:1; }","duration":"380.255041ms","start":"2026-10-26T17:33:36.99332Z","end":"2026-10-26T17:33:37.373575Z","steps":["trace[75377877] 'process raft request' (duration: 380.036965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:33:37.373786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:33:36.993296Z","time spent":"380.374163ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:33:37.373953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.716525ms","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-26T17:33:37.374044Z","caller":"traceutil/trace.go:171","msg":"trace[1598778313] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26358; }","duration":"372.837019ms","start":"2026-10-26T17:33:37.001156Z","end":"2026-10-26T17:33:37.373993Z","steps":["trace[1598778313] 'agreement among raft nodes before linearized reading' (duration: 372.484399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:33:37.374127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:33:37.001114Z","time spent":"372.996673ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T17:33:37.40438Z","caller":"traceutil/trace.go:171","msg":"trace[947205332] transaction","detail":"{read_only:false; response_revision:26359; number_of_response:1; }","duration":"386.412911ms","start":"2026-10-26T17:33:37.017941Z","end":"2026-10-26T17:33:37.404354Z","steps":["trace[947205332] 'process raft request' (duration: 386.199006ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:33:37.404572Z","caller":"traceutil/trace.go:171","msg":"trace[1655984882] transaction","detail":"{read_only:false; response_revision:26360; number_of_response:1; }","duration":"136.619102ms","start":"2026-10-26T17:33:37.26793Z","end":"2026-10-26T17:33:37.40455Z","steps":["trace[1655984882] 'process raft request' (duration: 136.377586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:33:37.405438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:33:37.017917Z","time spent":"386.537964ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:33:37.788729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.691528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:33:37.788979Z","caller":"traceutil/trace.go:171","msg":"trace[542167254] transaction","detail":"{read_only:false; response_revision:26362; number_of_response:1; }","duration":"379.364578ms","start":"2026-10-26T17:33:37.409587Z","end":"2026-10-26T17:33:37.788952Z","steps":["trace[542167254] 'process raft request' (duration: 379.223054ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:33:37.788967Z","caller":"traceutil/trace.go:171","msg":"trace[1958075641] transaction","detail":"{read_only:false; response_revision:26361; number_of_response:1; }","duration":"410.048533ms","start":"2026-10-26T17:33:37.378883Z","end":"2026-10-26T17:33:37.788932Z","steps":["trace[1958075641] 'process raft request' (duration: 114.089608ms)","trace[1958075641] 'compare' (duration: 295.593426ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:33:37.789033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:33:37.409563Z","time spent":"379.443969ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:33:37.789161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:33:37.378837Z","time spent":"410.217837ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:33:38.082384Z","caller":"traceutil/trace.go:171","msg":"trace[1032899390] transaction","detail":"{read_only:false; response_revision:26363; number_of_response:1; }","duration":"513.079874ms","start":"2026-10-26T17:33:37.56928Z","end":"2026-10-26T17:33:38.08236Z","steps":["trace[1032899390] 'process raft request' (duration: 512.876909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:33:38.082566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:33:37.569251Z","time spent":"513.219508ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:33:38.082822Z","caller":"traceutil/trace.go:171","msg":"trace[273793886] transaction","detail":"{read_only:false; response_revision:26365; number_of_response:1; }","duration":"472.4119ms","start":"2026-10-26T17:33:37.610396Z","end":"2026-10-26T17:33:38.082807Z","steps":["trace[273793886] 'process raft request' (duration: 472.205735ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:33:38.082881Z","caller":"traceutil/trace.go:171","msg":"trace[1432697196] transaction","detail":"{read_only:false; response_revision:26364; number_of_response:1; }","duration":"499.812762ms","start":"2026-10-26T17:33:37.58302Z","end":"2026-10-26T17:33:38.082833Z","steps":["trace[1432697196] 'process raft request' (duration: 499.293018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:33:38.082912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:33:37.610367Z","time spent":"472.506793ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:33:38.083039Z","caller":"traceutil/trace.go:171","msg":"trace[1958363670] linearizableReadLoop","detail":"{readStateIndex:27233; appliedIndex:27228; }","duration":"314.441365ms","start":"2026-10-26T17:33:37.768299Z","end":"2026-10-26T17:33:38.08274Z","steps":["trace[1958363670] 'read index received' (duration: 20.42926ms)","trace[1958363670] 'applied index is now lower than readState.Index' (duration: 294.011165ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:33:38.083135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.811774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-26T17:33:38.083231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.875627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-26T17:33:38.083245Z","caller":"traceutil/trace.go:171","msg":"trace[1721604271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26365; }","duration":"314.962677ms","start":"2026-10-26T17:33:37.768256Z","end":"2026-10-26T17:33:38.083219Z","steps":["trace[1721604271] 'agreement among raft nodes before linearized reading' (duration: 314.715211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:33:38.083247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.990652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:33:38.083277Z","caller":"traceutil/trace.go:171","msg":"trace[592822310] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26365; }","duration":"230.967689ms","start":"2026-10-26T17:33:37.852297Z","end":"2026-10-26T17:33:38.083265Z","steps":["trace[592822310] 'agreement among raft nodes before linearized reading' (duration: 230.870976ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:33:38.083302Z","caller":"traceutil/trace.go:171","msg":"trace[1389639556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26365; }","duration":"105.086954ms","start":"2026-10-26T17:33:37.978199Z","end":"2026-10-26T17:33:38.083286Z","steps":["trace[1389639556] 'agreement among raft nodes before linearized reading' (duration: 104.994072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:33:38.083298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:33:37.768236Z","time spent":"315.051509ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T17:33:38.083226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:33:37.582993Z","time spent":"500.080479ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:33:38.379887Z","caller":"traceutil/trace.go:171","msg":"trace[638098582] transaction","detail":"{read_only:false; response_revision:26367; number_of_response:1; }","duration":"282.310241ms","start":"2026-10-26T17:33:38.097557Z","end":"2026-10-26T17:33:38.379867Z","steps":["trace[638098582] 'process raft request' (duration: 282.243719ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:33:38.379924Z","caller":"traceutil/trace.go:171","msg":"trace[508588305] transaction","detail":"{read_only:false; response_revision:26366; number_of_response:1; }","duration":"290.013921ms","start":"2026-10-26T17:33:38.089871Z","end":"2026-10-26T17:33:38.379885Z","steps":["trace[508588305] 'process raft request' (duration: 197.724758ms)","trace[508588305] 'compare' (duration: 91.865992ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:33:43.12233Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a88.wal"} {"level":"info","ts":"2026-10-26T17:34:37.623457Z","caller":"traceutil/trace.go:171","msg":"trace[499697233] transaction","detail":"{read_only:false; response_revision:26900; number_of_response:1; }","duration":"185.596133ms","start":"2026-10-26T17:34:37.437823Z","end":"2026-10-26T17:34:37.623419Z","steps":["trace[499697233] 'process raft request' (duration: 184.736761ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:34:37.677816Z","caller":"traceutil/trace.go:171","msg":"trace[80376309] linearizableReadLoop","detail":"{readStateIndex:27782; appliedIndex:27780; }","duration":"197.044369ms","start":"2026-10-26T17:34:37.480736Z","end":"2026-10-26T17:34:37.67778Z","steps":["trace[80376309] 'read index received' (duration: 142.161996ms)","trace[80376309] 'applied index is now lower than readState.Index' (duration: 54.881253ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:34:37.677957Z","caller":"traceutil/trace.go:171","msg":"trace[312209084] transaction","detail":"{read_only:false; response_revision:26901; number_of_response:1; }","duration":"203.826485ms","start":"2026-10-26T17:34:37.47407Z","end":"2026-10-26T17:34:37.677896Z","steps":["trace[312209084] 'process raft request' (duration: 203.491527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:37.678261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.4859ms","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:9"} {"level":"info","ts":"2026-10-26T17:34:37.678313Z","caller":"traceutil/trace.go:171","msg":"trace[916822659] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:26901; }","duration":"197.614954ms","start":"2026-10-26T17:34:37.480681Z","end":"2026-10-26T17:34:37.678296Z","steps":["trace[916822659] 'agreement among raft nodes before linearized reading' (duration: 197.253625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:43.241686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.132371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:34:43.241782Z","caller":"traceutil/trace.go:171","msg":"trace[667779822] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:26937; }","duration":"384.288685ms","start":"2026-10-26T17:34:42.857471Z","end":"2026-10-26T17:34:43.24176Z","steps":["trace[667779822] 'count revisions from in-memory index tree' (duration: 384.053988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:43.241822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:42.857452Z","time spent":"384.358907ms","remote":"127.0.0.1:58814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"info","ts":"2026-10-26T17:34:43.24185Z","caller":"traceutil/trace.go:171","msg":"trace[1428360789] transaction","detail":"{read_only:false; response_revision:26938; number_of_response:1; }","duration":"278.149713ms","start":"2026-10-26T17:34:42.963661Z","end":"2026-10-26T17:34:43.241811Z","steps":["trace[1428360789] 'process raft request' (duration: 215.617191ms)","trace[1428360789] 'compare' (duration: 62.191202ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:34:43.748595Z","caller":"traceutil/trace.go:171","msg":"trace[1767709326] transaction","detail":"{read_only:false; response_revision:26939; number_of_response:1; }","duration":"763.199199ms","start":"2026-10-26T17:34:42.985347Z","end":"2026-10-26T17:34:43.748546Z","steps":["trace[1767709326] 'process raft request' (duration: 762.882221ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:34:43.748711Z","caller":"traceutil/trace.go:171","msg":"trace[1923976007] transaction","detail":"{read_only:false; response_revision:26940; number_of_response:1; }","duration":"663.989728ms","start":"2026-10-26T17:34:43.084687Z","end":"2026-10-26T17:34:43.748677Z","steps":["trace[1923976007] 'process raft request' (duration: 663.72736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:43.748771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:42.98532Z","time spent":"763.361114ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:34:43.748909Z","caller":"traceutil/trace.go:171","msg":"trace[1300258045] linearizableReadLoop","detail":"{readStateIndex:27821; appliedIndex:27819; }","duration":"763.348184ms","start":"2026-10-26T17:34:42.985538Z","end":"2026-10-26T17:34:43.748886Z","steps":["trace[1300258045] 'read index received' (duration: 193.771045ms)","trace[1300258045] 'applied index is now lower than readState.Index' (duration: 569.575709ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:34:43.748927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:43.084674Z","time spent":"664.109991ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:34:43.749Z","caller":"traceutil/trace.go:171","msg":"trace[168503276] transaction","detail":"{read_only:false; response_revision:26941; number_of_response:1; }","duration":"616.987948ms","start":"2026-10-26T17:34:43.132002Z","end":"2026-10-26T17:34:43.748989Z","steps":["trace[168503276] 'process raft request' (duration: 616.605089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:43.749067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:43.13195Z","time spent":"617.07084ms","remote":"127.0.0.1:57120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419267,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:34:43.749156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.6136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:34:43.749194Z","caller":"traceutil/trace.go:171","msg":"trace[266331759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26941; }","duration":"763.675892ms","start":"2026-10-26T17:34:42.985507Z","end":"2026-10-26T17:34:43.749183Z","steps":["trace[266331759] 'agreement among raft nodes before linearized reading' (duration: 763.60293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:43.749224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:42.985499Z","time spent":"763.719553ms","remote":"127.0.0.1:50574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T17:34:44.506653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.936132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:34:44.506925Z","caller":"traceutil/trace.go:171","msg":"trace[388918006] linearizableReadLoop","detail":"{readStateIndex:27825; appliedIndex:27823; }","duration":"757.951584ms","start":"2026-10-26T17:34:43.748957Z","end":"2026-10-26T17:34:44.506908Z","steps":["trace[388918006] 'read index received' (duration: 490.661162ms)","trace[388918006] 'applied index is now lower than readState.Index' (duration: 267.289412ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:34:44.506922Z","caller":"traceutil/trace.go:171","msg":"trace[987030960] transaction","detail":"{read_only:false; response_revision:26943; number_of_response:1; }","duration":"1.321464515s","start":"2026-10-26T17:34:43.18542Z","end":"2026-10-26T17:34:44.506884Z","steps":["trace[987030960] 'process raft request' (duration: 1.321333392s)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:34:44.507051Z","caller":"traceutil/trace.go:171","msg":"trace[145986086] transaction","detail":"{read_only:false; response_revision:26944; number_of_response:1; }","duration":"459.534995ms","start":"2026-10-26T17:34:44.047506Z","end":"2026-10-26T17:34:44.507041Z","steps":["trace[145986086] 'process raft request' (duration: 459.344391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:44.507102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073392313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T17:34:44.507112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:43.185407Z","time spent":"1.321593458s","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:34:44.507141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:44.04748Z","time spent":"459.604647ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:34:44.507178Z","caller":"traceutil/trace.go:171","msg":"trace[835410968] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:26944; }","duration":"1.073521246s","start":"2026-10-26T17:34:43.433639Z","end":"2026-10-26T17:34:44.50716Z","steps":["trace[835410968] 'agreement among raft nodes before linearized reading' (duration: 1.07327425s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:44.507213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:43.433616Z","time spent":"1.073587057s","remote":"127.0.0.1:45916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-10-26T17:34:44.50721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358828754s","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-26T17:34:44.50722Z","caller":"traceutil/trace.go:171","msg":"trace[542939167] transaction","detail":"{read_only:false; response_revision:26945; number_of_response:1; }","duration":"251.526802ms","start":"2026-10-26T17:34:44.255662Z","end":"2026-10-26T17:34:44.507189Z","steps":["trace[542939167] 'process raft request' (duration: 251.336717ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:34:44.507291Z","caller":"traceutil/trace.go:171","msg":"trace[1862720206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26944; }","duration":"1.358956017s","start":"2026-10-26T17:34:43.148313Z","end":"2026-10-26T17:34:44.507269Z","steps":["trace[1862720206] 'agreement among raft nodes before linearized reading' (duration: 1.358641329s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:44.50742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.362275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-26T17:34:44.507458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:43.148292Z","time spent":"1.35909729s","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T17:34:44.5075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129759434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-26T17:34:44.507623Z","caller":"traceutil/trace.go:171","msg":"trace[333586129] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:26945; }","duration":"1.129895668s","start":"2026-10-26T17:34:43.377683Z","end":"2026-10-26T17:34:44.507578Z","steps":["trace[333586129] 'agreement among raft nodes before linearized reading' (duration: 1.129253522s)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:34:44.506902Z","caller":"traceutil/trace.go:171","msg":"trace[333459548] transaction","detail":"{read_only:false; response_revision:26942; number_of_response:1; }","duration":"1.323534729s","start":"2026-10-26T17:34:43.183309Z","end":"2026-10-26T17:34:44.506844Z","steps":["trace[333459548] 'process raft request' (duration: 1.05632083s)","trace[333459548] 'compare' (duration: 265.530316ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:34:44.507711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:43.377658Z","time spent":"1.130040943s","remote":"127.0.0.1:50730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"info","ts":"2026-10-26T17:34:44.507511Z","caller":"traceutil/trace.go:171","msg":"trace[2127147922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26945; }","duration":"780.505718ms","start":"2026-10-26T17:34:43.726983Z","end":"2026-10-26T17:34:44.507488Z","steps":["trace[2127147922] 'agreement among raft nodes before linearized reading' (duration: 779.932124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:44.507815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:43.183284Z","time spent":"1.324454783s","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:34:44.507936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:43.726966Z","time spent":"780.94702ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-26T17:34:44.521967Z","caller":"traceutil/trace.go:171","msg":"trace[344843074] transaction","detail":"{read_only:false; response_revision:26946; number_of_response:1; }","duration":"198.854467ms","start":"2026-10-26T17:34:44.323081Z","end":"2026-10-26T17:34:44.521935Z","steps":["trace[344843074] 'process raft request' (duration: 198.156338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:44.523541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.222367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:34:44.523608Z","caller":"traceutil/trace.go:171","msg":"trace[1265447557] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:26947; }","duration":"172.336619ms","start":"2026-10-26T17:34:44.351251Z","end":"2026-10-26T17:34:44.523588Z","steps":["trace[1265447557] 'agreement among raft nodes before linearized reading' (duration: 172.183435ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:34:44.523789Z","caller":"traceutil/trace.go:171","msg":"trace[1804730538] transaction","detail":"{read_only:false; response_revision:26947; number_of_response:1; }","duration":"195.408088ms","start":"2026-10-26T17:34:44.32827Z","end":"2026-10-26T17:34:44.523678Z","steps":["trace[1804730538] 'process raft request' (duration: 193.617001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:44.52402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.527717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T17:34:44.524105Z","caller":"traceutil/trace.go:171","msg":"trace[1747574259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26947; }","duration":"412.64218ms","start":"2026-10-26T17:34:44.11144Z","end":"2026-10-26T17:34:44.524082Z","steps":["trace[1747574259] 'agreement among raft nodes before linearized reading' (duration: 412.26814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:44.524161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:44.111364Z","time spent":"412.783243ms","remote":"127.0.0.1:50824","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-26T17:34:44.524038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.816881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-26T17:34:44.524461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.740758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18543"} {"level":"info","ts":"2026-10-26T17:34:44.52451Z","caller":"traceutil/trace.go:171","msg":"trace[303802966] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26947; }","duration":"391.81663ms","start":"2026-10-26T17:34:44.132678Z","end":"2026-10-26T17:34:44.524495Z","steps":["trace[303802966] 'agreement among raft nodes before linearized reading' (duration: 391.697916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:44.524502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.085102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T17:34:44.524561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.139787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:34:44.524612Z","caller":"traceutil/trace.go:171","msg":"trace[1348040870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26947; }","duration":"773.22861ms","start":"2026-10-26T17:34:43.751371Z","end":"2026-10-26T17:34:44.524599Z","steps":["trace[1348040870] 'agreement among raft nodes before linearized reading' (duration: 773.142897ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:34:44.524615Z","caller":"traceutil/trace.go:171","msg":"trace[51217360] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:26947; }","duration":"676.230735ms","start":"2026-10-26T17:34:43.848365Z","end":"2026-10-26T17:34:44.524596Z","steps":["trace[51217360] 'agreement among raft nodes before linearized reading' (duration: 676.035211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:44.524643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:43.751351Z","time spent":"773.285541ms","remote":"127.0.0.1:50586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T17:34:44.524543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:44.132664Z","time spent":"391.871041ms","remote":"127.0.0.1:50744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18566,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-26T17:34:44.524691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:34:43.848349Z","time spent":"676.328678ms","remote":"127.0.0.1:46136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"info","ts":"2026-10-26T17:34:44.524878Z","caller":"traceutil/trace.go:171","msg":"trace[934448066] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:26947; }","duration":"124.311134ms","start":"2026-10-26T17:34:44.400166Z","end":"2026-10-26T17:34:44.524477Z","steps":["trace[934448066] 'agreement among raft nodes before linearized reading' (duration: 123.79916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:34:44.524398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.664346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:34:44.525051Z","caller":"traceutil/trace.go:171","msg":"trace[236724407] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:26947; }","duration":"188.368254ms","start":"2026-10-26T17:34:44.336669Z","end":"2026-10-26T17:34:44.525037Z","steps":["trace[236724407] 'agreement among raft nodes before linearized reading' (duration: 187.606215ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:35:21.525663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24481} {"level":"info","ts":"2026-10-26T17:35:21.683329Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24481,"took":"154.690181ms","hash":3993126584,"current-db-size-bytes":57667584,"current-db-size":"58 MB","current-db-size-in-use-bytes":36823040,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-26T17:35:21.683403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3993126584,"revision":24481,"compact-revision":21584} {"level":"warn","ts":"2026-10-26T17:36:29.422578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.159012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:36:29.423093Z","caller":"traceutil/trace.go:171","msg":"trace[1085486032] transaction","detail":"{read_only:false; response_revision:27981; number_of_response:1; }","duration":"485.454168ms","start":"2026-10-26T17:36:28.937624Z","end":"2026-10-26T17:36:29.423078Z","steps":["trace[1085486032] 'process raft request' (duration: 485.371146ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:29.423075Z","caller":"traceutil/trace.go:171","msg":"trace[1148751341] transaction","detail":"{read_only:false; response_revision:27980; number_of_response:1; }","duration":"487.203913ms","start":"2026-10-26T17:36:28.935841Z","end":"2026-10-26T17:36:29.423045Z","steps":["trace[1148751341] 'process raft request' (duration: 486.836784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.42316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:28.937612Z","time spent":"485.520359ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:36:29.423069Z","caller":"traceutil/trace.go:171","msg":"trace[659317184] transaction","detail":"{read_only:false; response_revision:27979; number_of_response:1; }","duration":"488.650481ms","start":"2026-10-26T17:36:28.934366Z","end":"2026-10-26T17:36:29.423016Z","steps":["trace[659317184] 'process raft request' (duration: 123.981605ms)","trace[659317184] 'compare' (duration: 363.966828ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:36:29.423241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:28.935803Z","time spent":"487.348117ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:36:29.423272Z","caller":"traceutil/trace.go:171","msg":"trace[613776642] transaction","detail":"{read_only:false; response_revision:27982; number_of_response:1; }","duration":"390.840565ms","start":"2026-10-26T17:36:29.032396Z","end":"2026-10-26T17:36:29.423236Z","steps":["trace[613776642] 'process raft request' (duration: 390.64562ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.423372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:28.93435Z","time spent":"488.913257ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:36:29.423427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.032357Z","time spent":"390.989258ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:36:29.510136Z","caller":"traceutil/trace.go:171","msg":"trace[747205394] linearizableReadLoop","detail":"{readStateIndex:28890; appliedIndex:28885; }","duration":"385.701551ms","start":"2026-10-26T17:36:29.124409Z","end":"2026-10-26T17:36:29.510111Z","steps":["trace[747205394] 'read index received' (duration: 48.862857ms)","trace[747205394] 'applied index is now lower than readState.Index' (duration: 336.837304ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:36:29.51033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.912816ms","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-26T17:36:29.5104Z","caller":"traceutil/trace.go:171","msg":"trace[892272143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27983; }","duration":"386.027289ms","start":"2026-10-26T17:36:29.12436Z","end":"2026-10-26T17:36:29.510387Z","steps":["trace[892272143] 'agreement among raft nodes before linearized reading' (duration: 385.860355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.510433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.124342Z","time spent":"386.083661ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T17:36:29.510423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.135468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-26T17:36:29.510498Z","caller":"traceutil/trace.go:171","msg":"trace[542036078] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27983; }","duration":"372.264743ms","start":"2026-10-26T17:36:29.138214Z","end":"2026-10-26T17:36:29.510479Z","steps":["trace[542036078] 'agreement among raft nodes before linearized reading' (duration: 372.090178ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.510533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.13818Z","time spent":"372.344754ms","remote":"127.0.0.1:50824","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-26T17:36:29.510546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.362999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:36:29.510585Z","caller":"traceutil/trace.go:171","msg":"trace[1809020931] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27983; }","duration":"251.42574ms","start":"2026-10-26T17:36:29.25915Z","end":"2026-10-26T17:36:29.510576Z","steps":["trace[1809020931] 'agreement among raft nodes before linearized reading' (duration: 251.363028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.510609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.477371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:36:29.510303Z","caller":"traceutil/trace.go:171","msg":"trace[986195592] transaction","detail":"{read_only:false; response_revision:27983; number_of_response:1; }","duration":"439.068634ms","start":"2026-10-26T17:36:29.071165Z","end":"2026-10-26T17:36:29.510234Z","steps":["trace[986195592] 'process raft request' (duration: 438.816568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.510696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.441587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-26T17:36:29.510696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.624002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-26T17:36:29.510723Z","caller":"traceutil/trace.go:171","msg":"trace[1632098554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27983; }","duration":"355.495198ms","start":"2026-10-26T17:36:29.155222Z","end":"2026-10-26T17:36:29.510717Z","steps":["trace[1632098554] 'agreement among raft nodes before linearized reading' (duration: 355.421676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.510743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.155209Z","time spent":"355.528459ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T17:36:29.510863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.72103ms","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-26T17:36:29.510632Z","caller":"traceutil/trace.go:171","msg":"trace[1975842634] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27983; }","duration":"251.579924ms","start":"2026-10-26T17:36:29.259046Z","end":"2026-10-26T17:36:29.510626Z","steps":["trace[1975842634] 'agreement among raft nodes before linearized reading' (duration: 251.541383ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:29.510963Z","caller":"traceutil/trace.go:171","msg":"trace[1340714035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27983; }","duration":"210.701213ms","start":"2026-10-26T17:36:29.300022Z","end":"2026-10-26T17:36:29.510723Z","steps":["trace[1340714035] 'agreement among raft nodes before linearized reading' (duration: 210.631682ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:29.510907Z","caller":"traceutil/trace.go:171","msg":"trace[2035311567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27983; }","duration":"221.769801ms","start":"2026-10-26T17:36:29.289131Z","end":"2026-10-26T17:36:29.510901Z","steps":["trace[2035311567] 'agreement among raft nodes before linearized reading' (duration: 221.621867ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.511803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.071138Z","time spent":"439.564108ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:36:29.692482Z","caller":"traceutil/trace.go:171","msg":"trace[2105451487] transaction","detail":"{read_only:false; response_revision:27984; number_of_response:1; }","duration":"177.273376ms","start":"2026-10-26T17:36:29.515178Z","end":"2026-10-26T17:36:29.692451Z","steps":["trace[2105451487] 'process raft request' (duration: 162.587056ms)","trace[2105451487] 'compare' (duration: 14.542126ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:36:29.715614Z","caller":"traceutil/trace.go:171","msg":"trace[1172888599] transaction","detail":"{read_only:false; response_revision:27985; number_of_response:1; }","duration":"200.259852ms","start":"2026-10-26T17:36:29.515317Z","end":"2026-10-26T17:36:29.715577Z","steps":["trace[1172888599] 'process raft request' (duration: 200.008236ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:29.715944Z","caller":"traceutil/trace.go:171","msg":"trace[1524651700] transaction","detail":"{read_only:false; response_revision:27986; number_of_response:1; }","duration":"200.618913ms","start":"2026-10-26T17:36:29.515302Z","end":"2026-10-26T17:36:29.715921Z","steps":["trace[1524651700] 'process raft request' (duration: 200.218952ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:29.716118Z","caller":"traceutil/trace.go:171","msg":"trace[997752903] transaction","detail":"{read_only:false; response_revision:27987; number_of_response:1; }","duration":"200.576871ms","start":"2026-10-26T17:36:29.515525Z","end":"2026-10-26T17:36:29.716102Z","steps":["trace[997752903] 'process raft request' (duration: 200.297124ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:29.716387Z","caller":"traceutil/trace.go:171","msg":"trace[173240695] transaction","detail":"{read_only:false; response_revision:27988; number_of_response:1; }","duration":"200.086098ms","start":"2026-10-26T17:36:29.516285Z","end":"2026-10-26T17:36:29.716371Z","steps":["trace[173240695] 'process raft request' (duration: 199.660117ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:38:22.402445Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T17:38:22.410428Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T17:38:22.410516Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T17:38:39.57601Z","caller":"traceutil/trace.go:171","msg":"trace[1179131835] transaction","detail":"{read_only:false; response_revision:29216; number_of_response:1; }","duration":"125.451982ms","start":"2026-10-26T17:38:39.450523Z","end":"2026-10-26T17:38:39.575975Z","steps":["trace[1179131835] 'process raft request' (duration: 125.241917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:38:39.576134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.189626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:38:39.576203Z","caller":"traceutil/trace.go:171","msg":"trace[649871626] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:29216; }","duration":"125.334989ms","start":"2026-10-26T17:38:39.450853Z","end":"2026-10-26T17:38:39.576188Z","steps":["trace[649871626] 'agreement among raft nodes before linearized reading' (duration: 125.205456ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:38:39.575954Z","caller":"traceutil/trace.go:171","msg":"trace[1239619139] linearizableReadLoop","detail":"{readStateIndex:30155; appliedIndex:30154; }","duration":"125.012542ms","start":"2026-10-26T17:38:39.450914Z","end":"2026-10-26T17:38:39.575927Z","steps":["trace[1239619139] 'read index received' (duration: 124.849978ms)","trace[1239619139] 'applied index is now lower than readState.Index' (duration: 161.474µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:38:39.623979Z","caller":"traceutil/trace.go:171","msg":"trace[925380438] transaction","detail":"{read_only:false; response_revision:29217; number_of_response:1; }","duration":"160.08324ms","start":"2026-10-26T17:38:39.463853Z","end":"2026-10-26T17:38:39.623936Z","steps":["trace[925380438] 'process raft request' (duration: 159.934227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:38:39.624027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.480078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T17:38:39.624111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.092453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1230"} {"level":"info","ts":"2026-10-26T17:38:39.624142Z","caller":"traceutil/trace.go:171","msg":"trace[10918428] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:29217; }","duration":"135.169875ms","start":"2026-10-26T17:38:39.488964Z","end":"2026-10-26T17:38:39.624134Z","steps":["trace[10918428] 'agreement among raft nodes before linearized reading' (duration: 134.938278ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:38:39.624122Z","caller":"traceutil/trace.go:171","msg":"trace[823936506] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:29217; }","duration":"124.618052ms","start":"2026-10-26T17:38:39.499475Z","end":"2026-10-26T17:38:39.624093Z","steps":["trace[823936506] 'agreement among raft nodes before linearized reading' (duration: 124.478588ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:38:40.112406Z","caller":"traceutil/trace.go:171","msg":"trace[533691004] transaction","detail":"{read_only:false; response_revision:29220; number_of_response:1; }","duration":"131.824449ms","start":"2026-10-26T17:38:39.980552Z","end":"2026-10-26T17:38:40.112377Z","steps":["trace[533691004] 'process raft request' (duration: 131.567172ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:38:40.112513Z","caller":"traceutil/trace.go:171","msg":"trace[1992283392] transaction","detail":"{read_only:false; response_revision:29221; number_of_response:1; }","duration":"102.18183ms","start":"2026-10-26T17:38:40.010308Z","end":"2026-10-26T17:38:40.11249Z","steps":["trace[1992283392] 'process raft request' (duration: 102.007225ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:40:21.533705Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27404} {"level":"info","ts":"2026-10-26T17:40:21.682011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27404,"took":"144.201059ms","hash":1693367939,"current-db-size-bytes":58089472,"current-db-size":"58 MB","current-db-size-in-use-bytes":37728256,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-26T17:40:21.682121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1693367939,"revision":27404,"compact-revision":24481} {"level":"info","ts":"2026-10-26T17:45:21.543298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30130} {"level":"info","ts":"2026-10-26T17:45:21.702949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30130,"took":"156.241613ms","hash":4268680424,"current-db-size-bytes":58089472,"current-db-size":"58 MB","current-db-size-in-use-bytes":30842880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T17:45:21.703008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4268680424,"revision":30130,"compact-revision":27404} {"level":"info","ts":"2026-10-26T17:50:21.550944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32384} {"level":"info","ts":"2026-10-26T17:50:21.719206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32384,"took":"163.731468ms","hash":106866383,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":30367744,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T17:50:21.719278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":106866383,"revision":32384,"compact-revision":30130} {"level":"info","ts":"2026-10-26T17:55:21.559198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34611} {"level":"info","ts":"2026-10-26T17:55:21.703574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34611,"took":"141.212923ms","hash":518461364,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":31322112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T17:55:21.703637Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":518461364,"revision":34611,"compact-revision":32384} {"level":"info","ts":"2026-10-26T17:59:32.907431Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T17:59:32.914303Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T17:59:32.914416Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T18:00:21.566137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36908} {"level":"info","ts":"2026-10-26T18:00:21.71108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36908,"took":"138.446969ms","hash":2745215105,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":33345536,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T18:00:21.711233Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2745215105,"revision":36908,"compact-revision":34611} {"level":"info","ts":"2026-10-26T18:05:21.574931Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39231} {"level":"info","ts":"2026-10-26T18:05:21.716611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39231,"took":"137.584995ms","hash":1082724153,"current-db-size-bytes":59555840,"current-db-size":"60 MB","current-db-size-in-use-bytes":31903744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T18:05:21.71668Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1082724153,"revision":39231,"compact-revision":36908}