{"level":"warn","ts":"2026-10-09T10:56:44.983273Z","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-09T10:56:44.983363Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.5:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.5:2380","--initial-cluster=instance=https://162.253.55.5:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.5:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.5: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-09T10:56:44.983432Z","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-09T10:56:44.983448Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-10-09T10:56:44.983469Z","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-09T10:56:44.984222Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"]} {"level":"info","ts":"2026-10-09T10:56:44.984326Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.5: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-09T10:56:45.003433Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"18.843765ms"} {"level":"info","ts":"2026-10-09T10:56:45.010945Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b512f7d8ac93be25","cluster-id":"e1953d9a3e7368e5"} {"level":"info","ts":"2026-10-09T10:56:45.011017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T10:56:45.011037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 0"} {"level":"info","ts":"2026-10-09T10:56:45.011066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b512f7d8ac93be25 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T10:56:45.011074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 1"} {"level":"info","ts":"2026-10-09T10:56:45.011123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"warn","ts":"2026-10-09T10:56:45.027314Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T10:56:45.029552Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T10:56:45.031888Z","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-09T10:56:45.034918Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b512f7d8ac93be25","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T10:56:45.035166Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b512f7d8ac93be25","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T10:56:45.035207Z","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-09T10:56:45.035342Z","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-09T10:56:45.035359Z","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-09T10:56:45.037538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"info","ts":"2026-10-09T10:56:45.037848Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","added-peer-id":"b512f7d8ac93be25","added-peer-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-10-09T10:56:45.039822Z","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-09T10:56:45.039919Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-10-09T10:56:45.039957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-10-09T10:56:45.040139Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b512f7d8ac93be25","initial-advertise-peer-urls":["https://162.253.55.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T10:56:45.0402Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T10:56:45.811978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T10:56:45.812067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T10:56:45.812091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgPreVoteResp from b512f7d8ac93be25 at term 1"} {"level":"info","ts":"2026-10-09T10:56:45.812124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became candidate at term 2"} {"level":"info","ts":"2026-10-09T10:56:45.812134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgVoteResp from b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-10-09T10:56:45.812149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became leader at term 2"} {"level":"info","ts":"2026-10-09T10:56:45.812175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b512f7d8ac93be25 elected leader b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-10-09T10:56:45.813678Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b512f7d8ac93be25","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.5:2379]}","request-path":"/0/members/b512f7d8ac93be25/attributes","cluster-id":"e1953d9a3e7368e5","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T10:56:45.813701Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T10:56:45.813736Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T10:56:45.813731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T10:56:45.814052Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T10:56:45.814143Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T10:56:45.814939Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T10:56:45.81504Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T10:56:45.815085Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T10:56:45.815972Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T10:56:45.8177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.5:2379"} {"level":"info","ts":"2026-10-09T10:56:49.107609Z","caller":"traceutil/trace.go:171","msg":"trace[978045553] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"125.698394ms","start":"2026-10-09T10:56:48.981883Z","end":"2026-10-09T10:56:49.107581Z","steps":["trace[978045553] 'process raft request' (duration: 83.839383ms)","trace[978045553] 'compare' (duration: 41.703738ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:57:35.974869Z","caller":"traceutil/trace.go:171","msg":"trace[614512056] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"118.378566ms","start":"2026-10-09T10:57:35.856448Z","end":"2026-10-09T10:57:35.974826Z","steps":["trace[614512056] 'process raft request' (duration: 113.845721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:57:36.385446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.003389ms","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-09T10:57:36.385576Z","caller":"traceutil/trace.go:171","msg":"trace[672918011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:644; }","duration":"101.206113ms","start":"2026-10-09T10:57:36.284345Z","end":"2026-10-09T10:57:36.385551Z","steps":["trace[672918011] 'range keys from in-memory index tree' (duration: 100.853785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:57:36.3854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.565532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T10:57:36.385687Z","caller":"traceutil/trace.go:171","msg":"trace[154906787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"108.913658ms","start":"2026-10-09T10:57:36.276754Z","end":"2026-10-09T10:57:36.385667Z","steps":["trace[154906787] 'range keys from in-memory index tree' (duration: 108.45447ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:57:36.865018Z","caller":"traceutil/trace.go:171","msg":"trace[322649567] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"318.167651ms","start":"2026-10-09T10:57:36.546826Z","end":"2026-10-09T10:57:36.864994Z","steps":["trace[322649567] 'read index received' (duration: 316.372357ms)","trace[322649567] 'applied index is now lower than readState.Index' (duration: 1.794234ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:57:36.865174Z","caller":"traceutil/trace.go:171","msg":"trace[6751690] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"344.775585ms","start":"2026-10-09T10:57:36.520364Z","end":"2026-10-09T10:57:36.86514Z","steps":["trace[6751690] 'process raft request' (duration: 343.034932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:57:36.865284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.440937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xgvr4\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-10-09T10:57:36.865345Z","caller":"traceutil/trace.go:171","msg":"trace[262749907] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xgvr4; range_end:; response_count:1; response_revision:646; }","duration":"318.582049ms","start":"2026-10-09T10:57:36.546747Z","end":"2026-10-09T10:57:36.865329Z","steps":["trace[262749907] 'agreement among raft nodes before linearized reading' (duration: 318.386476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:57:36.865395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:57:36.546714Z","time spent":"318.668731ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16981,"request content":"key:\"/registry/pods/kube-system/cilium-xgvr4\" "} {"level":"warn","ts":"2026-10-09T10:57:36.865436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.658909ms","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-09T10:57:36.865537Z","caller":"traceutil/trace.go:171","msg":"trace[2066079649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"265.792722ms","start":"2026-10-09T10:57:36.599688Z","end":"2026-10-09T10:57:36.865481Z","steps":["trace[2066079649] 'agreement among raft nodes before linearized reading' (duration: 265.496707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:57:36.86665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:57:36.520334Z","time spent":"344.922307ms","remote":"127.0.0.1:56190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:57:52.950747Z","caller":"traceutil/trace.go:171","msg":"trace[1939777900] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"291.355036ms","start":"2026-10-09T10:57:52.659369Z","end":"2026-10-09T10:57:52.950724Z","steps":["trace[1939777900] 'process raft request' (duration: 264.366642ms)","trace[1939777900] 'compare' (duration: 26.898683ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:57:52.967882Z","caller":"traceutil/trace.go:171","msg":"trace[515666842] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"132.619668ms","start":"2026-10-09T10:57:52.835235Z","end":"2026-10-09T10:57:52.967855Z","steps":["trace[515666842] 'process raft request' (duration: 132.342113ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:58:37.279432Z","caller":"traceutil/trace.go:171","msg":"trace[1744043116] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"100.65434ms","start":"2026-10-09T10:58:37.178751Z","end":"2026-10-09T10:58:37.279405Z","steps":["trace[1744043116] 'process raft request' (duration: 100.515867ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:08.277095Z","caller":"traceutil/trace.go:171","msg":"trace[672153342] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"152.537489ms","start":"2026-10-09T10:59:08.124503Z","end":"2026-10-09T10:59:08.27704Z","steps":["trace[672153342] 'read index received' (duration: 54.790373ms)","trace[672153342] 'applied index is now lower than readState.Index' (duration: 97.745416ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:59:08.277193Z","caller":"traceutil/trace.go:171","msg":"trace[1468116369] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"251.294894ms","start":"2026-10-09T10:59:08.025824Z","end":"2026-10-09T10:59:08.277119Z","steps":["trace[1468116369] 'process raft request' (duration: 153.432896ms)","trace[1468116369] 'compare' (duration: 97.531722ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:59:08.277567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.053759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-09T10:59:08.277626Z","caller":"traceutil/trace.go:171","msg":"trace[1968509675] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1881; }","duration":"153.16568ms","start":"2026-10-09T10:59:08.124442Z","end":"2026-10-09T10:59:08.277608Z","steps":["trace[1968509675] 'agreement among raft nodes before linearized reading' (duration: 152.986748ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:08.364435Z","caller":"traceutil/trace.go:171","msg":"trace[905326221] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"189.587217ms","start":"2026-10-09T10:59:08.174777Z","end":"2026-10-09T10:59:08.364364Z","steps":["trace[905326221] 'process raft request' (duration: 189.459325ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:08.455239Z","caller":"traceutil/trace.go:171","msg":"trace[636984210] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"222.661838ms","start":"2026-10-09T10:59:08.232546Z","end":"2026-10-09T10:59:08.455207Z","steps":["trace[636984210] 'process raft request' (duration: 222.424704ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:08.455571Z","caller":"traceutil/trace.go:171","msg":"trace[1138122831] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"172.19721ms","start":"2026-10-09T10:59:08.28335Z","end":"2026-10-09T10:59:08.455547Z","steps":["trace[1138122831] 'process raft request' (duration: 171.775442ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:08.455652Z","caller":"traceutil/trace.go:171","msg":"trace[694399646] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"172.055178ms","start":"2026-10-09T10:59:08.28357Z","end":"2026-10-09T10:59:08.455625Z","steps":["trace[694399646] 'process raft request' (duration: 171.853534ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:08.455679Z","caller":"traceutil/trace.go:171","msg":"trace[328775858] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1957; }","duration":"178.465121ms","start":"2026-10-09T10:59:08.277192Z","end":"2026-10-09T10:59:08.455657Z","steps":["trace[328775858] 'read index received' (duration: 87.004918ms)","trace[328775858] 'applied index is now lower than readState.Index' (duration: 91.458503ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:59:08.455799Z","caller":"traceutil/trace.go:171","msg":"trace[1080242194] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"172.132449ms","start":"2026-10-09T10:59:08.283652Z","end":"2026-10-09T10:59:08.455785Z","steps":["trace[1080242194] 'process raft request' (duration: 171.889874ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:08.455939Z","caller":"traceutil/trace.go:171","msg":"trace[1038984331] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"171.998367ms","start":"2026-10-09T10:59:08.283926Z","end":"2026-10-09T10:59:08.455925Z","steps":["trace[1038984331] 'process raft request' (duration: 171.66814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:59:08.45596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.127272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1dcee006-0abe-4356-9521-0a60a1d82ade\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-10-09T10:59:08.456014Z","caller":"traceutil/trace.go:171","msg":"trace[1866982874] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1dcee006-0abe-4356-9521-0a60a1d82ade; range_end:; response_count:1; response_revision:1887; }","duration":"282.222704ms","start":"2026-10-09T10:59:08.173772Z","end":"2026-10-09T10:59:08.455995Z","steps":["trace[1866982874] 'agreement among raft nodes before linearized reading' (duration: 282.04509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:59:08.456001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.970224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1dcee006-0abe-4356-9521-0a60a1d82ade\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-10-09T10:59:08.456084Z","caller":"traceutil/trace.go:171","msg":"trace[764301865] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1dcee006-0abe-4356-9521-0a60a1d82ade; range_end:; response_count:1; response_revision:1887; }","duration":"174.101977ms","start":"2026-10-09T10:59:08.281962Z","end":"2026-10-09T10:59:08.456064Z","steps":["trace[764301865] 'agreement among raft nodes before linearized reading' (duration: 173.884973ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:08.814345Z","caller":"traceutil/trace.go:171","msg":"trace[1532557788] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1965; }","duration":"111.501992ms","start":"2026-10-09T10:59:08.702814Z","end":"2026-10-09T10:59:08.814316Z","steps":["trace[1532557788] 'read index received' (duration: 94.446511ms)","trace[1532557788] 'applied index is now lower than readState.Index' (duration: 17.054041ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:59:08.814422Z","caller":"traceutil/trace.go:171","msg":"trace[1840809128] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"119.412156ms","start":"2026-10-09T10:59:08.694975Z","end":"2026-10-09T10:59:08.814387Z","steps":["trace[1840809128] 'process raft request' (duration: 102.346906ms)","trace[1840809128] 'compare' (duration: 16.860296ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:59:08.814619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.777818ms","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-09T10:59:08.814726Z","caller":"traceutil/trace.go:171","msg":"trace[1241379385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1890; }","duration":"111.948461ms","start":"2026-10-09T10:59:08.702759Z","end":"2026-10-09T10:59:08.814707Z","steps":["trace[1241379385] 'agreement among raft nodes before linearized reading' (duration: 111.658655ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:20.622769Z","caller":"traceutil/trace.go:171","msg":"trace[1318403611] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"108.915222ms","start":"2026-10-09T10:59:20.513828Z","end":"2026-10-09T10:59:20.622743Z","steps":["trace[1318403611] 'process raft request' (duration: 108.231679ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:52.904713Z","caller":"traceutil/trace.go:171","msg":"trace[721760370] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"108.031668ms","start":"2026-10-09T10:59:52.796659Z","end":"2026-10-09T10:59:52.904691Z","steps":["trace[721760370] 'process raft request' (duration: 107.902836ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:58.55803Z","caller":"traceutil/trace.go:171","msg":"trace[816639688] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"132.986734ms","start":"2026-10-09T10:59:58.425011Z","end":"2026-10-09T10:59:58.557998Z","steps":["trace[816639688] 'process raft request' (duration: 132.77813ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:59:58.818049Z","caller":"traceutil/trace.go:171","msg":"trace[32762112] linearizableReadLoop","detail":"{readStateIndex:2298; appliedIndex:2297; }","duration":"213.634768ms","start":"2026-10-09T10:59:58.604386Z","end":"2026-10-09T10:59:58.81802Z","steps":["trace[32762112] 'read index received' (duration: 130.029026ms)","trace[32762112] 'applied index is now lower than readState.Index' (duration: 83.604202ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:59:58.818074Z","caller":"traceutil/trace.go:171","msg":"trace[737125015] transaction","detail":"{read_only:false; response_revision:2210; number_of_response:1; }","duration":"213.72417ms","start":"2026-10-09T10:59:58.604314Z","end":"2026-10-09T10:59:58.818038Z","steps":["trace[737125015] 'process raft request' (duration: 130.18003ms)","trace[737125015] 'compare' (duration: 83.425578ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:59:58.818437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.968554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T10:59:58.818555Z","caller":"traceutil/trace.go:171","msg":"trace[1880422835] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2210; }","duration":"214.167839ms","start":"2026-10-09T10:59:58.604346Z","end":"2026-10-09T10:59:58.818514Z","steps":["trace[1880422835] 'agreement among raft nodes before linearized reading' (duration: 213.769101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:59:58.838894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.513108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-09T10:59:58.838979Z","caller":"traceutil/trace.go:171","msg":"trace[1621494596] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2211; }","duration":"211.624809ms","start":"2026-10-09T10:59:58.627324Z","end":"2026-10-09T10:59:58.838949Z","steps":["trace[1621494596] 'agreement among raft nodes before linearized reading' (duration: 211.344734ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:01:37.331446Z","caller":"traceutil/trace.go:171","msg":"trace[1600026132] transaction","detail":"{read_only:false; response_revision:2933; number_of_response:1; }","duration":"103.106621ms","start":"2026-10-09T11:01:37.228311Z","end":"2026-10-09T11:01:37.331417Z","steps":["trace[1600026132] 'process raft request' (duration: 102.937758ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:01:37.522725Z","caller":"traceutil/trace.go:171","msg":"trace[2003161048] linearizableReadLoop","detail":"{readStateIndex:3049; appliedIndex:3047; }","duration":"245.251016ms","start":"2026-10-09T11:01:37.277447Z","end":"2026-10-09T11:01:37.522698Z","steps":["trace[2003161048] 'read index received' (duration: 53.8075ms)","trace[2003161048] 'applied index is now lower than readState.Index' (duration: 191.442206ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:01:37.522833Z","caller":"traceutil/trace.go:171","msg":"trace[1288269264] transaction","detail":"{read_only:false; response_revision:2934; number_of_response:1; }","duration":"287.937568ms","start":"2026-10-09T11:01:37.234881Z","end":"2026-10-09T11:01:37.522819Z","steps":["trace[1288269264] 'process raft request' (duration: 225.189204ms)","trace[1288269264] 'compare' (duration: 62.506779ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:01:37.522973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.509751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:01:37.523012Z","caller":"traceutil/trace.go:171","msg":"trace[1316954568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2934; }","duration":"245.594233ms","start":"2026-10-09T11:01:37.27741Z","end":"2026-10-09T11:01:37.523004Z","steps":["trace[1316954568] 'agreement among raft nodes before linearized reading' (duration: 245.519071ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:01:37.528701Z","caller":"traceutil/trace.go:171","msg":"trace[732575795] transaction","detail":"{read_only:false; response_revision:2935; number_of_response:1; }","duration":"101.676804ms","start":"2026-10-09T11:01:37.42699Z","end":"2026-10-09T11:01:37.528667Z","steps":["trace[732575795] 'process raft request' (duration: 101.45435ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:04:39.319279Z","caller":"traceutil/trace.go:171","msg":"trace[1194650937] linearizableReadLoop","detail":"{readStateIndex:4255; appliedIndex:4254; }","duration":"124.811395ms","start":"2026-10-09T11:04:39.194434Z","end":"2026-10-09T11:04:39.319245Z","steps":["trace[1194650937] 'read index received' (duration: 124.652152ms)","trace[1194650937] 'applied index is now lower than readState.Index' (duration: 158.563µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:04:39.319315Z","caller":"traceutil/trace.go:171","msg":"trace[1632497545] transaction","detail":"{read_only:false; response_revision:4097; number_of_response:1; }","duration":"127.19007ms","start":"2026-10-09T11:04:39.192104Z","end":"2026-10-09T11:04:39.319294Z","steps":["trace[1632497545] 'process raft request' (duration: 127.023977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:04:39.319478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.02995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-09T11:04:39.319557Z","caller":"traceutil/trace.go:171","msg":"trace[682729207] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4097; }","duration":"125.137732ms","start":"2026-10-09T11:04:39.194404Z","end":"2026-10-09T11:04:39.319541Z","steps":["trace[682729207] 'agreement among raft nodes before linearized reading' (duration: 124.945028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:04:44.829493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.909461ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T11:04:44.829755Z","caller":"traceutil/trace.go:171","msg":"trace[668458414] transaction","detail":"{read_only:false; response_revision:4126; number_of_response:1; }","duration":"139.970152ms","start":"2026-10-09T11:04:44.689766Z","end":"2026-10-09T11:04:44.829736Z","steps":["trace[668458414] 'process raft request' (duration: 139.85371ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:06:15.953078Z","caller":"traceutil/trace.go:171","msg":"trace[1097161831] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"125.183858ms","start":"2026-10-09T11:06:15.827867Z","end":"2026-10-09T11:06:15.953051Z","steps":["trace[1097161831] 'process raft request' (duration: 125.028245ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:06:16.069375Z","caller":"traceutil/trace.go:171","msg":"trace[1569860202] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"213.639537ms","start":"2026-10-09T11:06:15.855711Z","end":"2026-10-09T11:06:16.06935Z","steps":["trace[1569860202] 'process raft request' (duration: 193.048007ms)","trace[1569860202] 'compare' (duration: 20.248805ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:06:16.28735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.82402ms","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-09T11:06:16.287435Z","caller":"traceutil/trace.go:171","msg":"trace[756339078] 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:5315; }","duration":"116.953483ms","start":"2026-10-09T11:06:16.170461Z","end":"2026-10-09T11:06:16.287414Z","steps":["trace[756339078] 'range keys from in-memory index tree' (duration: 116.647076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:06:18.423221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.470135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:06:18.423335Z","caller":"traceutil/trace.go:171","msg":"trace[2082201530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5358; }","duration":"143.599138ms","start":"2026-10-09T11:06:18.279718Z","end":"2026-10-09T11:06:18.423317Z","steps":["trace[2082201530] 'range keys from in-memory index tree' (duration: 143.306063ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:06:45.841338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2984} {"level":"info","ts":"2026-10-09T11:06:45.970827Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2984,"took":"128.046323ms","hash":1650515031,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":32350208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T11:06:45.970903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1650515031,"revision":2984,"compact-revision":-1} {"level":"info","ts":"2026-10-09T11:11:45.849121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5872} {"level":"info","ts":"2026-10-09T11:11:45.942561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5872,"took":"91.525756ms","hash":3718233284,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":26931200,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-09T11:11:45.942622Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3718233284,"revision":5872,"compact-revision":2984} {"level":"warn","ts":"2026-10-09T11:13:05.471958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.85967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:13:05.47205Z","caller":"traceutil/trace.go:171","msg":"trace[1298889106] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8691; }","duration":"271.002803ms","start":"2026-10-09T11:13:05.201028Z","end":"2026-10-09T11:13:05.472031Z","steps":["trace[1298889106] 'range keys from in-memory index tree' (duration: 270.722658ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:13:05.472177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.52127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T11:13:05.472264Z","caller":"traceutil/trace.go:171","msg":"trace[1910635045] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8691; }","duration":"273.628222ms","start":"2026-10-09T11:13:05.198615Z","end":"2026-10-09T11:13:05.472243Z","steps":["trace[1910635045] 'count revisions from in-memory index tree' (duration: 273.430718ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:13:13.848072Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002357.wal"} {"level":"info","ts":"2026-10-09T11:13:16.99427Z","caller":"traceutil/trace.go:171","msg":"trace[982049769] linearizableReadLoop","detail":"{readStateIndex:9063; appliedIndex:9062; }","duration":"146.662737ms","start":"2026-10-09T11:13:16.84758Z","end":"2026-10-09T11:13:16.994243Z","steps":["trace[982049769] 'read index received' (duration: 146.494174ms)","trace[982049769] 'applied index is now lower than readState.Index' (duration: 167.243µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:13:16.994434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.82423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-09T11:13:16.994476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.918619ms","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-09T11:13:16.994497Z","caller":"traceutil/trace.go:171","msg":"trace[34790690] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8750; }","duration":"146.905841ms","start":"2026-10-09T11:13:16.847574Z","end":"2026-10-09T11:13:16.99448Z","steps":["trace[34790690] 'agreement among raft nodes before linearized reading' (duration: 146.785969ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:13:16.994533Z","caller":"traceutil/trace.go:171","msg":"trace[838625091] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8750; }","duration":"132.006741ms","start":"2026-10-09T11:13:16.862511Z","end":"2026-10-09T11:13:16.994517Z","steps":["trace[838625091] 'agreement among raft nodes before linearized reading' (duration: 131.909859ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:13:16.994399Z","caller":"traceutil/trace.go:171","msg":"trace[2071379229] transaction","detail":"{read_only:false; response_revision:8750; number_of_response:1; }","duration":"242.666218ms","start":"2026-10-09T11:13:16.75169Z","end":"2026-10-09T11:13:16.994357Z","steps":["trace[2071379229] 'process raft request' (duration: 242.330212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:13:50.803512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.413119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T11:13:50.803629Z","caller":"traceutil/trace.go:171","msg":"trace[125936899] transaction","detail":"{read_only:false; response_revision:8984; number_of_response:1; }","duration":"396.03233ms","start":"2026-10-09T11:13:50.407575Z","end":"2026-10-09T11:13:50.803608Z","steps":["trace[125936899] 'process raft request' (duration: 135.439938ms)","trace[125936899] 'compare' (duration: 260.286627ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:13:50.8037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:13:50.407559Z","time spent":"396.104111ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:15:00.631272Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b512f7d8ac93be25","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T11:15:00.64478Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-09T11:15:00.644919Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-09T11:15:26.104265Z","caller":"traceutil/trace.go:171","msg":"trace[204537522] transaction","detail":"{read_only:false; response_revision:9877; number_of_response:1; }","duration":"100.34116ms","start":"2026-10-09T11:15:26.0039Z","end":"2026-10-09T11:15:26.104241Z","steps":["trace[204537522] 'process raft request' (duration: 100.182467ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:16:45.856095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8038} {"level":"info","ts":"2026-10-09T11:16:45.929264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8038,"took":"71.218051ms","hash":2867748289,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":28360704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T11:16:45.929349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2867748289,"revision":8038,"compact-revision":5872} {"level":"warn","ts":"2026-10-09T11:21:06.947574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.642163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:21:06.947703Z","caller":"traceutil/trace.go:171","msg":"trace[1304167439] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:12323; }","duration":"183.812357ms","start":"2026-10-09T11:21:06.763848Z","end":"2026-10-09T11:21:06.94766Z","steps":["trace[1304167439] 'count revisions from in-memory index tree' (duration: 183.567672ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:21:06.956023Z","caller":"traceutil/trace.go:171","msg":"trace[334161921] transaction","detail":"{read_only:false; response_revision:12324; number_of_response:1; }","duration":"116.270634ms","start":"2026-10-09T11:21:06.839712Z","end":"2026-10-09T11:21:06.955982Z","steps":["trace[334161921] 'process raft request' (duration: 116.007379ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:21:07.927249Z","caller":"traceutil/trace.go:171","msg":"trace[635386152] linearizableReadLoop","detail":"{readStateIndex:12757; appliedIndex:12756; }","duration":"117.064378ms","start":"2026-10-09T11:21:07.810162Z","end":"2026-10-09T11:21:07.927226Z","steps":["trace[635386152] 'read index received' (duration: 116.984207ms)","trace[635386152] 'applied index is now lower than readState.Index' (duration: 79.131µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:21:07.927357Z","caller":"traceutil/trace.go:171","msg":"trace[897937951] transaction","detail":"{read_only:false; response_revision:12326; number_of_response:1; }","duration":"130.872468ms","start":"2026-10-09T11:21:07.796457Z","end":"2026-10-09T11:21:07.927329Z","steps":["trace[897937951] 'process raft request' (duration: 130.626354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:21:07.927456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.269762ms","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-09T11:21:07.927497Z","caller":"traceutil/trace.go:171","msg":"trace[625528261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12326; }","duration":"117.353683ms","start":"2026-10-09T11:21:07.810131Z","end":"2026-10-09T11:21:07.927485Z","steps":["trace[625528261] 'agreement among raft nodes before linearized reading' (duration: 117.20589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:21:07.987156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.074023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T11:21:07.987141Z","caller":"traceutil/trace.go:171","msg":"trace[741219416] transaction","detail":"{read_only:false; response_revision:12327; number_of_response:1; }","duration":"147.476802ms","start":"2026-10-09T11:21:07.839566Z","end":"2026-10-09T11:21:07.987043Z","steps":["trace[741219416] 'process raft request' (duration: 112.578813ms)","trace[741219416] 'compare' (duration: 34.631674ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:21:07.987216Z","caller":"traceutil/trace.go:171","msg":"trace[1609683557] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:12328; }","duration":"148.171126ms","start":"2026-10-09T11:21:07.839031Z","end":"2026-10-09T11:21:07.987202Z","steps":["trace[1609683557] 'agreement among raft nodes before linearized reading' (duration: 148.044473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:21:07.987259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.06004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-09T11:21:07.987353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.689769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-10-09T11:21:07.987411Z","caller":"traceutil/trace.go:171","msg":"trace[2024117844] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12328; }","duration":"127.772651ms","start":"2026-10-09T11:21:07.859621Z","end":"2026-10-09T11:21:07.987394Z","steps":["trace[2024117844] 'agreement among raft nodes before linearized reading' (duration: 127.638008ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:21:07.987382Z","caller":"traceutil/trace.go:171","msg":"trace[874038964] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12328; }","duration":"132.187643ms","start":"2026-10-09T11:21:07.855171Z","end":"2026-10-09T11:21:07.987359Z","steps":["trace[874038964] 'agreement among raft nodes before linearized reading' (duration: 132.008829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:21:07.987444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.398586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:21:07.987518Z","caller":"traceutil/trace.go:171","msg":"trace[1225582652] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12328; }","duration":"132.505809ms","start":"2026-10-09T11:21:07.855004Z","end":"2026-10-09T11:21:07.987509Z","steps":["trace[1225582652] 'agreement among raft nodes before linearized reading' (duration: 132.377507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:21:07.98759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.403527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-09T11:21:07.987624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.352496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:21:07.987706Z","caller":"traceutil/trace.go:171","msg":"trace[78510387] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12328; }","duration":"132.537699ms","start":"2026-10-09T11:21:07.855129Z","end":"2026-10-09T11:21:07.987667Z","steps":["trace[78510387] 'agreement among raft nodes before linearized reading' (duration: 132.337045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:21:07.987603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.943997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-09T11:21:07.987713Z","caller":"traceutil/trace.go:171","msg":"trace[637773492] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12328; }","duration":"132.501938ms","start":"2026-10-09T11:21:07.855172Z","end":"2026-10-09T11:21:07.987674Z","steps":["trace[637773492] 'agreement among raft nodes before linearized reading' (duration: 132.318434ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:21:07.987773Z","caller":"traceutil/trace.go:171","msg":"trace[1226907387] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12328; }","duration":"133.09738ms","start":"2026-10-09T11:21:07.854647Z","end":"2026-10-09T11:21:07.987745Z","steps":["trace[1226907387] 'agreement among raft nodes before linearized reading' (duration: 132.805265ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:21:45.863397Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10407} {"level":"info","ts":"2026-10-09T11:21:45.949393Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10407,"took":"83.561286ms","hash":3953846071,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":26554368,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-09T11:21:45.949463Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3953846071,"revision":10407,"compact-revision":8038} {"level":"info","ts":"2026-10-09T11:26:45.870334Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12600} {"level":"info","ts":"2026-10-09T11:26:45.953272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12600,"took":"80.104891ms","hash":3195853713,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":27865088,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T11:26:45.953339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3195853713,"revision":12600,"compact-revision":10407} {"level":"info","ts":"2026-10-09T11:27:15.473833Z","caller":"traceutil/trace.go:171","msg":"trace[356972584] transaction","detail":"{read_only:false; response_revision:14812; number_of_response:1; }","duration":"117.68098ms","start":"2026-10-09T11:27:15.356121Z","end":"2026-10-09T11:27:15.473802Z","steps":["trace[356972584] 'process raft request' (duration: 117.481996ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:27:35.777934Z","caller":"traceutil/trace.go:171","msg":"trace[1493183671] transaction","detail":"{read_only:false; response_revision:14922; number_of_response:1; }","duration":"174.813458ms","start":"2026-10-09T11:27:35.603084Z","end":"2026-10-09T11:27:35.777897Z","steps":["trace[1493183671] 'process raft request' (duration: 136.18195ms)","trace[1493183671] 'compare' (duration: 38.499656ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:27:35.844872Z","caller":"traceutil/trace.go:171","msg":"trace[968618274] transaction","detail":"{read_only:false; response_revision:14923; number_of_response:1; }","duration":"198.756859ms","start":"2026-10-09T11:27:35.646077Z","end":"2026-10-09T11:27:35.844834Z","steps":["trace[968618274] 'process raft request' (duration: 198.581765ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:27:52.799904Z","caller":"traceutil/trace.go:171","msg":"trace[2048675283] linearizableReadLoop","detail":"{readStateIndex:15554; appliedIndex:15553; }","duration":"110.428883ms","start":"2026-10-09T11:27:52.689454Z","end":"2026-10-09T11:27:52.799883Z","steps":["trace[2048675283] 'read index received' (duration: 110.277931ms)","trace[2048675283] 'applied index is now lower than readState.Index' (duration: 149.812µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:27:52.799974Z","caller":"traceutil/trace.go:171","msg":"trace[1394985297] transaction","detail":"{read_only:false; response_revision:15027; number_of_response:1; }","duration":"298.503351ms","start":"2026-10-09T11:27:52.50144Z","end":"2026-10-09T11:27:52.799943Z","steps":["trace[1394985297] 'process raft request' (duration: 298.292567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:27:52.800356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.884752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:16 size:151643"} {"level":"info","ts":"2026-10-09T11:27:52.800491Z","caller":"traceutil/trace.go:171","msg":"trace[1940365383] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:16; response_revision:15027; }","duration":"111.068915ms","start":"2026-10-09T11:27:52.689403Z","end":"2026-10-09T11:27:52.800471Z","steps":["trace[1940365383] 'agreement among raft nodes before linearized reading' (duration: 110.597517ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:28:16.200779Z","caller":"traceutil/trace.go:171","msg":"trace[960311057] transaction","detail":"{read_only:false; response_revision:15342; number_of_response:1; }","duration":"102.516505ms","start":"2026-10-09T11:28:16.098222Z","end":"2026-10-09T11:28:16.200739Z","steps":["trace[960311057] 'process raft request' (duration: 96.308517ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:28:16.526422Z","caller":"traceutil/trace.go:171","msg":"trace[2016787701] linearizableReadLoop","detail":"{readStateIndex:15884; appliedIndex:15883; }","duration":"247.52662ms","start":"2026-10-09T11:28:16.278869Z","end":"2026-10-09T11:28:16.526396Z","steps":["trace[2016787701] 'read index received' (duration: 247.265505ms)","trace[2016787701] 'applied index is now lower than readState.Index' (duration: 260.115µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:28:16.526504Z","caller":"traceutil/trace.go:171","msg":"trace[831753834] transaction","detail":"{read_only:false; response_revision:15344; number_of_response:1; }","duration":"263.822705ms","start":"2026-10-09T11:28:16.262644Z","end":"2026-10-09T11:28:16.526466Z","steps":["trace[831753834] 'process raft request' (duration: 263.50504ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:28:16.526598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.710772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T11:28:16.526654Z","caller":"traceutil/trace.go:171","msg":"trace[1843352479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15344; }","duration":"247.803883ms","start":"2026-10-09T11:28:16.278835Z","end":"2026-10-09T11:28:16.526639Z","steps":["trace[1843352479] 'agreement among raft nodes before linearized reading' (duration: 247.703791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:28:16.526793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.634258ms","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-09T11:28:16.526867Z","caller":"traceutil/trace.go:171","msg":"trace[300913507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15344; }","duration":"197.739719ms","start":"2026-10-09T11:28:16.329108Z","end":"2026-10-09T11:28:16.526848Z","steps":["trace[300913507] 'agreement among raft nodes before linearized reading' (duration: 197.509585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:28:16.526913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.922558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p658n\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-10-09T11:28:16.527Z","caller":"traceutil/trace.go:171","msg":"trace[1880647245] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p658n; range_end:; response_count:1; response_revision:15344; }","duration":"168.03123ms","start":"2026-10-09T11:28:16.35895Z","end":"2026-10-09T11:28:16.526981Z","steps":["trace[1880647245] 'agreement among raft nodes before linearized reading' (duration: 167.753215ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:28:16.5271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.589428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T11:28:16.527168Z","caller":"traceutil/trace.go:171","msg":"trace[40642662] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:15344; }","duration":"100.71322ms","start":"2026-10-09T11:28:16.426436Z","end":"2026-10-09T11:28:16.527149Z","steps":["trace[40642662] 'agreement among raft nodes before linearized reading' (duration: 100.538007ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:29:22.643309Z","caller":"traceutil/trace.go:171","msg":"trace[862440730] transaction","detail":"{read_only:false; response_revision:16053; number_of_response:1; }","duration":"100.410429ms","start":"2026-10-09T11:29:22.542856Z","end":"2026-10-09T11:29:22.643267Z","steps":["trace[862440730] 'process raft request' (duration: 100.095184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:29:43.478097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.360177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737130"} {"level":"info","ts":"2026-10-09T11:29:43.478195Z","caller":"traceutil/trace.go:171","msg":"trace[1361216879] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16158; }","duration":"110.469029ms","start":"2026-10-09T11:29:43.367707Z","end":"2026-10-09T11:29:43.478176Z","steps":["trace[1361216879] 'range keys from in-memory index tree' (duration: 109.634233ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:31:45.877352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14605} {"level":"info","ts":"2026-10-09T11:31:45.959981Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14605,"took":"79.82467ms","hash":4091164676,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":31137792,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T11:31:45.960047Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4091164676,"revision":14605,"compact-revision":12600} {"level":"info","ts":"2026-10-09T11:35:59.670104Z","caller":"traceutil/trace.go:171","msg":"trace[1851734168] linearizableReadLoop","detail":"{readStateIndex:19532; appliedIndex:19531; }","duration":"164.333892ms","start":"2026-10-09T11:35:59.505748Z","end":"2026-10-09T11:35:59.670082Z","steps":["trace[1851734168] 'read index received' (duration: 164.289651ms)","trace[1851734168] 'applied index is now lower than readState.Index' (duration: 43.071µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:35:59.67032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.551976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T11:35:59.670356Z","caller":"traceutil/trace.go:171","msg":"trace[627376026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18879; }","duration":"164.629378ms","start":"2026-10-09T11:35:59.505717Z","end":"2026-10-09T11:35:59.670346Z","steps":["trace[627376026] 'agreement among raft nodes before linearized reading' (duration: 164.494056ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:35:59.719402Z","caller":"traceutil/trace.go:171","msg":"trace[431883410] transaction","detail":"{read_only:false; response_revision:18880; number_of_response:1; }","duration":"210.75019ms","start":"2026-10-09T11:35:59.508628Z","end":"2026-10-09T11:35:59.719378Z","steps":["trace[431883410] 'process raft request' (duration: 210.399753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:35:59.71981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.608702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-8z4rj\" ","response":"range_response_count:1 size:20739"} {"level":"info","ts":"2026-10-09T11:35:59.719895Z","caller":"traceutil/trace.go:171","msg":"trace[260934413] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-8z4rj; range_end:; response_count:1; response_revision:18881; }","duration":"112.744084ms","start":"2026-10-09T11:35:59.607129Z","end":"2026-10-09T11:35:59.719873Z","steps":["trace[260934413] 'agreement among raft nodes before linearized reading' (duration: 112.473699ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:36:00.102568Z","caller":"traceutil/trace.go:171","msg":"trace[1697806367] transaction","detail":"{read_only:false; response_revision:18886; number_of_response:1; }","duration":"361.431813ms","start":"2026-10-09T11:35:59.741117Z","end":"2026-10-09T11:36:00.102549Z","steps":["trace[1697806367] 'process raft request' (duration: 361.310031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:36:00.102727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:35:59.741087Z","time spent":"361.587526ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20856,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:36:39.77507Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b512f7d8ac93be25","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T11:36:39.783637Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-09T11:36:39.783761Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-09T11:36:45.885959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16921} {"level":"info","ts":"2026-10-09T11:36:45.995322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16921,"took":"104.487987ms","hash":3461175476,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":33083392,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T11:36:45.995403Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3461175476,"revision":16921,"compact-revision":14605} {"level":"info","ts":"2026-10-09T11:41:45.892886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19457} {"level":"info","ts":"2026-10-09T11:41:46.021004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19457,"took":"124.912075ms","hash":4084936386,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":30269440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T11:41:46.021067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4084936386,"revision":19457,"compact-revision":16921} {"level":"info","ts":"2026-10-09T11:42:23.171609Z","caller":"traceutil/trace.go:171","msg":"trace[1974203663] transaction","detail":"{read_only:false; response_revision:21764; number_of_response:1; }","duration":"151.272544ms","start":"2026-10-09T11:42:23.020305Z","end":"2026-10-09T11:42:23.171577Z","steps":["trace[1974203663] 'process raft request' (duration: 151.077541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:42:23.356708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.307985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:42:23.356839Z","caller":"traceutil/trace.go:171","msg":"trace[1311649156] linearizableReadLoop","detail":"{readStateIndex:22516; appliedIndex:22514; }","duration":"332.800621ms","start":"2026-10-09T11:42:23.024019Z","end":"2026-10-09T11:42:23.356819Z","steps":["trace[1311649156] 'read index received' (duration: 147.456882ms)","trace[1311649156] 'applied index is now lower than readState.Index' (duration: 185.342129ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:42:23.35692Z","caller":"traceutil/trace.go:171","msg":"trace[850186085] transaction","detail":"{read_only:false; response_revision:21765; number_of_response:1; }","duration":"333.299731ms","start":"2026-10-09T11:42:23.023606Z","end":"2026-10-09T11:42:23.356905Z","steps":["trace[850186085] 'process raft request' (duration: 163.68274ms)","trace[850186085] 'compare' (duration: 169.01857ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:42:23.35698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:42:23.023579Z","time spent":"333.369083ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:42:23.357266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.216449ms","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-09T11:42:23.357348Z","caller":"traceutil/trace.go:171","msg":"trace[622437566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21765; }","duration":"333.342172ms","start":"2026-10-09T11:42:23.023984Z","end":"2026-10-09T11:42:23.357327Z","steps":["trace[622437566] 'agreement among raft nodes before linearized reading' (duration: 333.077057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:42:23.357388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:42:23.023976Z","time spent":"333.402883ms","remote":"127.0.0.1:56360","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":"info","ts":"2026-10-09T11:42:23.536447Z","caller":"traceutil/trace.go:171","msg":"trace[620184876] linearizableReadLoop","detail":"{readStateIndex:22520; appliedIndex:22519; }","duration":"136.79558ms","start":"2026-10-09T11:42:23.399623Z","end":"2026-10-09T11:42:23.536419Z","steps":["trace[620184876] 'read index received' (duration: 136.523885ms)","trace[620184876] 'applied index is now lower than readState.Index' (duration: 270.935µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:42:23.536596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.939103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:42:23.536542Z","caller":"traceutil/trace.go:171","msg":"trace[716519021] transaction","detail":"{read_only:false; response_revision:21769; number_of_response:1; }","duration":"157.164076ms","start":"2026-10-09T11:42:23.379341Z","end":"2026-10-09T11:42:23.536505Z","steps":["trace[716519021] 'process raft request' (duration: 156.887581ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:42:23.536637Z","caller":"traceutil/trace.go:171","msg":"trace[826536998] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:21769; }","duration":"137.050825ms","start":"2026-10-09T11:42:23.399574Z","end":"2026-10-09T11:42:23.536625Z","steps":["trace[826536998] 'agreement among raft nodes before linearized reading' (duration: 136.948273ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:46:34.128553Z","caller":"traceutil/trace.go:171","msg":"trace[309103275] transaction","detail":"{read_only:false; response_revision:24327; number_of_response:1; }","duration":"238.515856ms","start":"2026-10-09T11:46:33.890008Z","end":"2026-10-09T11:46:34.128524Z","steps":["trace[309103275] 'process raft request' (duration: 238.289462ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:46:45.900433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21573} {"level":"info","ts":"2026-10-09T11:46:46.018778Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21573,"took":"114.311145ms","hash":3040293946,"current-db-size-bytes":57020416,"current-db-size":"57 MB","current-db-size-in-use-bytes":42131456,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-09T11:46:46.018849Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3040293946,"revision":21573,"compact-revision":19457} {"level":"info","ts":"2026-10-09T11:48:40.636012Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000675b.wal"} {"level":"info","ts":"2026-10-09T11:50:03.331427Z","caller":"traceutil/trace.go:171","msg":"trace[1689791611] transaction","detail":"{read_only:false; response_revision:26396; number_of_response:1; }","duration":"156.217398ms","start":"2026-10-09T11:50:03.175192Z","end":"2026-10-09T11:50:03.331409Z","steps":["trace[1689791611] 'process raft request' (duration: 156.117066ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:03.331567Z","caller":"traceutil/trace.go:171","msg":"trace[603433229] linearizableReadLoop","detail":"{readStateIndex:27278; appliedIndex:27278; }","duration":"110.008184ms","start":"2026-10-09T11:50:03.221544Z","end":"2026-10-09T11:50:03.331552Z","steps":["trace[603433229] 'read index received' (duration: 110.002104ms)","trace[603433229] 'applied index is now lower than readState.Index' (duration: 4.98µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:03.331728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.168827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-8589c75df8-gw5jr\" ","response":"range_response_count:1 size:8501"} {"level":"info","ts":"2026-10-09T11:50:03.331759Z","caller":"traceutil/trace.go:171","msg":"trace[704128071] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-8589c75df8-gw5jr; range_end:; response_count:1; response_revision:26396; }","duration":"110.238808ms","start":"2026-10-09T11:50:03.22151Z","end":"2026-10-09T11:50:03.331749Z","steps":["trace[704128071] 'agreement among raft nodes before linearized reading' (duration: 110.111135ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:03.351226Z","caller":"traceutil/trace.go:171","msg":"trace[1748039996] transaction","detail":"{read_only:false; response_revision:26397; number_of_response:1; }","duration":"128.406121ms","start":"2026-10-09T11:50:03.222795Z","end":"2026-10-09T11:50:03.351201Z","steps":["trace[1748039996] 'process raft request' (duration: 128.090915ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:03.351415Z","caller":"traceutil/trace.go:171","msg":"trace[1907225043] transaction","detail":"{read_only:false; response_revision:26398; number_of_response:1; }","duration":"123.803624ms","start":"2026-10-09T11:50:03.227579Z","end":"2026-10-09T11:50:03.351382Z","steps":["trace[1907225043] 'process raft request' (duration: 123.459168ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:08.267478Z","caller":"traceutil/trace.go:171","msg":"trace[83222727] transaction","detail":"{read_only:false; response_revision:26462; number_of_response:1; }","duration":"346.333867ms","start":"2026-10-09T11:50:07.921112Z","end":"2026-10-09T11:50:08.267446Z","steps":["trace[83222727] 'process raft request' (duration: 346.164594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.267643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:07.921087Z","time spent":"346.47481ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:08.575806Z","caller":"traceutil/trace.go:171","msg":"trace[673556877] linearizableReadLoop","detail":"{readStateIndex:27348; appliedIndex:27346; }","duration":"621.5605ms","start":"2026-10-09T11:50:07.954216Z","end":"2026-10-09T11:50:08.575776Z","steps":["trace[673556877] 'read index received' (duration: 313.037898ms)","trace[673556877] 'applied index is now lower than readState.Index' (duration: 308.521342ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:08.575844Z","caller":"traceutil/trace.go:171","msg":"trace[553806379] transaction","detail":"{read_only:false; response_revision:26463; number_of_response:1; }","duration":"645.838319ms","start":"2026-10-09T11:50:07.929954Z","end":"2026-10-09T11:50:08.575792Z","steps":["trace[553806379] 'process raft request' (duration: 645.569484ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:08.575966Z","caller":"traceutil/trace.go:171","msg":"trace[2127924633] transaction","detail":"{read_only:false; response_revision:26464; number_of_response:1; }","duration":"377.893435ms","start":"2026-10-09T11:50:08.198056Z","end":"2026-10-09T11:50:08.57595Z","steps":["trace[2127924633] 'process raft request' (duration: 377.654161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.576088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.198029Z","time spent":"377.974597ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:08.576113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:07.929939Z","time spent":"645.995542ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:08.576117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.896516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T11:50:08.576196Z","caller":"traceutil/trace.go:171","msg":"trace[1305480390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26464; }","duration":"622.012188ms","start":"2026-10-09T11:50:07.954147Z","end":"2026-10-09T11:50:08.57616Z","steps":["trace[1305480390] 'agreement among raft nodes before linearized reading' (duration: 621.865375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.576223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:07.954127Z","time spent":"622.090809ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T11:50:08.593262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.427436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:08.593356Z","caller":"traceutil/trace.go:171","msg":"trace[2047302022] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:26465; }","duration":"518.566129ms","start":"2026-10-09T11:50:08.074766Z","end":"2026-10-09T11:50:08.593332Z","steps":["trace[2047302022] 'agreement among raft nodes before linearized reading' (duration: 518.352905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.593329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.35927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:08.593404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.07475Z","time spent":"518.642771ms","remote":"127.0.0.1:52044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:08.593353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.696018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T11:50:08.593435Z","caller":"traceutil/trace.go:171","msg":"trace[952217050] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:26465; }","duration":"299.515432ms","start":"2026-10-09T11:50:08.293892Z","end":"2026-10-09T11:50:08.593407Z","steps":["trace[952217050] 'agreement among raft nodes before linearized reading' (duration: 299.334688ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:08.593476Z","caller":"traceutil/trace.go:171","msg":"trace[336352516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26465; }","duration":"146.857041ms","start":"2026-10-09T11:50:08.446596Z","end":"2026-10-09T11:50:08.593453Z","steps":["trace[336352516] 'agreement among raft nodes before linearized reading' (duration: 146.569955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.593597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.069395ms","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-09T11:50:08.593638Z","caller":"traceutil/trace.go:171","msg":"trace[2097380159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26465; }","duration":"446.113496ms","start":"2026-10-09T11:50:08.147513Z","end":"2026-10-09T11:50:08.593626Z","steps":["trace[2097380159] 'agreement among raft nodes before linearized reading' (duration: 445.994574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.593667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:08.147458Z","time spent":"446.204229ms","remote":"127.0.0.1:56360","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-09T11:50:08.841436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.697201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:08.841668Z","caller":"traceutil/trace.go:171","msg":"trace[805213531] transaction","detail":"{read_only:false; response_revision:26466; number_of_response:1; }","duration":"260.178626ms","start":"2026-10-09T11:50:08.581465Z","end":"2026-10-09T11:50:08.841644Z","steps":["trace[805213531] 'process raft request' (duration: 126.180759ms)","trace[805213531] 'compare' (duration: 133.291743ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:08.841714Z","caller":"traceutil/trace.go:171","msg":"trace[123012428] transaction","detail":"{read_only:false; response_revision:26468; number_of_response:1; }","duration":"243.123513ms","start":"2026-10-09T11:50:08.598573Z","end":"2026-10-09T11:50:08.841696Z","steps":["trace[123012428] 'process raft request' (duration: 243.054472ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:08.841819Z","caller":"traceutil/trace.go:171","msg":"trace[9712272] linearizableReadLoop","detail":"{readStateIndex:27354; appliedIndex:27350; }","duration":"167.508671ms","start":"2026-10-09T11:50:08.674303Z","end":"2026-10-09T11:50:08.841811Z","steps":["trace[9712272] 'read index received' (duration: 33.366051ms)","trace[9712272] 'applied index is now lower than readState.Index' (duration: 134.14139ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:08.841825Z","caller":"traceutil/trace.go:171","msg":"trace[146051620] transaction","detail":"{read_only:false; response_revision:26467; number_of_response:1; }","duration":"243.665474ms","start":"2026-10-09T11:50:08.59815Z","end":"2026-10-09T11:50:08.841816Z","steps":["trace[146051620] 'process raft request' (duration: 243.395239ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:08.841979Z","caller":"traceutil/trace.go:171","msg":"trace[40327414] transaction","detail":"{read_only:false; response_revision:26469; number_of_response:1; }","duration":"233.125794ms","start":"2026-10-09T11:50:08.608847Z","end":"2026-10-09T11:50:08.841973Z","steps":["trace[40327414] 'process raft request' (duration: 232.812358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:08.84212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.809528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:08.842145Z","caller":"traceutil/trace.go:171","msg":"trace[2096378985] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26469; }","duration":"167.876288ms","start":"2026-10-09T11:50:08.674261Z","end":"2026-10-09T11:50:08.842138Z","steps":["trace[2096378985] 'agreement among raft nodes before linearized reading' (duration: 167.782207ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:09.637239Z","caller":"traceutil/trace.go:171","msg":"trace[55351564] transaction","detail":"{read_only:false; response_revision:26470; number_of_response:1; }","duration":"284.63092ms","start":"2026-10-09T11:50:09.352588Z","end":"2026-10-09T11:50:09.637219Z","steps":["trace[55351564] 'process raft request' (duration: 284.472107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.379067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.791982ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:10.379267Z","caller":"traceutil/trace.go:171","msg":"trace[16958882] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26470; }","duration":"127.005786ms","start":"2026-10-09T11:50:10.252236Z","end":"2026-10-09T11:50:10.379241Z","steps":["trace[16958882] 'range keys from in-memory index tree' (duration: 126.77026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.379568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.52828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:10.379767Z","caller":"traceutil/trace.go:171","msg":"trace[297476191] linearizableReadLoop","detail":"{readStateIndex:27358; appliedIndex:27354; }","duration":"919.97932ms","start":"2026-10-09T11:50:09.459771Z","end":"2026-10-09T11:50:10.37975Z","steps":["trace[297476191] 'read index received' (duration: 177.274257ms)","trace[297476191] 'applied index is now lower than readState.Index' (duration: 742.704133ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:10.379832Z","caller":"traceutil/trace.go:171","msg":"trace[1950133683] transaction","detail":"{read_only:false; response_revision:26471; number_of_response:1; }","duration":"1.007873194s","start":"2026-10-09T11:50:09.371948Z","end":"2026-10-09T11:50:10.379822Z","steps":["trace[1950133683] 'process raft request' (duration: 349.020889ms)","trace[1950133683] 'compare' (duration: 657.872277ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:10.379894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:09.371922Z","time spent":"1.007940786s","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:10.380137Z","caller":"traceutil/trace.go:171","msg":"trace[1142153565] transaction","detail":"{read_only:false; response_revision:26472; number_of_response:1; }","duration":"1.003680695s","start":"2026-10-09T11:50:09.376446Z","end":"2026-10-09T11:50:10.380126Z","steps":["trace[1142153565] 'process raft request' (duration: 1.003197235s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.380205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:09.376428Z","time spent":"1.003729765s","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:10.38033Z","caller":"traceutil/trace.go:171","msg":"trace[757220214] transaction","detail":"{read_only:false; response_revision:26473; number_of_response:1; }","duration":"981.484635ms","start":"2026-10-09T11:50:09.398837Z","end":"2026-10-09T11:50:10.380322Z","steps":["trace[757220214] 'process raft request' (duration: 980.870683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.380388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:09.398823Z","time spent":"981.530245ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:10.380623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.837926ms","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-09T11:50:10.380697Z","caller":"traceutil/trace.go:171","msg":"trace[1849077037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26473; }","duration":"920.962908ms","start":"2026-10-09T11:50:09.459713Z","end":"2026-10-09T11:50:10.380676Z","steps":["trace[1849077037] 'agreement among raft nodes before linearized reading' (duration: 920.637692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.380746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:09.459691Z","time spent":"921.045211ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T11:50:10.427549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.088357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:10.427596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.622557ms","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-09T11:50:10.427633Z","caller":"traceutil/trace.go:171","msg":"trace[1812324831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26474; }","duration":"727.23902ms","start":"2026-10-09T11:50:09.700372Z","end":"2026-10-09T11:50:10.427611Z","steps":["trace[1812324831] 'agreement among raft nodes before linearized reading' (duration: 727.048896ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:10.42767Z","caller":"traceutil/trace.go:171","msg":"trace[1735146171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26474; }","duration":"154.74236ms","start":"2026-10-09T11:50:10.272909Z","end":"2026-10-09T11:50:10.427651Z","steps":["trace[1735146171] 'agreement among raft nodes before linearized reading' (duration: 154.493755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.427684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:09.700353Z","time spent":"727.322882ms","remote":"127.0.0.1:56088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T11:50:10.427794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.107724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T11:50:10.427882Z","caller":"traceutil/trace.go:171","msg":"trace[968568939] transaction","detail":"{read_only:false; response_revision:26474; number_of_response:1; }","duration":"304.970695ms","start":"2026-10-09T11:50:10.122899Z","end":"2026-10-09T11:50:10.42787Z","steps":["trace[968568939] 'process raft request' (duration: 304.327742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.427646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.042214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.5\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-09T11:50:10.427884Z","caller":"traceutil/trace.go:171","msg":"trace[992402426] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:26474; }","duration":"796.253067ms","start":"2026-10-09T11:50:09.631607Z","end":"2026-10-09T11:50:10.42786Z","steps":["trace[992402426] 'agreement among raft nodes before linearized reading' (duration: 796.083613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.427936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.328093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:10.427954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:09.631588Z","time spent":"796.349478ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":14,"response size":32,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:10.427983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:10.122875Z","time spent":"305.042616ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:10.427989Z","caller":"traceutil/trace.go:171","msg":"trace[1528437352] range","detail":"{range_begin:/registry/masterleases/162.253.55.5; range_end:; response_count:1; response_revision:26474; }","duration":"927.383391ms","start":"2026-10-09T11:50:09.500542Z","end":"2026-10-09T11:50:10.427925Z","steps":["trace[1528437352] 'agreement among raft nodes before linearized reading' (duration: 926.862231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.428163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:09.500528Z","time spent":"927.580594ms","remote":"127.0.0.1:56130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.5\" "} {"level":"info","ts":"2026-10-09T11:50:10.428249Z","caller":"traceutil/trace.go:171","msg":"trace[535063759] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26474; }","duration":"247.419125ms","start":"2026-10-09T11:50:10.180547Z","end":"2026-10-09T11:50:10.427966Z","steps":["trace[535063759] 'agreement among raft nodes before linearized reading' (duration: 247.345833ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:10.559084Z","caller":"traceutil/trace.go:171","msg":"trace[187006717] transaction","detail":"{read_only:false; response_revision:26475; number_of_response:1; }","duration":"166.423401ms","start":"2026-10-09T11:50:10.392636Z","end":"2026-10-09T11:50:10.559059Z","steps":["trace[187006717] 'process raft request' (duration: 166.249098ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:10.562617Z","caller":"traceutil/trace.go:171","msg":"trace[2088781662] linearizableReadLoop","detail":"{readStateIndex:27362; appliedIndex:27359; }","duration":"121.674664ms","start":"2026-10-09T11:50:10.440918Z","end":"2026-10-09T11:50:10.562592Z","steps":["trace[2088781662] 'read index received' (duration: 117.991654ms)","trace[2088781662] 'applied index is now lower than readState.Index' (duration: 3.68211ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:10.562741Z","caller":"traceutil/trace.go:171","msg":"trace[1147446545] transaction","detail":"{read_only:false; response_revision:26476; number_of_response:1; }","duration":"130.236536ms","start":"2026-10-09T11:50:10.432494Z","end":"2026-10-09T11:50:10.56273Z","steps":["trace[1147446545] 'process raft request' (duration: 129.997771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:10.563071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.115302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T11:50:10.563151Z","caller":"traceutil/trace.go:171","msg":"trace[782905875] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26476; }","duration":"122.243454ms","start":"2026-10-09T11:50:10.44089Z","end":"2026-10-09T11:50:10.563133Z","steps":["trace[782905875] 'agreement among raft nodes before linearized reading' (duration: 122.00729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:12.965077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.285917ms","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-09T11:50:12.965172Z","caller":"traceutil/trace.go:171","msg":"trace[1619826042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26491; }","duration":"402.421509ms","start":"2026-10-09T11:50:12.562727Z","end":"2026-10-09T11:50:12.965149Z","steps":["trace[1619826042] 'range keys from in-memory index tree' (duration: 402.097223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:12.965247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.562706Z","time spent":"402.527271ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T11:50:12.965452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.595336ms","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-09T11:50:12.965503Z","caller":"traceutil/trace.go:171","msg":"trace[229573800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26491; }","duration":"399.684988ms","start":"2026-10-09T11:50:12.565807Z","end":"2026-10-09T11:50:12.965492Z","steps":["trace[229573800] 'range keys from in-memory index tree' (duration: 399.432282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:12.965525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.565791Z","time spent":"399.728939ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T11:50:12.96615Z","caller":"traceutil/trace.go:171","msg":"trace[40452567] transaction","detail":"{read_only:false; response_revision:26492; number_of_response:1; }","duration":"375.080612ms","start":"2026-10-09T11:50:12.591035Z","end":"2026-10-09T11:50:12.966115Z","steps":["trace[40452567] 'process raft request' (duration: 353.598575ms)","trace[40452567] 'compare' (duration: 19.604281ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:12.966315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.591016Z","time spent":"375.224045ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:13.425959Z","caller":"traceutil/trace.go:171","msg":"trace[1911896681] transaction","detail":"{read_only:false; response_revision:26493; number_of_response:1; }","duration":"833.337689ms","start":"2026-10-09T11:50:12.592591Z","end":"2026-10-09T11:50:13.425929Z","steps":["trace[1911896681] 'process raft request' (duration: 812.498395ms)","trace[1911896681] 'compare' (duration: 20.650011ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:13.426137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.592573Z","time spent":"833.482942ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:13.426248Z","caller":"traceutil/trace.go:171","msg":"trace[2056898697] linearizableReadLoop","detail":"{readStateIndex:27380; appliedIndex:27377; }","duration":"754.315103ms","start":"2026-10-09T11:50:12.671902Z","end":"2026-10-09T11:50:13.426217Z","steps":["trace[2056898697] 'read index received' (duration: 272.743394ms)","trace[2056898697] 'applied index is now lower than readState.Index' (duration: 481.570319ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:13.426376Z","caller":"traceutil/trace.go:171","msg":"trace[229815192] transaction","detail":"{read_only:false; response_revision:26494; number_of_response:1; }","duration":"822.622437ms","start":"2026-10-09T11:50:12.603738Z","end":"2026-10-09T11:50:13.426361Z","steps":["trace[229815192] 'process raft request' (duration: 822.144287ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:13.426476Z","caller":"traceutil/trace.go:171","msg":"trace[1506325645] transaction","detail":"{read_only:false; response_revision:26495; number_of_response:1; }","duration":"572.631452ms","start":"2026-10-09T11:50:12.853814Z","end":"2026-10-09T11:50:13.426446Z","steps":["trace[1506325645] 'process raft request' (duration: 572.285776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.42658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.658089ms","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":"warn","ts":"2026-10-09T11:50:13.426618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.853797Z","time spent":"572.746335ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:13.426494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.603721Z","time spent":"822.683737ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:13.426659Z","caller":"traceutil/trace.go:171","msg":"trace[2057803542] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26495; }","duration":"754.769072ms","start":"2026-10-09T11:50:12.671868Z","end":"2026-10-09T11:50:13.426637Z","steps":["trace[2057803542] 'agreement among raft nodes before linearized reading' (duration: 754.615309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.426721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.671854Z","time spent":"754.852463ms","remote":"127.0.0.1:43814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-09T11:50:13.63779Z","caller":"traceutil/trace.go:171","msg":"trace[776175703] linearizableReadLoop","detail":"{readStateIndex:27383; appliedIndex:27381; }","duration":"211.442304ms","start":"2026-10-09T11:50:13.426322Z","end":"2026-10-09T11:50:13.637765Z","steps":["trace[776175703] 'read index received' (duration: 180.538679ms)","trace[776175703] 'applied index is now lower than readState.Index' (duration: 30.902375ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:13.637942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.525868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:13.637952Z","caller":"traceutil/trace.go:171","msg":"trace[1798049000] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"666.47377ms","start":"2026-10-09T11:50:12.971447Z","end":"2026-10-09T11:50:13.637921Z","steps":["trace[1798049000] 'process raft request' (duration: 635.411642ms)","trace[1798049000] 'compare' (duration: 30.696611ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:13.638004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.908285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T11:50:13.638033Z","caller":"traceutil/trace.go:171","msg":"trace[587178382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26497; }","duration":"359.62336ms","start":"2026-10-09T11:50:13.278383Z","end":"2026-10-09T11:50:13.638006Z","steps":["trace[587178382] 'agreement among raft nodes before linearized reading' (duration: 359.480487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.638012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.813873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-09T11:50:13.638086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.278325Z","time spent":"359.753062ms","remote":"127.0.0.1:56088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T11:50:13.638092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.971426Z","time spent":"666.594932ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:13.6381Z","caller":"traceutil/trace.go:171","msg":"trace[206601852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26497; }","duration":"772.921136ms","start":"2026-10-09T11:50:12.865157Z","end":"2026-10-09T11:50:13.638078Z","steps":["trace[206601852] 'agreement among raft nodes before linearized reading' (duration: 772.677001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.638114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.49685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419209"} {"level":"warn","ts":"2026-10-09T11:50:13.638142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.865151Z","time spent":"772.981727ms","remote":"127.0.0.1:56360","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-09T11:50:13.638217Z","caller":"traceutil/trace.go:171","msg":"trace[1657376674] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26497; }","duration":"225.605693ms","start":"2026-10-09T11:50:13.412562Z","end":"2026-10-09T11:50:13.638168Z","steps":["trace[1657376674] 'agreement among raft nodes before linearized reading' (duration: 225.226245ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:13.638043Z","caller":"traceutil/trace.go:171","msg":"trace[1325890804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26497; }","duration":"782.979656ms","start":"2026-10-09T11:50:12.855051Z","end":"2026-10-09T11:50:13.63803Z","steps":["trace[1325890804] 'agreement among raft nodes before linearized reading' (duration: 782.819443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.638306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.855038Z","time spent":"783.255511ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T11:50:13.638136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.048771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T11:50:13.638349Z","caller":"traceutil/trace.go:171","msg":"trace[2064536336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26497; }","duration":"779.296445ms","start":"2026-10-09T11:50:12.859043Z","end":"2026-10-09T11:50:13.638339Z","steps":["trace[2064536336] 'agreement among raft nodes before linearized reading' (duration: 779.019361ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:13.638395Z","caller":"traceutil/trace.go:171","msg":"trace[1325909905] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"666.731274ms","start":"2026-10-09T11:50:12.971651Z","end":"2026-10-09T11:50:13.638382Z","steps":["trace[1325909905] 'process raft request' (duration: 666.042731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:13.638394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.859021Z","time spent":"779.361927ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T11:50:13.638528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:12.971628Z","time spent":"666.804455ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:14.042969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.065504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:14.043152Z","caller":"traceutil/trace.go:171","msg":"trace[1550173322] transaction","detail":"{read_only:false; response_revision:26499; number_of_response:1; }","duration":"398.794621ms","start":"2026-10-09T11:50:13.644325Z","end":"2026-10-09T11:50:14.04312Z","steps":["trace[1550173322] 'process raft request' (duration: 297.500983ms)","trace[1550173322] 'compare' (duration: 100.714697ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:14.043208Z","caller":"traceutil/trace.go:171","msg":"trace[766916219] transaction","detail":"{read_only:false; response_revision:26501; number_of_response:1; }","duration":"394.867167ms","start":"2026-10-09T11:50:13.648298Z","end":"2026-10-09T11:50:14.043165Z","steps":["trace[766916219] 'process raft request' (duration: 394.819966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.043277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.648285Z","time spent":"394.956989ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:14.043315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.644304Z","time spent":"398.932385ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:14.043519Z","caller":"traceutil/trace.go:171","msg":"trace[1081850795] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"398.939525ms","start":"2026-10-09T11:50:13.644569Z","end":"2026-10-09T11:50:14.043509Z","steps":["trace[1081850795] 'process raft request' (duration: 398.485516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.043581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.644548Z","time spent":"399.001186ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:14.114793Z","caller":"traceutil/trace.go:171","msg":"trace[237158279] linearizableReadLoop","detail":"{readStateIndex:27388; appliedIndex:27384; }","duration":"394.51408ms","start":"2026-10-09T11:50:13.720259Z","end":"2026-10-09T11:50:14.114773Z","steps":["trace[237158279] 'read index received' (duration: 221.450853ms)","trace[237158279] 'applied index is now lower than readState.Index' (duration: 173.062347ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:14.114861Z","caller":"traceutil/trace.go:171","msg":"trace[876813014] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"421.077933ms","start":"2026-10-09T11:50:13.693755Z","end":"2026-10-09T11:50:14.114833Z","steps":["trace[876813014] 'process raft request' (duration: 420.91232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.11499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.693728Z","time spent":"421.189614ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:14.115026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.733625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T11:50:14.115113Z","caller":"traceutil/trace.go:171","msg":"trace[1142010821] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:26502; }","duration":"394.851997ms","start":"2026-10-09T11:50:13.72024Z","end":"2026-10-09T11:50:14.115092Z","steps":["trace[1142010821] 'agreement among raft nodes before linearized reading' (duration: 394.627182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.115155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.720133Z","time spent":"395.01112ms","remote":"127.0.0.1:56588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":54,"response size":32,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:14.615421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13701534601479647620,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-09T11:50:14.789978Z","caller":"traceutil/trace.go:171","msg":"trace[1238184469] transaction","detail":"{read_only:false; response_revision:26503; number_of_response:1; }","duration":"697.63736ms","start":"2026-10-09T11:50:14.092315Z","end":"2026-10-09T11:50:14.789952Z","steps":["trace[1238184469] 'process raft request' (duration: 697.309654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:14.790123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:14.092277Z","time spent":"697.772102ms","remote":"127.0.0.1:46816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419342,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:15.012046Z","caller":"traceutil/trace.go:171","msg":"trace[944123794] transaction","detail":"{read_only:false; response_revision:26504; number_of_response:1; }","duration":"620.282945ms","start":"2026-10-09T11:50:14.39172Z","end":"2026-10-09T11:50:15.012003Z","steps":["trace[944123794] 'process raft request' (duration: 619.044671ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:15.012062Z","caller":"traceutil/trace.go:171","msg":"trace[711096900] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"596.7801ms","start":"2026-10-09T11:50:14.415258Z","end":"2026-10-09T11:50:15.012039Z","steps":["trace[711096900] 'process raft request' (duration: 596.688138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:15.012211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:14.391694Z","time spent":"620.416908ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:15.012236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:14.41523Z","time spent":"596.906453ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:15.012446Z","caller":"traceutil/trace.go:171","msg":"trace[766421195] transaction","detail":"{read_only:false; response_revision:26506; number_of_response:1; }","duration":"597.164598ms","start":"2026-10-09T11:50:14.415268Z","end":"2026-10-09T11:50:15.012433Z","steps":["trace[766421195] 'process raft request' (duration: 596.728849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:15.012502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:14.415244Z","time spent":"597.227369ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:15.012598Z","caller":"traceutil/trace.go:171","msg":"trace[1707234178] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"532.867061ms","start":"2026-10-09T11:50:14.479719Z","end":"2026-10-09T11:50:15.012586Z","steps":["trace[1707234178] 'process raft request' (duration: 532.542444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:15.012655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:14.479693Z","time spent":"532.931601ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:15.024235Z","caller":"traceutil/trace.go:171","msg":"trace[1058306290] linearizableReadLoop","detail":"{readStateIndex:27394; appliedIndex:27388; }","duration":"909.378089ms","start":"2026-10-09T11:50:14.114839Z","end":"2026-10-09T11:50:15.024217Z","steps":["trace[1058306290] 'read index received' (duration: 674.849568ms)","trace[1058306290] 'applied index is now lower than readState.Index' (duration: 234.526001ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:15.024412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14973237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:15.024453Z","caller":"traceutil/trace.go:171","msg":"trace[1841572502] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:26508; }","duration":"1.149828541s","start":"2026-10-09T11:50:13.874612Z","end":"2026-10-09T11:50:15.024441Z","steps":["trace[1841572502] 'agreement among raft nodes before linearized reading' (duration: 1.149707809s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:15.02449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.874597Z","time spent":"1.149883743s","remote":"127.0.0.1:33638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:15.024689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.179152618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:15.024724Z","caller":"traceutil/trace.go:171","msg":"trace[11058430] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26508; }","duration":"1.179225678s","start":"2026-10-09T11:50:13.845489Z","end":"2026-10-09T11:50:15.024715Z","steps":["trace[11058430] 'agreement among raft nodes before linearized reading' (duration: 1.179167567s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:15.024757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.845468Z","time spent":"1.17927924s","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:15.024799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298055668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:15.024856Z","caller":"traceutil/trace.go:171","msg":"trace[1874824443] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:26508; }","duration":"1.298100979s","start":"2026-10-09T11:50:13.726733Z","end":"2026-10-09T11:50:15.024834Z","steps":["trace[1874824443] 'agreement among raft nodes before linearized reading' (duration: 1.298042838s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:15.02489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:13.726714Z","time spent":"1.298166201s","remote":"127.0.0.1:43880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-10-09T11:50:15.024735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.737272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:15.024963Z","caller":"traceutil/trace.go:171","msg":"trace[1722508345] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:26508; }","duration":"791.005937ms","start":"2026-10-09T11:50:14.233946Z","end":"2026-10-09T11:50:15.024952Z","steps":["trace[1722508345] 'agreement among raft nodes before linearized reading' (duration: 790.733602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:15.025002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:14.233929Z","time spent":"791.061479ms","remote":"127.0.0.1:43784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"info","ts":"2026-10-09T11:50:25.853362Z","caller":"traceutil/trace.go:171","msg":"trace[2125621853] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"377.807194ms","start":"2026-10-09T11:50:25.47553Z","end":"2026-10-09T11:50:25.853337Z","steps":["trace[2125621853] 'process raft request' (duration: 377.708962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:25.853498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:25.475523Z","time spent":"377.904716ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:25.85354Z","caller":"traceutil/trace.go:171","msg":"trace[1155299457] transaction","detail":"{read_only:false; response_revision:26601; number_of_response:1; }","duration":"374.624723ms","start":"2026-10-09T11:50:25.478886Z","end":"2026-10-09T11:50:25.85351Z","steps":["trace[1155299457] 'process raft request' (duration: 374.415979ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:25.853643Z","caller":"traceutil/trace.go:171","msg":"trace[816093813] transaction","detail":"{read_only:false; response_revision:26599; number_of_response:1; }","duration":"378.12895ms","start":"2026-10-09T11:50:25.475459Z","end":"2026-10-09T11:50:25.853588Z","steps":["trace[816093813] 'process raft request' (duration: 315.544125ms)","trace[816093813] 'compare' (duration: 62.112206ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:25.854108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:25.478873Z","time spent":"374.875878ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:25.854154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:25.475445Z","time spent":"378.573998ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:25.9956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.021391ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-09T11:50:25.995699Z","caller":"traceutil/trace.go:171","msg":"trace[1076350254] linearizableReadLoop","detail":"{readStateIndex:27490; appliedIndex:27486; }","duration":"305.710588ms","start":"2026-10-09T11:50:25.689969Z","end":"2026-10-09T11:50:25.99568Z","steps":["trace[1076350254] 'read index received' (duration: 101.051634ms)","trace[1076350254] 'applied index is now lower than readState.Index' (duration: 204.657564ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:25.996144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.162107ms","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-09T11:50:25.996251Z","caller":"traceutil/trace.go:171","msg":"trace[1414859734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26601; }","duration":"306.3118ms","start":"2026-10-09T11:50:25.689926Z","end":"2026-10-09T11:50:25.996238Z","steps":["trace[1414859734] 'agreement among raft nodes before linearized reading' (duration: 306.022744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:25.99622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.57848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-09T11:50:25.996227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.286128ms","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-09T11:50:25.996316Z","caller":"traceutil/trace.go:171","msg":"trace[1011696208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26601; }","duration":"273.739173ms","start":"2026-10-09T11:50:25.722553Z","end":"2026-10-09T11:50:25.996292Z","steps":["trace[1011696208] 'agreement among raft nodes before linearized reading' (duration: 273.483378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:25.996327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:25.689906Z","time spent":"306.378881ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T11:50:25.996123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.513417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:25.996533Z","caller":"traceutil/trace.go:171","msg":"trace[1146978198] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26601; }","duration":"152.930045ms","start":"2026-10-09T11:50:25.843569Z","end":"2026-10-09T11:50:25.996499Z","steps":["trace[1146978198] 'agreement among raft nodes before linearized reading' (duration: 152.446716ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:25.996328Z","caller":"traceutil/trace.go:171","msg":"trace[389778464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26601; }","duration":"298.45208ms","start":"2026-10-09T11:50:25.697858Z","end":"2026-10-09T11:50:25.99631Z","steps":["trace[389778464] 'agreement among raft nodes before linearized reading' (duration: 298.160395ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:25.996122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.031663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:25.996753Z","caller":"traceutil/trace.go:171","msg":"trace[1407801191] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:26601; }","duration":"110.710576ms","start":"2026-10-09T11:50:25.886026Z","end":"2026-10-09T11:50:25.996736Z","steps":["trace[1407801191] 'agreement among raft nodes before linearized reading' (duration: 109.995172ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:26.241704Z","caller":"traceutil/trace.go:171","msg":"trace[493852033] linearizableReadLoop","detail":"{readStateIndex:27495; appliedIndex:27494; }","duration":"124.878415ms","start":"2026-10-09T11:50:26.116801Z","end":"2026-10-09T11:50:26.24168Z","steps":["trace[493852033] 'read index received' (duration: 124.682451ms)","trace[493852033] 'applied index is now lower than readState.Index' (duration: 194.994µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:26.241817Z","caller":"traceutil/trace.go:171","msg":"trace[994134336] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"146.158747ms","start":"2026-10-09T11:50:26.095647Z","end":"2026-10-09T11:50:26.241806Z","steps":["trace[994134336] 'process raft request' (duration: 145.883402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:26.242031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.21377ms","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-09T11:50:26.24208Z","caller":"traceutil/trace.go:171","msg":"trace[52494223] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26606; }","duration":"125.295452ms","start":"2026-10-09T11:50:26.116755Z","end":"2026-10-09T11:50:26.24205Z","steps":["trace[52494223] 'agreement among raft nodes before linearized reading' (duration: 125.17065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:26.242301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.211606ms","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-09T11:50:26.242366Z","caller":"traceutil/trace.go:171","msg":"trace[1589828768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26606; }","duration":"120.303078ms","start":"2026-10-09T11:50:26.122046Z","end":"2026-10-09T11:50:26.242349Z","steps":["trace[1589828768] 'agreement among raft nodes before linearized reading' (duration: 120.107044ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:28.321043Z","caller":"traceutil/trace.go:171","msg":"trace[1208139161] transaction","detail":"{read_only:false; response_revision:26616; number_of_response:1; }","duration":"302.003561ms","start":"2026-10-09T11:50:28.01901Z","end":"2026-10-09T11:50:28.321013Z","steps":["trace[1208139161] 'process raft request' (duration: 301.849408ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:28.321222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:28.018993Z","time spent":"302.124533ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:28.748084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.092484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:28.74837Z","caller":"traceutil/trace.go:171","msg":"trace[805013911] linearizableReadLoop","detail":"{readStateIndex:27508; appliedIndex:27504; }","duration":"470.548819ms","start":"2026-10-09T11:50:28.277804Z","end":"2026-10-09T11:50:28.748353Z","steps":["trace[805013911] 'read index received' (duration: 43.154306ms)","trace[805013911] 'applied index is now lower than readState.Index' (duration: 427.393373ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:28.748508Z","caller":"traceutil/trace.go:171","msg":"trace[274863917] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"729.061698ms","start":"2026-10-09T11:50:28.019433Z","end":"2026-10-09T11:50:28.748495Z","steps":["trace[274863917] 'process raft request' (duration: 728.736841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:28.748589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:28.019406Z","time spent":"729.142409ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:28.748552Z","caller":"traceutil/trace.go:171","msg":"trace[1280150519] transaction","detail":"{read_only:false; response_revision:26617; number_of_response:1; }","duration":"729.085648ms","start":"2026-10-09T11:50:28.019414Z","end":"2026-10-09T11:50:28.7485Z","steps":["trace[1280150519] 'process raft request' (duration: 418.494124ms)","trace[1280150519] 'compare' (duration: 309.89263ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:28.748602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.782063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:28.748702Z","caller":"traceutil/trace.go:171","msg":"trace[1229499035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26619; }","duration":"470.931206ms","start":"2026-10-09T11:50:28.277742Z","end":"2026-10-09T11:50:28.748673Z","steps":["trace[1229499035] 'agreement among raft nodes before linearized reading' (duration: 470.752333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:28.748766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:28.277721Z","time spent":"471.030099ms","remote":"127.0.0.1:56088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T11:50:28.74876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:28.01938Z","time spent":"729.270682ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:28.748869Z","caller":"traceutil/trace.go:171","msg":"trace[596306211] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"501.872601ms","start":"2026-10-09T11:50:28.246971Z","end":"2026-10-09T11:50:28.748844Z","steps":["trace[596306211] 'process raft request' (duration: 501.322141ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:28.748944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.225129ms","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-09T11:50:28.748998Z","caller":"traceutil/trace.go:171","msg":"trace[143482287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26619; }","duration":"455.28036ms","start":"2026-10-09T11:50:28.293703Z","end":"2026-10-09T11:50:28.748984Z","steps":["trace[143482287] 'agreement among raft nodes before linearized reading' (duration: 455.113007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:28.748998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:28.246948Z","time spent":"501.969204ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:28.74904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:28.293659Z","time spent":"455.370532ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T11:50:28.749102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.36627ms","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-09T11:50:28.749185Z","caller":"traceutil/trace.go:171","msg":"trace[711299002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26619; }","duration":"421.496312ms","start":"2026-10-09T11:50:28.327668Z","end":"2026-10-09T11:50:28.749164Z","steps":["trace[711299002] 'agreement among raft nodes before linearized reading' (duration: 421.221937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:28.749253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:28.327652Z","time spent":"421.592134ms","remote":"127.0.0.1:56360","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-09T11:50:29.31045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.840308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:29.310685Z","caller":"traceutil/trace.go:171","msg":"trace[1199159812] linearizableReadLoop","detail":"{readStateIndex:27510; appliedIndex:27508; }","duration":"341.369566ms","start":"2026-10-09T11:50:28.969289Z","end":"2026-10-09T11:50:29.310658Z","steps":["trace[1199159812] 'read index received' (duration: 57.294394ms)","trace[1199159812] 'applied index is now lower than readState.Index' (duration: 284.074302ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:29.310898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.601291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:29.310932Z","caller":"traceutil/trace.go:171","msg":"trace[1117810162] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:26621; }","duration":"341.636621ms","start":"2026-10-09T11:50:28.969283Z","end":"2026-10-09T11:50:29.310919Z","steps":["trace[1117810162] 'agreement among raft nodes before linearized reading' (duration: 341.57948ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:29.310916Z","caller":"traceutil/trace.go:171","msg":"trace[902382807] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"556.845831ms","start":"2026-10-09T11:50:28.75402Z","end":"2026-10-09T11:50:29.310866Z","steps":["trace[902382807] 'process raft request' (duration: 556.524785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:29.310962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:28.96923Z","time spent":"341.724033ms","remote":"127.0.0.1:37644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-09T11:50:29.310939Z","caller":"traceutil/trace.go:171","msg":"trace[1456441924] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"557.30332ms","start":"2026-10-09T11:50:28.753593Z","end":"2026-10-09T11:50:29.310896Z","steps":["trace[1456441924] 'process raft request' (duration: 272.943972ms)","trace[1456441924] 'compare' (duration: 283.198606ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:29.311066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:28.753572Z","time spent":"557.425022ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:29.311069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:28.753999Z","time spent":"556.985343ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:29.811258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13701534601479647891,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-09T11:50:30.060747Z","caller":"traceutil/trace.go:171","msg":"trace[477251752] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"993.03091ms","start":"2026-10-09T11:50:29.067684Z","end":"2026-10-09T11:50:30.060715Z","steps":["trace[477251752] 'process raft request' (duration: 941.933555ms)","trace[477251752] 'compare' (duration: 50.443474ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:30.060884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.06766Z","time spent":"993.153872ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:30.061023Z","caller":"traceutil/trace.go:171","msg":"trace[1073418291] transaction","detail":"{read_only:false; response_revision:26623; number_of_response:1; }","duration":"990.203007ms","start":"2026-10-09T11:50:29.070795Z","end":"2026-10-09T11:50:30.060998Z","steps":["trace[1073418291] 'process raft request' (duration: 989.83175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.061161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.070777Z","time spent":"990.304288ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:30.061564Z","caller":"traceutil/trace.go:171","msg":"trace[386561475] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"951.569406ms","start":"2026-10-09T11:50:29.10997Z","end":"2026-10-09T11:50:30.061539Z","steps":["trace[386561475] 'process raft request' (duration: 950.942744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.061694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.109944Z","time spent":"951.679449ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:30.061718Z","caller":"traceutil/trace.go:171","msg":"trace[122458937] transaction","detail":"{read_only:false; response_revision:26625; number_of_response:1; }","duration":"950.480905ms","start":"2026-10-09T11:50:29.111219Z","end":"2026-10-09T11:50:30.0617Z","steps":["trace[122458937] 'process raft request' (duration: 950.23203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.0618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.111193Z","time spent":"950.563466ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:30.73551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.317101ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:30.73561Z","caller":"traceutil/trace.go:171","msg":"trace[1065615264] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26625; }","duration":"483.431423ms","start":"2026-10-09T11:50:30.252165Z","end":"2026-10-09T11:50:30.735596Z","steps":["trace[1065615264] 'range keys from in-memory index tree' (duration: 483.28655ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:30.736267Z","caller":"traceutil/trace.go:171","msg":"trace[155448761] transaction","detail":"{read_only:false; response_revision:26626; number_of_response:1; }","duration":"1.608744064s","start":"2026-10-09T11:50:29.127511Z","end":"2026-10-09T11:50:30.736255Z","steps":["trace[155448761] 'process raft request' (duration: 1.58578961s)","trace[155448761] 'compare' (duration: 22.173839ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:30.736331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.127496Z","time spent":"1.608793675s","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:30.736312Z","caller":"traceutil/trace.go:171","msg":"trace[1882371297] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"871.93815ms","start":"2026-10-09T11:50:29.864336Z","end":"2026-10-09T11:50:30.736274Z","steps":["trace[1882371297] 'process raft request' (duration: 871.768686ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:30.736322Z","caller":"traceutil/trace.go:171","msg":"trace[418832606] transaction","detail":"{read_only:false; response_revision:26628; number_of_response:1; }","duration":"869.637586ms","start":"2026-10-09T11:50:29.866659Z","end":"2026-10-09T11:50:30.736297Z","steps":["trace[418832606] 'process raft request' (duration: 869.526664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.736477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.866645Z","time spent":"869.739218ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:30.73649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.864309Z","time spent":"872.075352ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:30.736486Z","caller":"traceutil/trace.go:171","msg":"trace[1919641184] linearizableReadLoop","detail":"{readStateIndex:27515; appliedIndex:27510; }","duration":"1.425741484s","start":"2026-10-09T11:50:29.31071Z","end":"2026-10-09T11:50:30.736452Z","steps":["trace[1919641184] 'read index received' (duration: 698.918799ms)","trace[1919641184] 'applied index is now lower than readState.Index' (duration: 726.820605ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:30.736541Z","caller":"traceutil/trace.go:171","msg":"trace[1471944860] transaction","detail":"{read_only:false; response_revision:26629; number_of_response:1; }","duration":"869.80103ms","start":"2026-10-09T11:50:29.866732Z","end":"2026-10-09T11:50:30.736533Z","steps":["trace[1471944860] 'process raft request' (duration: 869.510424ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.736579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.866725Z","time spent":"869.83249ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:30.736621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.726694515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:30.736689Z","caller":"traceutil/trace.go:171","msg":"trace[1235782629] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:26629; }","duration":"1.726787837s","start":"2026-10-09T11:50:29.009879Z","end":"2026-10-09T11:50:30.736666Z","steps":["trace[1235782629] 'agreement among raft nodes before linearized reading' (duration: 1.726691575s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.736726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.009862Z","time spent":"1.726853548s","remote":"127.0.0.1:37676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-10-09T11:50:30.748595Z","caller":"traceutil/trace.go:171","msg":"trace[416659876] transaction","detail":"{read_only:false; response_revision:26631; number_of_response:1; }","duration":"252.617808ms","start":"2026-10-09T11:50:30.495948Z","end":"2026-10-09T11:50:30.748565Z","steps":["trace[416659876] 'process raft request' (duration: 252.542216ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:30.748622Z","caller":"traceutil/trace.go:171","msg":"trace[28293924] transaction","detail":"{read_only:false; response_revision:26630; number_of_response:1; }","duration":"559.050963ms","start":"2026-10-09T11:50:30.189548Z","end":"2026-10-09T11:50:30.748599Z","steps":["trace[28293924] 'process raft request' (duration: 558.733317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.748734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:30.189483Z","time spent":"559.206626ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:30.749108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.247919891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.5\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-09T11:50:30.749227Z","caller":"traceutil/trace.go:171","msg":"trace[538406177] range","detail":"{range_begin:/registry/masterleases/162.253.55.5; range_end:; response_count:1; response_revision:26631; }","duration":"1.248081894s","start":"2026-10-09T11:50:29.501102Z","end":"2026-10-09T11:50:30.749184Z","steps":["trace[538406177] 'agreement among raft nodes before linearized reading' (duration: 1.24785747s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.749282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.501085Z","time spent":"1.248181705s","remote":"127.0.0.1:56130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.5\" "} {"level":"warn","ts":"2026-10-09T11:50:30.749346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.10927ms","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-09T11:50:30.749373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049998648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:30.749401Z","caller":"traceutil/trace.go:171","msg":"trace[1063161353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26631; }","duration":"424.211152ms","start":"2026-10-09T11:50:30.325169Z","end":"2026-10-09T11:50:30.74938Z","steps":["trace[1063161353] 'agreement among raft nodes before linearized reading' (duration: 423.999018ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:30.749426Z","caller":"traceutil/trace.go:171","msg":"trace[1067137430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26631; }","duration":"1.05008788s","start":"2026-10-09T11:50:29.699323Z","end":"2026-10-09T11:50:30.749411Z","steps":["trace[1067137430] 'agreement among raft nodes before linearized reading' (duration: 1.049996088s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.749457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:30.325149Z","time spent":"424.285823ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T11:50:30.749466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:29.699305Z","time spent":"1.050151741s","remote":"127.0.0.1:56088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T11:50:30.749113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.528007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:30.749531Z","caller":"traceutil/trace.go:171","msg":"trace[1948360961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26631; }","duration":"471.990326ms","start":"2026-10-09T11:50:30.277522Z","end":"2026-10-09T11:50:30.749513Z","steps":["trace[1948360961] 'agreement among raft nodes before linearized reading' (duration: 471.510027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:30.749579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:30.277503Z","time spent":"472.061087ms","remote":"127.0.0.1:56086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T11:50:31.099679Z","caller":"traceutil/trace.go:171","msg":"trace[512769381] linearizableReadLoop","detail":"{readStateIndex:27521; appliedIndex:27520; }","duration":"345.956962ms","start":"2026-10-09T11:50:30.753689Z","end":"2026-10-09T11:50:31.099646Z","steps":["trace[512769381] 'read index received' (duration: 262.926662ms)","trace[512769381] 'applied index is now lower than readState.Index' (duration: 83.02837ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:31.099757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:30.751133Z","time spent":"348.618503ms","remote":"127.0.0.1:56130","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-09T11:50:31.100015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.321659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T11:50:31.100068Z","caller":"traceutil/trace.go:171","msg":"trace[511864821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26631; }","duration":"346.423102ms","start":"2026-10-09T11:50:30.753631Z","end":"2026-10-09T11:50:31.100054Z","steps":["trace[511864821] 'agreement among raft nodes before linearized reading' (duration: 346.127116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:31.100278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:30.753603Z","time spent":"346.566184ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-09T11:50:31.349282Z","caller":"traceutil/trace.go:171","msg":"trace[2126968369] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"593.913642ms","start":"2026-10-09T11:50:30.755311Z","end":"2026-10-09T11:50:31.349224Z","steps":["trace[2126968369] 'process raft request' (duration: 593.684128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:31.349422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:30.755292Z","time spent":"594.052935ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:31.349465Z","caller":"traceutil/trace.go:171","msg":"trace[393005163] linearizableReadLoop","detail":"{readStateIndex:27523; appliedIndex:27521; }","duration":"249.696352ms","start":"2026-10-09T11:50:31.099741Z","end":"2026-10-09T11:50:31.349438Z","steps":["trace[393005163] 'read index received' (duration: 249.259134ms)","trace[393005163] 'applied index is now lower than readState.Index' (duration: 436.058µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:31.349661Z","caller":"traceutil/trace.go:171","msg":"trace[540657314] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"593.688188ms","start":"2026-10-09T11:50:30.755962Z","end":"2026-10-09T11:50:31.34965Z","steps":["trace[540657314] 'process raft request' (duration: 593.193759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:31.349785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:30.755938Z","time spent":"593.756299ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:31.349878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.986972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T11:50:31.349962Z","caller":"traceutil/trace.go:171","msg":"trace[82192396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26633; }","duration":"595.099675ms","start":"2026-10-09T11:50:30.754851Z","end":"2026-10-09T11:50:31.34995Z","steps":["trace[82192396] 'agreement among raft nodes before linearized reading' (duration: 594.929912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:31.350002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:30.754833Z","time spent":"595.153426ms","remote":"127.0.0.1:56360","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-09T11:50:31.350013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.216012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:31.350086Z","caller":"traceutil/trace.go:171","msg":"trace[1906174490] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26633; }","duration":"572.323264ms","start":"2026-10-09T11:50:30.777743Z","end":"2026-10-09T11:50:31.350067Z","steps":["trace[1906174490] 'agreement among raft nodes before linearized reading' (duration: 572.201982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:31.350134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:30.77773Z","time spent":"572.393825ms","remote":"127.0.0.1:33936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-10-09T11:50:31.360192Z","caller":"traceutil/trace.go:171","msg":"trace[1282290220] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"259.331904ms","start":"2026-10-09T11:50:31.100842Z","end":"2026-10-09T11:50:31.360174Z","steps":["trace[1282290220] 'process raft request' (duration: 259.12043ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:31.360576Z","caller":"traceutil/trace.go:171","msg":"trace[1316553415] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"256.569121ms","start":"2026-10-09T11:50:31.103988Z","end":"2026-10-09T11:50:31.360557Z","steps":["trace[1316553415] 'process raft request' (duration: 256.089532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:31.761189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.744003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:31.761396Z","caller":"traceutil/trace.go:171","msg":"trace[544335235] transaction","detail":"{read_only:false; response_revision:26638; number_of_response:1; }","duration":"395.533401ms","start":"2026-10-09T11:50:31.365843Z","end":"2026-10-09T11:50:31.761377Z","steps":["trace[544335235] 'process raft request' (duration: 395.46538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:31.761471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:31.365819Z","time spent":"395.616782ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:31.761519Z","caller":"traceutil/trace.go:171","msg":"trace[606735619] linearizableReadLoop","detail":"{readStateIndex:27527; appliedIndex:27526; }","duration":"395.725324ms","start":"2026-10-09T11:50:31.365769Z","end":"2026-10-09T11:50:31.761494Z","steps":["trace[606735619] 'read index received' (duration: 283.612814ms)","trace[606735619] 'applied index is now lower than readState.Index' (duration: 112.11109ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:31.76159Z","caller":"traceutil/trace.go:171","msg":"trace[1831861756] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"396.742503ms","start":"2026-10-09T11:50:31.364806Z","end":"2026-10-09T11:50:31.761548Z","steps":["trace[1831861756] 'process raft request' (duration: 284.559682ms)","trace[1831861756] 'compare' (duration: 111.61616ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:31.761694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.899798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:31.761696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.350104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T11:50:31.761749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.807296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-09T11:50:31.761748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:31.364788Z","time spent":"396.883086ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:31.761765Z","caller":"traceutil/trace.go:171","msg":"trace[1093167551] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26638; }","duration":"395.999789ms","start":"2026-10-09T11:50:31.365746Z","end":"2026-10-09T11:50:31.761746Z","steps":["trace[1093167551] 'agreement among raft nodes before linearized reading' (duration: 395.838756ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:31.761768Z","caller":"traceutil/trace.go:171","msg":"trace[369213506] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:26638; }","duration":"168.452976ms","start":"2026-10-09T11:50:31.5933Z","end":"2026-10-09T11:50:31.761753Z","steps":["trace[369213506] 'agreement among raft nodes before linearized reading' (duration: 168.333434ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:31.761783Z","caller":"traceutil/trace.go:171","msg":"trace[1604010173] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26638; }","duration":"395.898788ms","start":"2026-10-09T11:50:31.365874Z","end":"2026-10-09T11:50:31.761772Z","steps":["trace[1604010173] 'agreement among raft nodes before linearized reading' (duration: 395.750115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:31.761805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:31.365738Z","time spent":"396.058151ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-10-09T11:50:31.761818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:31.365837Z","time spent":"395.973159ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-10-09T11:50:31.761769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.488733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:31.761896Z","caller":"traceutil/trace.go:171","msg":"trace[459659398] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:26638; }","duration":"226.648197ms","start":"2026-10-09T11:50:31.535234Z","end":"2026-10-09T11:50:31.761882Z","steps":["trace[459659398] 'agreement among raft nodes before linearized reading' (duration: 226.498804ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:32.48552Z","caller":"traceutil/trace.go:171","msg":"trace[939403067] transaction","detail":"{read_only:false; response_revision:26639; number_of_response:1; }","duration":"419.166427ms","start":"2026-10-09T11:50:32.066328Z","end":"2026-10-09T11:50:32.485495Z","steps":["trace[939403067] 'process raft request' (duration: 419.010254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:32.485665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:32.066304Z","time spent":"419.286169ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:32.675718Z","caller":"traceutil/trace.go:171","msg":"trace[981429984] transaction","detail":"{read_only:false; response_revision:26640; number_of_response:1; }","duration":"608.917405ms","start":"2026-10-09T11:50:32.066771Z","end":"2026-10-09T11:50:32.675688Z","steps":["trace[981429984] 'process raft request' (duration: 571.660201ms)","trace[981429984] 'compare' (duration: 36.852626ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:32.675851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:32.06675Z","time spent":"609.029598ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:32.676273Z","caller":"traceutil/trace.go:171","msg":"trace[776655534] transaction","detail":"{read_only:false; response_revision:26642; number_of_response:1; }","duration":"609.138429ms","start":"2026-10-09T11:50:32.067125Z","end":"2026-10-09T11:50:32.676263Z","steps":["trace[776655534] 'process raft request' (duration: 609.027167ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:32.676287Z","caller":"traceutil/trace.go:171","msg":"trace[67340035] transaction","detail":"{read_only:false; response_revision:26641; number_of_response:1; }","duration":"609.377074ms","start":"2026-10-09T11:50:32.066876Z","end":"2026-10-09T11:50:32.676253Z","steps":["trace[67340035] 'process raft request' (duration: 608.522808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:32.67634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:32.067116Z","time spent":"609.19073ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:32.676465Z","caller":"traceutil/trace.go:171","msg":"trace[479355303] linearizableReadLoop","detail":"{readStateIndex:27532; appliedIndex:27528; }","duration":"279.537597ms","start":"2026-10-09T11:50:32.396687Z","end":"2026-10-09T11:50:32.676224Z","steps":["trace[479355303] 'read index received' (duration: 88.665387ms)","trace[479355303] 'applied index is now lower than readState.Index' (duration: 190.87125ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:32.676496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:32.066868Z","time spent":"609.501996ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:32.676706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.994545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-09T11:50:32.676796Z","caller":"traceutil/trace.go:171","msg":"trace[99113499] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26642; }","duration":"280.145188ms","start":"2026-10-09T11:50:32.396634Z","end":"2026-10-09T11:50:32.676779Z","steps":["trace[99113499] 'agreement among raft nodes before linearized reading' (duration: 279.849503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:32.676844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.224607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-10-09T11:50:32.676932Z","caller":"traceutil/trace.go:171","msg":"trace[1285510871] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26642; }","duration":"227.321019ms","start":"2026-10-09T11:50:32.44956Z","end":"2026-10-09T11:50:32.676881Z","steps":["trace[1285510871] 'agreement among raft nodes before linearized reading' (duration: 227.180387ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:39.195636Z","caller":"traceutil/trace.go:171","msg":"trace[1839327949] transaction","detail":"{read_only:false; response_revision:26741; number_of_response:1; }","duration":"484.187817ms","start":"2026-10-09T11:50:38.711426Z","end":"2026-10-09T11:50:39.195614Z","steps":["trace[1839327949] 'process raft request' (duration: 448.90484ms)","trace[1839327949] 'compare' (duration: 35.017692ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:39.195757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:38.711402Z","time spent":"484.296739ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:39.195866Z","caller":"traceutil/trace.go:171","msg":"trace[203790061] transaction","detail":"{read_only:false; response_revision:26742; number_of_response:1; }","duration":"483.483923ms","start":"2026-10-09T11:50:38.712339Z","end":"2026-10-09T11:50:39.195823Z","steps":["trace[203790061] 'process raft request' (duration: 483.212458ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:39.196003Z","caller":"traceutil/trace.go:171","msg":"trace[1942816063] transaction","detail":"{read_only:false; response_revision:26743; number_of_response:1; }","duration":"427.345311ms","start":"2026-10-09T11:50:38.76849Z","end":"2026-10-09T11:50:39.195835Z","steps":["trace[1942816063] 'process raft request' (duration: 427.231069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.196043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:38.712314Z","time spent":"483.652277ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:39.196243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:38.768463Z","time spent":"427.611887ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:39.205818Z","caller":"traceutil/trace.go:171","msg":"trace[98111910] linearizableReadLoop","detail":"{readStateIndex:27636; appliedIndex:27631; }","duration":"312.757165ms","start":"2026-10-09T11:50:38.893043Z","end":"2026-10-09T11:50:39.2058Z","steps":["trace[98111910] 'read index received' (duration: 267.226434ms)","trace[98111910] 'applied index is now lower than readState.Index' (duration: 45.529861ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:39.206018Z","caller":"traceutil/trace.go:171","msg":"trace[987898487] transaction","detail":"{read_only:false; response_revision:26744; number_of_response:1; }","duration":"414.06385ms","start":"2026-10-09T11:50:38.791943Z","end":"2026-10-09T11:50:39.206007Z","steps":["trace[987898487] 'process raft request' (duration: 413.748284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.206082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:38.791914Z","time spent":"414.121982ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:39.206199Z","caller":"traceutil/trace.go:171","msg":"trace[2128800493] transaction","detail":"{read_only:false; response_revision:26745; number_of_response:1; }","duration":"414.249774ms","start":"2026-10-09T11:50:38.791943Z","end":"2026-10-09T11:50:39.206193Z","steps":["trace[2128800493] 'process raft request' (duration: 413.824476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.206255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:38.791913Z","time spent":"414.315206ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:39.20637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.327616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-09528f31-7f46-4918-88ef-bd067d3db7e6\" ","response":"range_response_count:1 size:3642"} {"level":"info","ts":"2026-10-09T11:50:39.206389Z","caller":"traceutil/trace.go:171","msg":"trace[1308143727] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-09528f31-7f46-4918-88ef-bd067d3db7e6; range_end:; response_count:1; response_revision:26745; }","duration":"313.388548ms","start":"2026-10-09T11:50:38.892994Z","end":"2026-10-09T11:50:39.206383Z","steps":["trace[1308143727] 'agreement among raft nodes before linearized reading' (duration: 313.303796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.206406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:38.892976Z","time spent":"313.425669ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-09528f31-7f46-4918-88ef-bd067d3db7e6\" "} {"level":"warn","ts":"2026-10-09T11:50:39.20656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.988069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-09528f31-7f46-4918-88ef-bd067d3db7e6\" ","response":"range_response_count:1 size:3642"} {"level":"info","ts":"2026-10-09T11:50:39.206583Z","caller":"traceutil/trace.go:171","msg":"trace[503079533] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-09528f31-7f46-4918-88ef-bd067d3db7e6; range_end:; response_count:1; response_revision:26745; }","duration":"166.009099ms","start":"2026-10-09T11:50:39.040566Z","end":"2026-10-09T11:50:39.206575Z","steps":["trace[503079533] 'agreement among raft nodes before linearized reading' (duration: 165.951038ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:39.604901Z","caller":"traceutil/trace.go:171","msg":"trace[440894674] transaction","detail":"{read_only:false; response_revision:26746; number_of_response:1; }","duration":"392.35774ms","start":"2026-10-09T11:50:39.212519Z","end":"2026-10-09T11:50:39.604877Z","steps":["trace[440894674] 'process raft request' (duration: 358.260445ms)","trace[440894674] 'compare' (duration: 33.685717ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:39.605048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.212503Z","time spent":"392.466042ms","remote":"127.0.0.1:56296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3744,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:50:39.604845Z","caller":"traceutil/trace.go:171","msg":"trace[961707258] linearizableReadLoop","detail":"{readStateIndex:27637; appliedIndex:27636; }","duration":"325.795461ms","start":"2026-10-09T11:50:39.279025Z","end":"2026-10-09T11:50:39.604821Z","steps":["trace[961707258] 'read index received' (duration: 291.767228ms)","trace[961707258] 'applied index is now lower than readState.Index' (duration: 34.026303ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:39.60539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.354042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:39.605439Z","caller":"traceutil/trace.go:171","msg":"trace[349862411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26746; }","duration":"326.453274ms","start":"2026-10-09T11:50:39.278971Z","end":"2026-10-09T11:50:39.605424Z","steps":["trace[349862411] 'agreement among raft nodes before linearized reading' (duration: 326.340972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.605479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.278954Z","time spent":"326.518385ms","remote":"127.0.0.1:56088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T11:50:39.885372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.285365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:50:39.885456Z","caller":"traceutil/trace.go:171","msg":"trace[342614491] linearizableReadLoop","detail":"{readStateIndex:27638; appliedIndex:27637; }","duration":"280.030076ms","start":"2026-10-09T11:50:39.605412Z","end":"2026-10-09T11:50:39.885442Z","steps":["trace[342614491] 'read index received' (duration: 132.522986ms)","trace[342614491] 'applied index is now lower than readState.Index' (duration: 147.50565ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:50:39.885519Z","caller":"traceutil/trace.go:171","msg":"trace[907615995] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"491.985284ms","start":"2026-10-09T11:50:39.393526Z","end":"2026-10-09T11:50:39.885512Z","steps":["trace[907615995] 'process raft request' (duration: 344.479615ms)","trace[907615995] 'compare' (duration: 147.02855ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:50:39.885555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.393506Z","time spent":"492.028366ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:50:39.885781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.413248ms","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-09T11:50:39.885804Z","caller":"traceutil/trace.go:171","msg":"trace[1889921382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26747; }","duration":"487.47731ms","start":"2026-10-09T11:50:39.398321Z","end":"2026-10-09T11:50:39.885798Z","steps":["trace[1889921382] 'agreement among raft nodes before linearized reading' (duration: 487.365707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.885825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.398299Z","time spent":"487.52092ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T11:50:39.885928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.208847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.5\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-09T11:50:39.885934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.986778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-10-09T11:50:39.885968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.405154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:50:39.886002Z","caller":"traceutil/trace.go:171","msg":"trace[946996224] range","detail":"{range_begin:/registry/masterleases/162.253.55.5; range_end:; response_count:1; response_revision:26747; }","duration":"384.323889ms","start":"2026-10-09T11:50:39.501658Z","end":"2026-10-09T11:50:39.885982Z","steps":["trace[946996224] 'agreement among raft nodes before linearized reading' (duration: 384.119055ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:39.886011Z","caller":"traceutil/trace.go:171","msg":"trace[9538037] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26747; }","duration":"287.10298ms","start":"2026-10-09T11:50:39.598886Z","end":"2026-10-09T11:50:39.885989Z","steps":["trace[9538037] 'agreement among raft nodes before linearized reading' (duration: 286.937257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.886041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.501642Z","time spent":"384.38973ms","remote":"127.0.0.1:56130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.5\" "} {"level":"info","ts":"2026-10-09T11:50:39.886037Z","caller":"traceutil/trace.go:171","msg":"trace[1222497695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26747; }","duration":"279.572018ms","start":"2026-10-09T11:50:39.606446Z","end":"2026-10-09T11:50:39.886018Z","steps":["trace[1222497695] 'agreement among raft nodes before linearized reading' (duration: 279.448725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.886086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.939325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18dcda2c9ece1c2b\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-10-09T11:50:39.886112Z","caller":"traceutil/trace.go:171","msg":"trace[397850008] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18dcda2c9ece1c2b; range_end:; response_count:1; response_revision:26747; }","duration":"141.996326ms","start":"2026-10-09T11:50:39.744106Z","end":"2026-10-09T11:50:39.886102Z","steps":["trace[397850008] 'agreement among raft nodes before linearized reading' (duration: 141.927565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.885925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.176039ms","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-09T11:50:39.886234Z","caller":"traceutil/trace.go:171","msg":"trace[1135724405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26747; }","duration":"483.486764ms","start":"2026-10-09T11:50:39.402714Z","end":"2026-10-09T11:50:39.886201Z","steps":["trace[1135724405] 'agreement among raft nodes before linearized reading' (duration: 483.099267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.886271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.402705Z","time spent":"483.555815ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T11:50:39.886528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.375372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T11:50:39.886573Z","caller":"traceutil/trace.go:171","msg":"trace[868292686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26747; }","duration":"484.32215ms","start":"2026-10-09T11:50:39.402237Z","end":"2026-10-09T11:50:39.886559Z","steps":["trace[868292686] 'agreement among raft nodes before linearized reading' (duration: 483.313851ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:50:39.886963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:50:39.402225Z","time spent":"484.714517ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-09T11:50:45.372843Z","caller":"traceutil/trace.go:171","msg":"trace[913627136] transaction","detail":"{read_only:false; response_revision:26805; number_of_response:1; }","duration":"153.984422ms","start":"2026-10-09T11:50:45.218836Z","end":"2026-10-09T11:50:45.37282Z","steps":["trace[913627136] 'process raft request' (duration: 153.90854ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:45.372839Z","caller":"traceutil/trace.go:171","msg":"trace[515021280] transaction","detail":"{read_only:false; response_revision:26804; number_of_response:1; }","duration":"154.130404ms","start":"2026-10-09T11:50:45.218666Z","end":"2026-10-09T11:50:45.372797Z","steps":["trace[515021280] 'process raft request' (duration: 153.952941ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:45.373199Z","caller":"traceutil/trace.go:171","msg":"trace[567565324] transaction","detail":"{read_only:false; response_revision:26806; number_of_response:1; }","duration":"148.803874ms","start":"2026-10-09T11:50:45.224387Z","end":"2026-10-09T11:50:45.373191Z","steps":["trace[567565324] 'process raft request' (duration: 148.399046ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:50:45.37329Z","caller":"traceutil/trace.go:171","msg":"trace[175476279] transaction","detail":"{read_only:false; response_revision:26807; number_of_response:1; }","duration":"148.142381ms","start":"2026-10-09T11:50:45.225117Z","end":"2026-10-09T11:50:45.37326Z","steps":["trace[175476279] 'process raft request' (duration: 147.968338ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:09.671947Z","caller":"traceutil/trace.go:171","msg":"trace[348428975] transaction","detail":"{read_only:false; response_revision:27015; number_of_response:1; }","duration":"202.02535ms","start":"2026-10-09T11:51:09.469883Z","end":"2026-10-09T11:51:09.671908Z","steps":["trace[348428975] 'process raft request' (duration: 201.920668ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:09.672079Z","caller":"traceutil/trace.go:171","msg":"trace[444276595] transaction","detail":"{read_only:false; response_revision:27014; number_of_response:1; }","duration":"208.276439ms","start":"2026-10-09T11:51:09.46375Z","end":"2026-10-09T11:51:09.672027Z","steps":["trace[444276595] 'process raft request' (duration: 182.99705ms)","trace[444276595] 'compare' (duration: 24.82695ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:09.672306Z","caller":"traceutil/trace.go:171","msg":"trace[1932810557] transaction","detail":"{read_only:false; response_revision:27016; number_of_response:1; }","duration":"202.251575ms","start":"2026-10-09T11:51:09.470021Z","end":"2026-10-09T11:51:09.672273Z","steps":["trace[1932810557] 'process raft request' (duration: 201.835217ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:09.672576Z","caller":"traceutil/trace.go:171","msg":"trace[201449454] linearizableReadLoop","detail":"{readStateIndex:27916; appliedIndex:27912; }","duration":"151.25037ms","start":"2026-10-09T11:51:09.521281Z","end":"2026-10-09T11:51:09.672531Z","steps":["trace[201449454] 'read index received' (duration: 125.387731ms)","trace[201449454] 'applied index is now lower than readState.Index' (duration: 25.859459ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:09.672721Z","caller":"traceutil/trace.go:171","msg":"trace[1898343850] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"202.363117ms","start":"2026-10-09T11:51:09.470324Z","end":"2026-10-09T11:51:09.672687Z","steps":["trace[1898343850] 'process raft request' (duration: 201.798196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:09.672791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.359268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:51:09.67285Z","caller":"traceutil/trace.go:171","msg":"trace[1894715542] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:27017; }","duration":"165.445269ms","start":"2026-10-09T11:51:09.507386Z","end":"2026-10-09T11:51:09.672832Z","steps":["trace[1894715542] 'agreement among raft nodes before linearized reading' (duration: 165.301116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:09.67288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.522957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.5\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-09T11:51:09.672958Z","caller":"traceutil/trace.go:171","msg":"trace[314440912] range","detail":"{range_begin:/registry/masterleases/162.253.55.5; range_end:; response_count:1; response_revision:27017; }","duration":"169.647199ms","start":"2026-10-09T11:51:09.503291Z","end":"2026-10-09T11:51:09.672938Z","steps":["trace[314440912] 'agreement among raft nodes before linearized reading' (duration: 169.424965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:09.674236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.486688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5e46e5c1-ff56-439c-983e-38426a05d1bd\" ","response":"range_response_count:1 size:1316"} {"level":"info","ts":"2026-10-09T11:51:09.674476Z","caller":"traceutil/trace.go:171","msg":"trace[208491077] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5e46e5c1-ff56-439c-983e-38426a05d1bd; range_end:; response_count:1; response_revision:27017; }","duration":"149.323244ms","start":"2026-10-09T11:51:09.525137Z","end":"2026-10-09T11:51:09.67446Z","steps":["trace[208491077] 'agreement among raft nodes before linearized reading' (duration: 147.624632ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:09.885272Z","caller":"traceutil/trace.go:171","msg":"trace[1755372872] transaction","detail":"{read_only:false; response_revision:27018; number_of_response:1; }","duration":"177.809634ms","start":"2026-10-09T11:51:09.707405Z","end":"2026-10-09T11:51:09.885215Z","steps":["trace[1755372872] 'process raft request' (duration: 110.814067ms)","trace[1755372872] 'compare' (duration: 66.847574ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:10.137698Z","caller":"traceutil/trace.go:171","msg":"trace[1471590161] linearizableReadLoop","detail":"{readStateIndex:27919; appliedIndex:27918; }","duration":"107.213898ms","start":"2026-10-09T11:51:10.03045Z","end":"2026-10-09T11:51:10.137664Z","steps":["trace[1471590161] 'read index received' (duration: 84.249434ms)","trace[1471590161] 'applied index is now lower than readState.Index' (duration: 22.962834ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:10.13779Z","caller":"traceutil/trace.go:171","msg":"trace[293878019] transaction","detail":"{read_only:false; response_revision:27019; number_of_response:1; }","duration":"148.883386ms","start":"2026-10-09T11:51:09.988862Z","end":"2026-10-09T11:51:10.137746Z","steps":["trace[293878019] 'process raft request' (duration: 125.968842ms)","trace[293878019] 'compare' (duration: 22.488706ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:51:10.137907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.430102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:51:10.137949Z","caller":"traceutil/trace.go:171","msg":"trace[1219365429] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:27019; }","duration":"107.547894ms","start":"2026-10-09T11:51:10.030386Z","end":"2026-10-09T11:51:10.137934Z","steps":["trace[1219365429] 'agreement among raft nodes before linearized reading' (duration: 107.441672ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:10.13829Z","caller":"traceutil/trace.go:171","msg":"trace[984807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27019; }","duration":"100.042703ms","start":"2026-10-09T11:51:10.038185Z","end":"2026-10-09T11:51:10.138228Z","steps":["trace[984807] 'agreement among raft nodes before linearized reading' (duration: 99.846159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:10.425143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.748656ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:51:10.425305Z","caller":"traceutil/trace.go:171","msg":"trace[299712301] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:27019; }","duration":"172.94779ms","start":"2026-10-09T11:51:10.252332Z","end":"2026-10-09T11:51:10.42528Z","steps":["trace[299712301] 'range keys from in-memory index tree' (duration: 172.729266ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:10.448652Z","caller":"traceutil/trace.go:171","msg":"trace[1605428553] transaction","detail":"{read_only:false; response_revision:27020; number_of_response:1; }","duration":"307.063348ms","start":"2026-10-09T11:51:10.141565Z","end":"2026-10-09T11:51:10.448628Z","steps":["trace[1605428553] 'process raft request' (duration: 306.891825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:10.448816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:51:10.141529Z","time spent":"307.19837ms","remote":"127.0.0.1:56174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1119,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:51:10.478676Z","caller":"traceutil/trace.go:171","msg":"trace[803550451] transaction","detail":"{read_only:false; response_revision:27021; number_of_response:1; }","duration":"335.542605ms","start":"2026-10-09T11:51:10.143093Z","end":"2026-10-09T11:51:10.478636Z","steps":["trace[803550451] 'process raft request' (duration: 335.27922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:10.478929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:51:10.143067Z","time spent":"335.74338ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:51:10.478978Z","caller":"traceutil/trace.go:171","msg":"trace[1416589595] transaction","detail":"{read_only:false; response_revision:27022; number_of_response:1; }","duration":"335.434004ms","start":"2026-10-09T11:51:10.143494Z","end":"2026-10-09T11:51:10.478928Z","steps":["trace[1416589595] 'process raft request' (duration: 335.092917ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:10.479035Z","caller":"traceutil/trace.go:171","msg":"trace[1982192836] transaction","detail":"{read_only:false; response_revision:27025; number_of_response:1; }","duration":"334.460926ms","start":"2026-10-09T11:51:10.144553Z","end":"2026-10-09T11:51:10.479014Z","steps":["trace[1982192836] 'process raft request' (duration: 334.373494ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:10.479063Z","caller":"traceutil/trace.go:171","msg":"trace[1743932302] transaction","detail":"{read_only:false; response_revision:27023; number_of_response:1; }","duration":"335.320391ms","start":"2026-10-09T11:51:10.143733Z","end":"2026-10-09T11:51:10.479054Z","steps":["trace[1743932302] 'process raft request' (duration: 335.059936ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:10.479098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.55718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:51:10.479133Z","caller":"traceutil/trace.go:171","msg":"trace[1669128713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27025; }","duration":"185.643581ms","start":"2026-10-09T11:51:10.293481Z","end":"2026-10-09T11:51:10.479125Z","steps":["trace[1669128713] 'agreement among raft nodes before linearized reading' (duration: 185.560699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:10.479154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:51:10.144539Z","time spent":"334.545427ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:51:10.479159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:51:10.143713Z","time spent":"335.382182ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:51:10.479185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:51:10.143469Z","time spent":"335.613057ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:51:10.478979Z","caller":"traceutil/trace.go:171","msg":"trace[1015638032] linearizableReadLoop","detail":"{readStateIndex:27925; appliedIndex:27919; }","duration":"185.431527ms","start":"2026-10-09T11:51:10.293526Z","end":"2026-10-09T11:51:10.478958Z","steps":["trace[1015638032] 'read index received' (duration: 154.976631ms)","trace[1015638032] 'applied index is now lower than readState.Index' (duration: 30.453956ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:10.479013Z","caller":"traceutil/trace.go:171","msg":"trace[2010634454] transaction","detail":"{read_only:false; response_revision:27024; number_of_response:1; }","duration":"334.537167ms","start":"2026-10-09T11:51:10.144456Z","end":"2026-10-09T11:51:10.478993Z","steps":["trace[2010634454] 'process raft request' (duration: 334.440245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:10.479338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:51:10.144438Z","time spent":"334.870213ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T11:51:10.925457Z","caller":"traceutil/trace.go:171","msg":"trace[602752981] transaction","detail":"{read_only:false; response_revision:27027; number_of_response:1; }","duration":"108.005692ms","start":"2026-10-09T11:51:10.817401Z","end":"2026-10-09T11:51:10.925406Z","steps":["trace[602752981] 'process raft request' (duration: 107.756488ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:11.836617Z","caller":"traceutil/trace.go:171","msg":"trace[1812334690] transaction","detail":"{read_only:false; response_revision:27030; number_of_response:1; }","duration":"157.319846ms","start":"2026-10-09T11:51:11.67927Z","end":"2026-10-09T11:51:11.83659Z","steps":["trace[1812334690] 'process raft request' (duration: 157.04594ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:11.903322Z","caller":"traceutil/trace.go:171","msg":"trace[565240728] transaction","detail":"{read_only:false; response_revision:27031; number_of_response:1; }","duration":"223.703261ms","start":"2026-10-09T11:51:11.679599Z","end":"2026-10-09T11:51:11.903302Z","steps":["trace[565240728] 'process raft request' (duration: 223.542738ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:11.903619Z","caller":"traceutil/trace.go:171","msg":"trace[1279956875] transaction","detail":"{read_only:false; response_revision:27032; number_of_response:1; }","duration":"223.985837ms","start":"2026-10-09T11:51:11.6796Z","end":"2026-10-09T11:51:11.903586Z","steps":["trace[1279956875] 'process raft request' (duration: 223.66346ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:11.903737Z","caller":"traceutil/trace.go:171","msg":"trace[75206842] transaction","detail":"{read_only:false; response_revision:27033; number_of_response:1; }","duration":"224.023217ms","start":"2026-10-09T11:51:11.679685Z","end":"2026-10-09T11:51:11.903708Z","steps":["trace[75206842] 'process raft request' (duration: 223.807743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:12.058811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.387745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:51:12.058901Z","caller":"traceutil/trace.go:171","msg":"trace[1716782208] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:27033; }","duration":"150.497297ms","start":"2026-10-09T11:51:11.908382Z","end":"2026-10-09T11:51:12.058879Z","steps":["trace[1716782208] 'range keys from in-memory index tree' (duration: 150.288083ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:12.278135Z","caller":"traceutil/trace.go:171","msg":"trace[235495641] transaction","detail":"{read_only:false; response_revision:27034; number_of_response:1; }","duration":"133.363342ms","start":"2026-10-09T11:51:12.144703Z","end":"2026-10-09T11:51:12.278066Z","steps":["trace[235495641] 'process raft request' (duration: 133.135158ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:12.743848Z","caller":"traceutil/trace.go:171","msg":"trace[1848754732] transaction","detail":"{read_only:false; response_revision:27037; number_of_response:1; }","duration":"254.528814ms","start":"2026-10-09T11:51:12.48928Z","end":"2026-10-09T11:51:12.743809Z","steps":["trace[1848754732] 'process raft request' (duration: 254.412402ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:12.744009Z","caller":"traceutil/trace.go:171","msg":"trace[1690039911] transaction","detail":"{read_only:false; response_revision:27036; number_of_response:1; }","duration":"255.530043ms","start":"2026-10-09T11:51:12.488441Z","end":"2026-10-09T11:51:12.743971Z","steps":["trace[1690039911] 'process raft request' (duration: 193.749375ms)","trace[1690039911] 'compare' (duration: 61.271628ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:12.744359Z","caller":"traceutil/trace.go:171","msg":"trace[1506541170] transaction","detail":"{read_only:false; response_revision:27038; number_of_response:1; }","duration":"254.945431ms","start":"2026-10-09T11:51:12.489402Z","end":"2026-10-09T11:51:12.744347Z","steps":["trace[1506541170] 'process raft request' (duration: 254.37601ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:12.74457Z","caller":"traceutil/trace.go:171","msg":"trace[1677185436] transaction","detail":"{read_only:false; response_revision:27039; number_of_response:1; }","duration":"253.79786ms","start":"2026-10-09T11:51:12.49076Z","end":"2026-10-09T11:51:12.744557Z","steps":["trace[1677185436] 'process raft request' (duration: 253.543295ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:13.033833Z","caller":"traceutil/trace.go:171","msg":"trace[1340403809] transaction","detail":"{read_only:false; response_revision:27041; number_of_response:1; }","duration":"101.533559ms","start":"2026-10-09T11:51:12.932277Z","end":"2026-10-09T11:51:13.033811Z","steps":["trace[1340403809] 'process raft request' (duration: 101.368126ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:13.759643Z","caller":"traceutil/trace.go:171","msg":"trace[1104217656] transaction","detail":"{read_only:false; response_revision:27042; number_of_response:1; }","duration":"136.349649ms","start":"2026-10-09T11:51:13.623268Z","end":"2026-10-09T11:51:13.759617Z","steps":["trace[1104217656] 'process raft request' (duration: 136.186936ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:19.885636Z","caller":"traceutil/trace.go:171","msg":"trace[644045942] transaction","detail":"{read_only:false; response_revision:27089; number_of_response:1; }","duration":"124.452064ms","start":"2026-10-09T11:51:19.761154Z","end":"2026-10-09T11:51:19.885606Z","steps":["trace[644045942] 'process raft request' (duration: 124.23841ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:19.906886Z","caller":"traceutil/trace.go:171","msg":"trace[92595181] linearizableReadLoop","detail":"{readStateIndex:27992; appliedIndex:27990; }","duration":"107.299788ms","start":"2026-10-09T11:51:19.799557Z","end":"2026-10-09T11:51:19.906857Z","steps":["trace[92595181] 'read index received' (duration: 85.866323ms)","trace[92595181] 'applied index is now lower than readState.Index' (duration: 21.432095ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:51:19.907071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.497443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:51:19.907105Z","caller":"traceutil/trace.go:171","msg":"trace[1213627062] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:27091; }","duration":"107.587094ms","start":"2026-10-09T11:51:19.799504Z","end":"2026-10-09T11:51:19.907091Z","steps":["trace[1213627062] 'agreement among raft nodes before linearized reading' (duration: 107.446441ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:19.907461Z","caller":"traceutil/trace.go:171","msg":"trace[1369027607] transaction","detail":"{read_only:false; response_revision:27090; number_of_response:1; }","duration":"121.023479ms","start":"2026-10-09T11:51:19.786426Z","end":"2026-10-09T11:51:19.90745Z","steps":["trace[1369027607] 'process raft request' (duration: 120.274244ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:20.034944Z","caller":"traceutil/trace.go:171","msg":"trace[419989641] transaction","detail":"{read_only:false; response_revision:27092; number_of_response:1; }","duration":"101.736234ms","start":"2026-10-09T11:51:19.933147Z","end":"2026-10-09T11:51:20.034883Z","steps":["trace[419989641] 'process raft request' (duration: 30.816033ms)","trace[419989641] 'compare' (duration: 70.814999ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:20.048433Z","caller":"traceutil/trace.go:171","msg":"trace[986716680] transaction","detail":"{read_only:false; response_revision:27093; number_of_response:1; }","duration":"114.551596ms","start":"2026-10-09T11:51:19.93381Z","end":"2026-10-09T11:51:20.048362Z","steps":["trace[986716680] 'process raft request' (duration: 114.266621ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:20.04842Z","caller":"traceutil/trace.go:171","msg":"trace[1017109160] transaction","detail":"{read_only:false; response_revision:27094; number_of_response:1; }","duration":"114.266482ms","start":"2026-10-09T11:51:19.93409Z","end":"2026-10-09T11:51:20.048357Z","steps":["trace[1017109160] 'process raft request' (duration: 114.200991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:34.861444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.253335ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-09T11:51:35.116166Z","caller":"traceutil/trace.go:171","msg":"trace[449414337] linearizableReadLoop","detail":"{readStateIndex:28109; appliedIndex:28108; }","duration":"260.810041ms","start":"2026-10-09T11:51:34.855322Z","end":"2026-10-09T11:51:35.116132Z","steps":["trace[449414337] 'read index received' (duration: 260.555848ms)","trace[449414337] 'applied index is now lower than readState.Index' (duration: 252.563µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:35.116233Z","caller":"traceutil/trace.go:171","msg":"trace[1157021908] transaction","detail":"{read_only:false; response_revision:27204; number_of_response:1; }","duration":"298.642378ms","start":"2026-10-09T11:51:34.817548Z","end":"2026-10-09T11:51:35.11619Z","steps":["trace[1157021908] 'process raft request' (duration: 298.270021ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:35.116529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.188529ms","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-09T11:51:35.116585Z","caller":"traceutil/trace.go:171","msg":"trace[2118923934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27204; }","duration":"261.332082ms","start":"2026-10-09T11:51:34.855235Z","end":"2026-10-09T11:51:35.116567Z","steps":["trace[2118923934] 'agreement among raft nodes before linearized reading' (duration: 261.094687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:35.116673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.707689ms","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-09T11:51:35.11672Z","caller":"traceutil/trace.go:171","msg":"trace[1470743292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27204; }","duration":"233.808252ms","start":"2026-10-09T11:51:34.8829Z","end":"2026-10-09T11:51:35.116708Z","steps":["trace[1470743292] 'agreement among raft nodes before linearized reading' (duration: 233.605388ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:35.116734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.218281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-09T11:51:35.116789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.641162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T11:51:35.116969Z","caller":"traceutil/trace.go:171","msg":"trace[826186280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27204; }","duration":"249.868245ms","start":"2026-10-09T11:51:34.867086Z","end":"2026-10-09T11:51:35.116955Z","steps":["trace[826186280] 'agreement among raft nodes before linearized reading' (duration: 249.59345ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:35.116861Z","caller":"traceutil/trace.go:171","msg":"trace[1014306959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27204; }","duration":"260.427385ms","start":"2026-10-09T11:51:34.856411Z","end":"2026-10-09T11:51:35.116838Z","steps":["trace[1014306959] 'agreement among raft nodes before linearized reading' (duration: 260.084988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:35.340536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.697529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:51:35.340641Z","caller":"traceutil/trace.go:171","msg":"trace[1372766951] transaction","detail":"{read_only:false; response_revision:27205; number_of_response:1; }","duration":"218.92045ms","start":"2026-10-09T11:51:35.121698Z","end":"2026-10-09T11:51:35.340619Z","steps":["trace[1372766951] 'process raft request' (duration: 76.048278ms)","trace[1372766951] 'compare' (duration: 142.469114ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:35.367754Z","caller":"traceutil/trace.go:171","msg":"trace[1789933056] transaction","detail":"{read_only:false; response_revision:27207; number_of_response:1; }","duration":"245.168707ms","start":"2026-10-09T11:51:35.122548Z","end":"2026-10-09T11:51:35.367717Z","steps":["trace[1789933056] 'process raft request' (duration: 244.662027ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:35.36779Z","caller":"traceutil/trace.go:171","msg":"trace[2108003992] linearizableReadLoop","detail":"{readStateIndex:28114; appliedIndex:28109; }","duration":"227.457742ms","start":"2026-10-09T11:51:35.140306Z","end":"2026-10-09T11:51:35.367764Z","steps":["trace[2108003992] 'read index received' (duration: 57.481027ms)","trace[2108003992] 'applied index is now lower than readState.Index' (duration: 169.975255ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:35.368011Z","caller":"traceutil/trace.go:171","msg":"trace[492570856] transaction","detail":"{read_only:false; response_revision:27209; number_of_response:1; }","duration":"242.823801ms","start":"2026-10-09T11:51:35.125123Z","end":"2026-10-09T11:51:35.367947Z","steps":["trace[492570856] 'process raft request' (duration: 242.505776ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:35.367988Z","caller":"traceutil/trace.go:171","msg":"trace[683532246] transaction","detail":"{read_only:false; response_revision:27206; number_of_response:1; }","duration":"245.646906ms","start":"2026-10-09T11:51:35.121707Z","end":"2026-10-09T11:51:35.367354Z","steps":["trace[683532246] 'process raft request' (duration: 245.293699ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:35.368156Z","caller":"traceutil/trace.go:171","msg":"trace[1799716130] transaction","detail":"{read_only:false; response_revision:27208; number_of_response:1; }","duration":"245.012943ms","start":"2026-10-09T11:51:35.12313Z","end":"2026-10-09T11:51:35.368143Z","steps":["trace[1799716130] 'process raft request' (duration: 244.358942ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:35.368226Z","caller":"traceutil/trace.go:171","msg":"trace[880968777] transaction","detail":"{read_only:false; response_revision:27210; number_of_response:1; }","duration":"180.135346ms","start":"2026-10-09T11:51:35.18804Z","end":"2026-10-09T11:51:35.368176Z","steps":["trace[880968777] 'process raft request' (duration: 179.672218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:35.368288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.868309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T11:51:35.368995Z","caller":"traceutil/trace.go:171","msg":"trace[299693686] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:27210; }","duration":"228.692185ms","start":"2026-10-09T11:51:35.140252Z","end":"2026-10-09T11:51:35.368944Z","steps":["trace[299693686] 'agreement among raft nodes before linearized reading' (duration: 227.765057ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:35.593435Z","caller":"traceutil/trace.go:171","msg":"trace[451800058] transaction","detail":"{read_only:false; response_revision:27211; number_of_response:1; }","duration":"219.187296ms","start":"2026-10-09T11:51:35.374215Z","end":"2026-10-09T11:51:35.593402Z","steps":["trace[451800058] 'process raft request' (duration: 121.409207ms)","trace[451800058] 'compare' (duration: 97.447793ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:35.689647Z","caller":"traceutil/trace.go:171","msg":"trace[340760041] transaction","detail":"{read_only:false; response_revision:27212; number_of_response:1; }","duration":"315.060919ms","start":"2026-10-09T11:51:35.374542Z","end":"2026-10-09T11:51:35.689603Z","steps":["trace[340760041] 'process raft request' (duration: 314.807874ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:35.689794Z","caller":"traceutil/trace.go:171","msg":"trace[643268803] linearizableReadLoop","detail":"{readStateIndex:28118; appliedIndex:28115; }","duration":"299.465854ms","start":"2026-10-09T11:51:35.390291Z","end":"2026-10-09T11:51:35.689756Z","steps":["trace[643268803] 'read index received' (duration: 105.432344ms)","trace[643268803] 'applied index is now lower than readState.Index' (duration: 194.03032ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:51:35.689817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:51:35.374513Z","time spent":"315.219752ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1206,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:51:35.689964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.648766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T11:51:35.690021Z","caller":"traceutil/trace.go:171","msg":"trace[186279871] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:27213; }","duration":"299.774028ms","start":"2026-10-09T11:51:35.390228Z","end":"2026-10-09T11:51:35.690002Z","steps":["trace[186279871] 'agreement among raft nodes before linearized reading' (duration: 299.630906ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:35.689977Z","caller":"traceutil/trace.go:171","msg":"trace[1461717190] transaction","detail":"{read_only:false; response_revision:27213; number_of_response:1; }","duration":"313.895625ms","start":"2026-10-09T11:51:35.375985Z","end":"2026-10-09T11:51:35.689881Z","steps":["trace[1461717190] 'process raft request' (duration: 313.521059ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:35.690377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:51:35.375971Z","time spent":"314.182161ms","remote":"127.0.0.1:56626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:51:35.937329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.310011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:51:35.937454Z","caller":"traceutil/trace.go:171","msg":"trace[1649806952] transaction","detail":"{read_only:false; response_revision:27214; number_of_response:1; }","duration":"239.078792ms","start":"2026-10-09T11:51:35.698351Z","end":"2026-10-09T11:51:35.93743Z","steps":["trace[1649806952] 'process raft request' (duration: 87.477955ms)","trace[1649806952] 'compare' (duration: 151.187849ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:36.133534Z","caller":"traceutil/trace.go:171","msg":"trace[387513540] transaction","detail":"{read_only:false; response_revision:27215; number_of_response:1; }","duration":"174.673914ms","start":"2026-10-09T11:51:35.958827Z","end":"2026-10-09T11:51:36.133501Z","steps":["trace[387513540] 'process raft request' (duration: 174.486671ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:36.313867Z","caller":"traceutil/trace.go:171","msg":"trace[1206419180] transaction","detail":"{read_only:false; response_revision:27219; number_of_response:1; }","duration":"212.770054ms","start":"2026-10-09T11:51:36.101075Z","end":"2026-10-09T11:51:36.313845Z","steps":["trace[1206419180] 'process raft request' (duration: 212.523829ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:36.31393Z","caller":"traceutil/trace.go:171","msg":"trace[1273230846] transaction","detail":"{read_only:false; response_revision:27217; number_of_response:1; }","duration":"334.67833ms","start":"2026-10-09T11:51:35.979233Z","end":"2026-10-09T11:51:36.313911Z","steps":["trace[1273230846] 'process raft request' (duration: 334.263922ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:36.313923Z","caller":"traceutil/trace.go:171","msg":"trace[448882736] transaction","detail":"{read_only:false; response_revision:27216; number_of_response:1; }","duration":"335.249032ms","start":"2026-10-09T11:51:35.978649Z","end":"2026-10-09T11:51:36.313898Z","steps":["trace[448882736] 'process raft request' (duration: 334.606369ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:36.313957Z","caller":"traceutil/trace.go:171","msg":"trace[1395689134] transaction","detail":"{read_only:false; response_revision:27220; number_of_response:1; }","duration":"200.40128ms","start":"2026-10-09T11:51:36.113541Z","end":"2026-10-09T11:51:36.313943Z","steps":["trace[1395689134] 'process raft request' (duration: 200.090344ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:51:36.313863Z","caller":"traceutil/trace.go:171","msg":"trace[1309970602] transaction","detail":"{read_only:false; response_revision:27218; number_of_response:1; }","duration":"213.940706ms","start":"2026-10-09T11:51:36.099875Z","end":"2026-10-09T11:51:36.313816Z","steps":["trace[1309970602] 'process raft request' (duration: 213.686331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:36.314055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:51:35.978619Z","time spent":"335.367363ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:51:36.314055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T11:51:35.979221Z","time spent":"334.755922ms","remote":"127.0.0.1:56360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T11:51:36.561301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.694475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:51:36.561558Z","caller":"traceutil/trace.go:171","msg":"trace[1647159286] transaction","detail":"{read_only:false; response_revision:27221; number_of_response:1; }","duration":"205.917344ms","start":"2026-10-09T11:51:36.355602Z","end":"2026-10-09T11:51:36.56152Z","steps":["trace[1647159286] 'process raft request' (duration: 59.876812ms)","trace[1647159286] 'compare' (duration: 145.532802ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:36.561411Z","caller":"traceutil/trace.go:171","msg":"trace[2011896441] linearizableReadLoop","detail":"{readStateIndex:28126; appliedIndex:28125; }","duration":"159.973416ms","start":"2026-10-09T11:51:36.401421Z","end":"2026-10-09T11:51:36.561394Z","steps":["trace[2011896441] 'read index received' (duration: 14.026235ms)","trace[2011896441] 'applied index is now lower than readState.Index' (duration: 145.945881ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T11:51:36.562257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.828242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T11:51:36.562349Z","caller":"traceutil/trace.go:171","msg":"trace[1923860077] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:27221; }","duration":"160.954025ms","start":"2026-10-09T11:51:36.401374Z","end":"2026-10-09T11:51:36.562328Z","steps":["trace[1923860077] 'agreement among raft nodes before linearized reading' (duration: 160.599888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T11:51:36.844435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.127943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T11:51:36.84539Z","caller":"traceutil/trace.go:171","msg":"trace[1780304537] transaction","detail":"{read_only:false; response_revision:27222; number_of_response:1; }","duration":"183.190975ms","start":"2026-10-09T11:51:36.661886Z","end":"2026-10-09T11:51:36.845077Z","steps":["trace[1780304537] 'process raft request' (duration: 45.248886ms)","trace[1780304537] 'compare' (duration: 136.777477ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T11:51:45.907536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24434} {"level":"info","ts":"2026-10-09T11:51:46.063993Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24434,"took":"152.121368ms","hash":1185634444,"current-db-size-bytes":58707968,"current-db-size":"59 MB","current-db-size-in-use-bytes":36290560,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-09T11:51:46.064063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1185634444,"revision":24434,"compact-revision":21573} {"level":"info","ts":"2026-10-09T11:51:59.085544Z","caller":"traceutil/trace.go:171","msg":"trace[296660706] transaction","detail":"{read_only:false; response_revision:27531; number_of_response:1; }","duration":"114.713299ms","start":"2026-10-09T11:51:58.970782Z","end":"2026-10-09T11:51:59.085495Z","steps":["trace[296660706] 'process raft request' (duration: 114.420774ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T11:54:42.417925Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b512f7d8ac93be25","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T11:54:42.424077Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-09T11:54:42.424219Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-09T11:56:45.917349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27289} {"level":"info","ts":"2026-10-09T11:56:46.059655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27289,"took":"138.094342ms","hash":2089812642,"current-db-size-bytes":58707968,"current-db-size":"59 MB","current-db-size-in-use-bytes":37908480,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-09T11:56:46.059742Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2089812642,"revision":27289,"compact-revision":24434} {"level":"info","ts":"2026-10-09T12:01:45.925542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30170} {"level":"info","ts":"2026-10-09T12:01:46.076011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30170,"took":"146.594295ms","hash":3472166596,"current-db-size-bytes":58707968,"current-db-size":"59 MB","current-db-size-in-use-bytes":32575488,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T12:01:46.076101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3472166596,"revision":30170,"compact-revision":27289} {"level":"info","ts":"2026-10-09T12:06:45.933015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32522} {"level":"info","ts":"2026-10-09T12:06:46.103134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32522,"took":"165.262998ms","hash":1159075987,"current-db-size-bytes":59752448,"current-db-size":"60 MB","current-db-size-in-use-bytes":30973952,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T12:06:46.103252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1159075987,"revision":32522,"compact-revision":30170} {"level":"info","ts":"2026-10-09T12:11:45.941024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34779} {"level":"info","ts":"2026-10-09T12:11:46.078897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34779,"took":"126.375574ms","hash":45071,"current-db-size-bytes":59752448,"current-db-size":"60 MB","current-db-size-in-use-bytes":31547392,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T12:11:46.078968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":45071,"revision":34779,"compact-revision":32522} {"level":"info","ts":"2026-10-09T12:15:27.849081Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b512f7d8ac93be25","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T12:15:27.854083Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-09T12:15:27.85414Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-09T12:16:45.948716Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37080} {"level":"info","ts":"2026-10-09T12:16:46.084099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37080,"took":"131.261162ms","hash":3660169528,"current-db-size-bytes":60600320,"current-db-size":"61 MB","current-db-size-in-use-bytes":33214464,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T12:16:46.084174Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3660169528,"revision":37080,"compact-revision":34779} {"level":"info","ts":"2026-10-09T12:21:45.956927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39352} {"level":"info","ts":"2026-10-09T12:21:46.092113Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39352,"took":"130.697071ms","hash":2013438182,"current-db-size-bytes":61030400,"current-db-size":"61 MB","current-db-size-in-use-bytes":30400512,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T12:21:46.092184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2013438182,"revision":39352,"compact-revision":37080} {"level":"info","ts":"2026-10-09T12:26:45.966864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41586} {"level":"info","ts":"2026-10-09T12:26:46.100099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41586,"took":"129.304865ms","hash":4191563338,"current-db-size-bytes":61030400,"current-db-size":"61 MB","current-db-size-in-use-bytes":29151232,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T12:26:46.100174Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4191563338,"revision":41586,"compact-revision":39352} {"level":"info","ts":"2026-10-09T12:31:45.976957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43810} {"level":"info","ts":"2026-10-09T12:31:46.096875Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43810,"took":"115.901211ms","hash":285340439,"current-db-size-bytes":61030400,"current-db-size":"61 MB","current-db-size-in-use-bytes":28930048,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T12:31:46.096946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":285340439,"revision":43810,"compact-revision":41586}