{"level":"warn","ts":"2026-10-26T01:00:55.6946Z","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-26T01:00:55.694732Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.84: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.84:2380","--initial-cluster=instance=https://162.253.55.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.84: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-26T01:00:55.694817Z","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-26T01:00:55.694832Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-10-26T01:00:55.694859Z","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-26T01:00:55.695697Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"]} {"level":"info","ts":"2026-10-26T01:00:55.695804Z","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.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.84: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-26T01:00:55.698861Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.781367ms"} {"level":"info","ts":"2026-10-26T01:00:55.706464Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"} {"level":"info","ts":"2026-10-26T01:00:55.70653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T01:00:55.706548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"} {"level":"info","ts":"2026-10-26T01:00:55.706561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca4c710a8d5349d7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T01:00:55.706569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"} {"level":"info","ts":"2026-10-26T01:00:55.706632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"warn","ts":"2026-10-26T01:00:55.714473Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T01:00:55.716946Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T01:00:55.718664Z","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-26T01:00:55.720991Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca4c710a8d5349d7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T01:00:55.721757Z","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-26T01:00:55.721908Z","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-26T01:00:55.721926Z","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-26T01:00:55.723989Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca4c710a8d5349d7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T01:00:55.724853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"info","ts":"2026-10-26T01:00:55.725198Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","added-peer-id":"ca4c710a8d5349d7","added-peer-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-10-26T01:00:55.727156Z","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-26T01:00:55.727306Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-10-26T01:00:55.727374Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-10-26T01:00:55.727621Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca4c710a8d5349d7","initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T01:00:55.727703Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T01:00:56.107054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T01:00:56.107129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T01:00:56.107158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"} {"level":"info","ts":"2026-10-26T01:00:56.107184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"} {"level":"info","ts":"2026-10-26T01:00:56.107199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-10-26T01:00:56.107221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"} {"level":"info","ts":"2026-10-26T01:00:56.107239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-10-26T01:00:56.108884Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca4c710a8d5349d7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.84:2379]}","request-path":"/0/members/ca4c710a8d5349d7/attributes","cluster-id":"8e28a51debf1c8ab","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T01:00:56.108894Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T01:00:56.109027Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:00:56.109155Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T01:00:56.109786Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T01:00:56.109883Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T01:00:56.110606Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:00:56.110759Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:00:56.110811Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T01:00:56.111507Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"} {"level":"info","ts":"2026-10-26T01:00:56.113241Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T01:01:13.625646Z","caller":"traceutil/trace.go:171","msg":"trace[1002932288] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"308.78634ms","start":"2026-10-26T01:01:13.316826Z","end":"2026-10-26T01:01:13.625612Z","steps":["trace[1002932288] 'process raft request' (duration: 308.43281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:13.626333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:13.316805Z","time spent":"308.942864ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:01:44.254751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.830161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:01:44.254948Z","caller":"traceutil/trace.go:171","msg":"trace[157187158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"121.117129ms","start":"2026-10-26T01:01:44.133797Z","end":"2026-10-26T01:01:44.254914Z","steps":["trace[157187158] 'range keys from in-memory index tree' (duration: 120.661476ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:01:51.468935Z","caller":"traceutil/trace.go:171","msg":"trace[1213455626] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"307.566549ms","start":"2026-10-26T01:01:51.16133Z","end":"2026-10-26T01:01:51.468897Z","steps":["trace[1213455626] 'process raft request' (duration: 306.89828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:51.46917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:51.161309Z","time spent":"307.751493ms","remote":"127.0.0.1:58690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:01:51.705859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.477466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7rrv8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-26T01:01:51.705983Z","caller":"traceutil/trace.go:171","msg":"trace[768326466] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7rrv8; range_end:; response_count:1; response_revision:659; }","duration":"163.650381ms","start":"2026-10-26T01:01:51.542302Z","end":"2026-10-26T01:01:51.705952Z","steps":["trace[768326466] 'range keys from in-memory index tree' (duration: 163.23892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:51.706111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.030482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:01:51.70625Z","caller":"traceutil/trace.go:171","msg":"trace[885487243] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"138.979972ms","start":"2026-10-26T01:01:51.567249Z","end":"2026-10-26T01:01:51.706229Z","steps":["trace[885487243] 'process raft request' (duration: 15.609871ms)","trace[885487243] 'compare' (duration: 122.839526ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:01:52.114927Z","caller":"traceutil/trace.go:171","msg":"trace[706774056] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"388.303466ms","start":"2026-10-26T01:01:51.726576Z","end":"2026-10-26T01:01:52.11488Z","steps":["trace[706774056] 'process raft request' (duration: 360.349395ms)","trace[706774056] 'compare' (duration: 27.706434ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:01:52.115132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:51.726516Z","time spent":"388.49986ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:01:52.114758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.435518ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:01:52.11561Z","caller":"traceutil/trace.go:171","msg":"trace[1823264630] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:660; }","duration":"229.275861ms","start":"2026-10-26T01:01:51.886259Z","end":"2026-10-26T01:01:52.115535Z","steps":["trace[1823264630] 'range keys from in-memory index tree' (duration: 228.399737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:52.827998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320899011757481867,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T01:01:52.896067Z","caller":"traceutil/trace.go:171","msg":"trace[1914867587] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:690; }","duration":"568.981726ms","start":"2026-10-26T01:01:52.327028Z","end":"2026-10-26T01:01:52.89601Z","steps":["trace[1914867587] 'read index received' (duration: 531.168674ms)","trace[1914867587] 'applied index is now lower than readState.Index' (duration: 37.811482ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:01:52.896143Z","caller":"traceutil/trace.go:171","msg":"trace[450672030] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"371.280676ms","start":"2026-10-26T01:01:52.524809Z","end":"2026-10-26T01:01:52.89609Z","steps":["trace[450672030] 'process raft request' (duration: 371.127022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:52.896328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:52.524776Z","time spent":"371.456441ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:01:52.896274Z","caller":"traceutil/trace.go:171","msg":"trace[2129549426] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"725.652826ms","start":"2026-10-26T01:01:52.170549Z","end":"2026-10-26T01:01:52.896202Z","steps":["trace[2129549426] 'process raft request' (duration: 687.691509ms)","trace[2129549426] 'compare' (duration: 36.737603ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:01:52.896361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.329457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T01:01:52.896434Z","caller":"traceutil/trace.go:171","msg":"trace[122472620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"569.432009ms","start":"2026-10-26T01:01:52.326982Z","end":"2026-10-26T01:01:52.896414Z","steps":["trace[122472620] 'agreement among raft nodes before linearized reading' (duration: 569.212843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:52.89642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.549861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-26T01:01:52.89647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:52.326954Z","time spent":"569.507071ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T01:01:52.896515Z","caller":"traceutil/trace.go:171","msg":"trace[2014028283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"323.679353ms","start":"2026-10-26T01:01:52.572804Z","end":"2026-10-26T01:01:52.896483Z","steps":["trace[2014028283] 'agreement among raft nodes before linearized reading' (duration: 323.427066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:01:52.896559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:52.572791Z","time spent":"323.759596ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-26T01:01:52.896576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:01:52.170495Z","time spent":"725.926624ms","remote":"127.0.0.1:58690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:02:08.800365Z","caller":"traceutil/trace.go:171","msg":"trace[1570593719] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"119.822613ms","start":"2026-10-26T01:02:08.680514Z","end":"2026-10-26T01:02:08.800337Z","steps":["trace[1570593719] 'process raft request' (duration: 119.586716ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:02:08.916349Z","caller":"traceutil/trace.go:171","msg":"trace[883826159] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"228.577232ms","start":"2026-10-26T01:02:08.687742Z","end":"2026-10-26T01:02:08.916319Z","steps":["trace[883826159] 'process raft request' (duration: 227.143432ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:02:38.726027Z","caller":"traceutil/trace.go:171","msg":"trace[2108442099] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"142.467759ms","start":"2026-10-26T01:02:38.583506Z","end":"2026-10-26T01:02:38.725973Z","steps":["trace[2108442099] 'read index received' (duration: 142.200642ms)","trace[2108442099] 'applied index is now lower than readState.Index' (duration: 264.917µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:02:38.726271Z","caller":"traceutil/trace.go:171","msg":"trace[463527120] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"275.607981ms","start":"2026-10-26T01:02:38.450629Z","end":"2026-10-26T01:02:38.726237Z","steps":["trace[463527120] 'process raft request' (duration: 275.135468ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:02:38.726339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.784918ms","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-26T01:02:38.726379Z","caller":"traceutil/trace.go:171","msg":"trace[473109796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"142.909021ms","start":"2026-10-26T01:02:38.583459Z","end":"2026-10-26T01:02:38.726368Z","steps":["trace[473109796] 'agreement among raft nodes before linearized reading' (duration: 142.693935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:30.305176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.054758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:03:30.305323Z","caller":"traceutil/trace.go:171","msg":"trace[1760264325] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1990; }","duration":"172.225887ms","start":"2026-10-26T01:03:30.133068Z","end":"2026-10-26T01:03:30.305294Z","steps":["trace[1760264325] 'read index received' (duration: 96.822µs)","trace[1760264325] 'applied index is now lower than readState.Index' (duration: 172.127005ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:03:30.305342Z","caller":"traceutil/trace.go:171","msg":"trace[1600416193] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"357.856444ms","start":"2026-10-26T01:03:29.947464Z","end":"2026-10-26T01:03:30.30532Z","steps":["trace[1600416193] 'process raft request' (duration: 107.499487ms)","trace[1600416193] 'compare' (duration: 249.711779ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:03:30.30542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:29.947437Z","time spent":"357.944606ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:03:30.305567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.500805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1db7a856-583e-4800-b1b5-c5f244be723d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-26T01:03:30.305634Z","caller":"traceutil/trace.go:171","msg":"trace[1763335884] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1db7a856-583e-4800-b1b5-c5f244be723d; range_end:; response_count:1; response_revision:1914; }","duration":"172.625638ms","start":"2026-10-26T01:03:30.132994Z","end":"2026-10-26T01:03:30.30562Z","steps":["trace[1763335884] 'agreement among raft nodes before linearized reading' (duration: 172.407162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:30.692132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.806614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:03:30.692395Z","caller":"traceutil/trace.go:171","msg":"trace[950314586] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"351.010326ms","start":"2026-10-26T01:03:30.341362Z","end":"2026-10-26T01:03:30.692372Z","steps":["trace[950314586] 'process raft request' (duration: 350.894873ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:30.692417Z","caller":"traceutil/trace.go:171","msg":"trace[981318311] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"378.670096ms","start":"2026-10-26T01:03:30.313693Z","end":"2026-10-26T01:03:30.692363Z","steps":["trace[981318311] 'process raft request' (duration: 97.485222ms)","trace[981318311] 'compare' (duration: 280.579158ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:03:30.692536Z","caller":"traceutil/trace.go:171","msg":"trace[302252862] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"357.949507ms","start":"2026-10-26T01:03:30.334576Z","end":"2026-10-26T01:03:30.692526Z","steps":["trace[302252862] 'read index received' (duration: 76.614378ms)","trace[302252862] 'applied index is now lower than readState.Index' (duration: 281.333969ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:03:30.692747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:30.313666Z","time spent":"378.878542ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:03:30.692494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:30.341333Z","time spent":"351.126449ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:03:30.69294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.393009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T01:03:30.692975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.609994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"info","ts":"2026-10-26T01:03:30.69306Z","caller":"traceutil/trace.go:171","msg":"trace[894533215] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1917; }","duration":"386.620845ms","start":"2026-10-26T01:03:30.30641Z","end":"2026-10-26T01:03:30.693031Z","steps":["trace[894533215] 'agreement among raft nodes before linearized reading' (duration: 386.348157ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:30.69307Z","caller":"traceutil/trace.go:171","msg":"trace[2042028713] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1917; }","duration":"282.760208ms","start":"2026-10-26T01:03:30.41028Z","end":"2026-10-26T01:03:30.693041Z","steps":["trace[2042028713] 'agreement among raft nodes before linearized reading' (duration: 282.46202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:30.693146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:03:30.306391Z","time spent":"386.741499ms","remote":"127.0.0.1:35056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-10-26T01:03:39.269333Z","caller":"traceutil/trace.go:171","msg":"trace[244193555] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"114.444812ms","start":"2026-10-26T01:03:39.154855Z","end":"2026-10-26T01:03:39.2693Z","steps":["trace[244193555] 'process raft request' (duration: 113.770504ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:39.328139Z","caller":"traceutil/trace.go:171","msg":"trace[437051126] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"173.01789ms","start":"2026-10-26T01:03:39.155059Z","end":"2026-10-26T01:03:39.328077Z","steps":["trace[437051126] 'process raft request' (duration: 172.735172ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:39.328406Z","caller":"traceutil/trace.go:171","msg":"trace[1108578016] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"160.002201ms","start":"2026-10-26T01:03:39.16836Z","end":"2026-10-26T01:03:39.328362Z","steps":["trace[1108578016] 'process raft request' (duration: 159.662222ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:43.169179Z","caller":"traceutil/trace.go:171","msg":"trace[1539794169] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"115.372077ms","start":"2026-10-26T01:03:43.053721Z","end":"2026-10-26T01:03:43.169094Z","steps":["trace[1539794169] 'process raft request' (duration: 113.949308ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:43.681161Z","caller":"traceutil/trace.go:171","msg":"trace[77160030] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"142.408154ms","start":"2026-10-26T01:03:43.538693Z","end":"2026-10-26T01:03:43.681101Z","steps":["trace[77160030] 'process raft request' (duration: 142.096296ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:03:43.69776Z","caller":"traceutil/trace.go:171","msg":"trace[2145599378] linearizableReadLoop","detail":"{readStateIndex:2104; appliedIndex:2102; }","duration":"106.656707ms","start":"2026-10-26T01:03:43.591068Z","end":"2026-10-26T01:03:43.697725Z","steps":["trace[2145599378] 'read index received' (duration: 89.684888ms)","trace[2145599378] 'applied index is now lower than readState.Index' (duration: 16.970349ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:03:43.697853Z","caller":"traceutil/trace.go:171","msg":"trace[2069775644] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"138.236769ms","start":"2026-10-26T01:03:43.559582Z","end":"2026-10-26T01:03:43.697819Z","steps":["trace[2069775644] 'process raft request' (duration: 136.701467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:03:43.698037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.953345ms","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-26T01:03:43.698138Z","caller":"traceutil/trace.go:171","msg":"trace[14856522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2024; }","duration":"107.097319ms","start":"2026-10-26T01:03:43.591024Z","end":"2026-10-26T01:03:43.698121Z","steps":["trace[14856522] 'agreement among raft nodes before linearized reading' (duration: 106.812501ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:04:15.902841Z","caller":"traceutil/trace.go:171","msg":"trace[1796188699] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"120.443597ms","start":"2026-10-26T01:04:15.782369Z","end":"2026-10-26T01:04:15.902813Z","steps":["trace[1796188699] 'process raft request' (duration: 120.19503ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:04:21.387738Z","caller":"traceutil/trace.go:171","msg":"trace[277137959] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"162.695195ms","start":"2026-10-26T01:04:21.225013Z","end":"2026-10-26T01:04:21.387709Z","steps":["trace[277137959] 'process raft request' (duration: 162.459589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:21.932803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.831536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:04:21.932974Z","caller":"traceutil/trace.go:171","msg":"trace[1558713552] linearizableReadLoop","detail":"{readStateIndex:2330; appliedIndex:2329; }","duration":"341.856564ms","start":"2026-10-26T01:04:21.591098Z","end":"2026-10-26T01:04:21.932955Z","steps":["trace[1558713552] 'read index received' (duration: 80.680188ms)","trace[1558713552] 'applied index is now lower than readState.Index' (duration: 261.174766ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:04:21.933188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.0778ms","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-26T01:04:21.933253Z","caller":"traceutil/trace.go:171","msg":"trace[875527236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2241; }","duration":"342.157793ms","start":"2026-10-26T01:04:21.591069Z","end":"2026-10-26T01:04:21.933227Z","steps":["trace[875527236] 'agreement among raft nodes before linearized reading' (duration: 341.935856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:21.93329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:04:21.591058Z","time spent":"342.222654ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T01:04:21.933382Z","caller":"traceutil/trace.go:171","msg":"trace[819720417] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"371.634447ms","start":"2026-10-26T01:04:21.561496Z","end":"2026-10-26T01:04:21.93313Z","steps":["trace[819720417] 'process raft request' (duration: 110.309387ms)","trace[819720417] 'compare' (duration: 260.61891ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:04:21.933899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:04:21.561474Z","time spent":"372.282734ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:04:22.137866Z","caller":"traceutil/trace.go:171","msg":"trace[1635861672] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2330; }","duration":"175.857959ms","start":"2026-10-26T01:04:21.961981Z","end":"2026-10-26T01:04:22.137839Z","steps":["trace[1635861672] 'read index received' (duration: 129.035815ms)","trace[1635861672] 'applied index is now lower than readState.Index' (duration: 46.821224ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:04:22.13793Z","caller":"traceutil/trace.go:171","msg":"trace[699298696] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"199.313616ms","start":"2026-10-26T01:04:21.93855Z","end":"2026-10-26T01:04:22.137864Z","steps":["trace[699298696] 'process raft request' (duration: 152.446612ms)","trace[699298696] 'compare' (duration: 46.642048ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:04:22.138038Z","caller":"traceutil/trace.go:171","msg":"trace[269890407] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"196.009116ms","start":"2026-10-26T01:04:21.942015Z","end":"2026-10-26T01:04:22.138024Z","steps":["trace[269890407] 'process raft request' (duration: 195.767829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:22.138455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.157909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-26T01:04:22.138544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.491126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T01:04:22.138633Z","caller":"traceutil/trace.go:171","msg":"trace[969014087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2243; }","duration":"175.329894ms","start":"2026-10-26T01:04:21.963212Z","end":"2026-10-26T01:04:22.138542Z","steps":["trace[969014087] 'agreement among raft nodes before linearized reading' (duration: 175.031906ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:04:22.138645Z","caller":"traceutil/trace.go:171","msg":"trace[238865503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2243; }","duration":"176.660541ms","start":"2026-10-26T01:04:21.961966Z","end":"2026-10-26T01:04:22.138627Z","steps":["trace[238865503] 'agreement among raft nodes before linearized reading' (duration: 176.369133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:04:22.138853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.920705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T01:04:22.138973Z","caller":"traceutil/trace.go:171","msg":"trace[1865699167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2243; }","duration":"174.09207ms","start":"2026-10-26T01:04:21.964851Z","end":"2026-10-26T01:04:22.138943Z","steps":["trace[1865699167] 'agreement among raft nodes before linearized reading' (duration: 173.489784ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:05:17.971676Z","caller":"traceutil/trace.go:171","msg":"trace[1131188163] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"172.421349ms","start":"2026-10-26T01:05:17.799226Z","end":"2026-10-26T01:05:17.971647Z","steps":["trace[1131188163] 'process raft request' (duration: 172.239424ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:05:17.995604Z","caller":"traceutil/trace.go:171","msg":"trace[268322711] linearizableReadLoop","detail":"{readStateIndex:2820; appliedIndex:2818; }","duration":"119.980368ms","start":"2026-10-26T01:05:17.875571Z","end":"2026-10-26T01:05:17.995552Z","steps":["trace[268322711] 'read index received' (duration: 95.906466ms)","trace[268322711] 'applied index is now lower than readState.Index' (duration: 24.072182ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:05:17.996267Z","caller":"traceutil/trace.go:171","msg":"trace[2042587915] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"193.998102ms","start":"2026-10-26T01:05:17.802215Z","end":"2026-10-26T01:05:17.996214Z","steps":["trace[2042587915] 'process raft request' (duration: 193.095267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:05:17.996652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.057737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-26T01:05:17.996761Z","caller":"traceutil/trace.go:171","msg":"trace[788136384] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2712; }","duration":"121.238042ms","start":"2026-10-26T01:05:17.875494Z","end":"2026-10-26T01:05:17.996732Z","steps":["trace[788136384] 'agreement among raft nodes before linearized reading' (duration: 120.904863ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:05:50.281524Z","caller":"traceutil/trace.go:171","msg":"trace[978680248] transaction","detail":"{read_only:false; response_revision:2907; number_of_response:1; }","duration":"150.862407ms","start":"2026-10-26T01:05:50.130633Z","end":"2026-10-26T01:05:50.281495Z","steps":["trace[978680248] 'process raft request' (duration: 150.666332ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:05:50.282149Z","caller":"traceutil/trace.go:171","msg":"trace[273226928] linearizableReadLoop","detail":"{readStateIndex:3022; appliedIndex:3022; }","duration":"147.338041ms","start":"2026-10-26T01:05:50.134795Z","end":"2026-10-26T01:05:50.282133Z","steps":["trace[273226928] 'read index received' (duration: 147.332281ms)","trace[273226928] 'applied index is now lower than readState.Index' (duration: 4.36µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:05:50.282363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.559596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:05:50.282538Z","caller":"traceutil/trace.go:171","msg":"trace[1705693323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2907; }","duration":"147.789263ms","start":"2026-10-26T01:05:50.134735Z","end":"2026-10-26T01:05:50.282524Z","steps":["trace[1705693323] 'agreement among raft nodes before linearized reading' (duration: 147.577257ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:05:50.623032Z","caller":"traceutil/trace.go:171","msg":"trace[1347985376] transaction","detail":"{read_only:false; response_revision:2909; number_of_response:1; }","duration":"146.639562ms","start":"2026-10-26T01:05:50.476363Z","end":"2026-10-26T01:05:50.623003Z","steps":["trace[1347985376] 'process raft request' (duration: 146.473937ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:05:50.636975Z","caller":"traceutil/trace.go:171","msg":"trace[941147639] transaction","detail":"{read_only:false; response_revision:2910; number_of_response:1; }","duration":"116.579833ms","start":"2026-10-26T01:05:50.520374Z","end":"2026-10-26T01:05:50.636954Z","steps":["trace[941147639] 'process raft request' (duration: 116.45157ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:08:40.401038Z","caller":"traceutil/trace.go:171","msg":"trace[1809865582] transaction","detail":"{read_only:false; response_revision:3930; number_of_response:1; }","duration":"105.163005ms","start":"2026-10-26T01:08:40.295782Z","end":"2026-10-26T01:08:40.400945Z","steps":["trace[1809865582] 'process raft request' (duration: 98.362586ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:08:40.401483Z","caller":"traceutil/trace.go:171","msg":"trace[50992341] transaction","detail":"{read_only:false; response_revision:3931; number_of_response:1; }","duration":"102.781649ms","start":"2026-10-26T01:08:40.298662Z","end":"2026-10-26T01:08:40.401444Z","steps":["trace[50992341] 'process raft request' (duration: 102.574883ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:09:13.803203Z","caller":"traceutil/trace.go:171","msg":"trace[1751202602] transaction","detail":"{read_only:false; response_revision:4178; number_of_response:1; }","duration":"100.954498ms","start":"2026-10-26T01:09:13.702212Z","end":"2026-10-26T01:09:13.803166Z","steps":["trace[1751202602] 'process raft request' (duration: 100.815234ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:37.76986Z","caller":"traceutil/trace.go:171","msg":"trace[420214793] linearizableReadLoop","detail":"{readStateIndex:5286; appliedIndex:5282; }","duration":"217.861314ms","start":"2026-10-26T01:10:37.551968Z","end":"2026-10-26T01:10:37.76983Z","steps":["trace[420214793] 'read index received' (duration: 18.667594ms)","trace[420214793] 'applied index is now lower than readState.Index' (duration: 199.19203ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:10:37.769929Z","caller":"traceutil/trace.go:171","msg":"trace[568927060] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"221.235488ms","start":"2026-10-26T01:10:37.54866Z","end":"2026-10-26T01:10:37.769895Z","steps":["trace[568927060] 'process raft request' (duration: 220.927559ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:37.769986Z","caller":"traceutil/trace.go:171","msg":"trace[97452064] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"221.123954ms","start":"2026-10-26T01:10:37.548814Z","end":"2026-10-26T01:10:37.769938Z","steps":["trace[97452064] 'process raft request' (duration: 220.916268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:10:37.770766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.77758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-26T01:10:37.77082Z","caller":"traceutil/trace.go:171","msg":"trace[945706515] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5087; }","duration":"218.881933ms","start":"2026-10-26T01:10:37.551925Z","end":"2026-10-26T01:10:37.770807Z","steps":["trace[945706515] 'agreement among raft nodes before linearized reading' (duration: 218.068281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:10:37.770952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.960877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:8 size:50573"} {"level":"info","ts":"2026-10-26T01:10:37.771038Z","caller":"traceutil/trace.go:171","msg":"trace[2120896858] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:8; response_revision:5087; }","duration":"218.117542ms","start":"2026-10-26T01:10:37.552901Z","end":"2026-10-26T01:10:37.771018Z","steps":["trace[2120896858] 'agreement among raft nodes before linearized reading' (duration: 217.412492ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:42.955804Z","caller":"traceutil/trace.go:171","msg":"trace[991313895] linearizableReadLoop","detail":"{readStateIndex:5426; appliedIndex:5425; }","duration":"117.744265ms","start":"2026-10-26T01:10:42.838022Z","end":"2026-10-26T01:10:42.955766Z","steps":["trace[991313895] 'read index received' (duration: 89.98394ms)","trace[991313895] 'applied index is now lower than readState.Index' (duration: 27.757795ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:10:42.955882Z","caller":"traceutil/trace.go:171","msg":"trace[1679914857] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"119.281858ms","start":"2026-10-26T01:10:42.836538Z","end":"2026-10-26T01:10:42.955819Z","steps":["trace[1679914857] 'process raft request' (duration: 91.519953ms)","trace[1679914857] 'compare' (duration: 27.541829ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:10:42.95608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.025393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-26T01:10:42.956126Z","caller":"traceutil/trace.go:171","msg":"trace[1847221691] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5224; }","duration":"118.126646ms","start":"2026-10-26T01:10:42.837984Z","end":"2026-10-26T01:10:42.956111Z","steps":["trace[1847221691] 'agreement among raft nodes before linearized reading' (duration: 117.91536ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:42.971418Z","caller":"traceutil/trace.go:171","msg":"trace[1248601644] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"100.764787ms","start":"2026-10-26T01:10:42.870623Z","end":"2026-10-26T01:10:42.971388Z","steps":["trace[1248601644] 'process raft request' (duration: 100.597192ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:43.087238Z","caller":"traceutil/trace.go:171","msg":"trace[1030051898] linearizableReadLoop","detail":"{readStateIndex:5429; appliedIndex:5428; }","duration":"105.94805ms","start":"2026-10-26T01:10:42.981261Z","end":"2026-10-26T01:10:43.087209Z","steps":["trace[1030051898] 'read index received' (duration: 10.447788ms)","trace[1030051898] 'applied index is now lower than readState.Index' (duration: 95.498962ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:10:43.087383Z","caller":"traceutil/trace.go:171","msg":"trace[725203585] transaction","detail":"{read_only:false; response_revision:5227; number_of_response:1; }","duration":"108.043178ms","start":"2026-10-26T01:10:42.979301Z","end":"2026-10-26T01:10:43.087344Z","steps":["trace[725203585] 'process raft request' (duration: 103.955925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:10:43.087727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.85508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-26T01:10:43.087838Z","caller":"traceutil/trace.go:171","msg":"trace[455790344] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5227; }","duration":"105.013565ms","start":"2026-10-26T01:10:42.982797Z","end":"2026-10-26T01:10:43.087811Z","steps":["trace[455790344] 'agreement among raft nodes before linearized reading' (duration: 104.687516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:10:43.087855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.562097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11527"} {"level":"info","ts":"2026-10-26T01:10:43.088029Z","caller":"traceutil/trace.go:171","msg":"trace[25596831] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5227; }","duration":"106.724611ms","start":"2026-10-26T01:10:42.981226Z","end":"2026-10-26T01:10:43.087951Z","steps":["trace[25596831] 'agreement among raft nodes before linearized reading' (duration: 106.334341ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:10:46.951353Z","caller":"traceutil/trace.go:171","msg":"trace[1123379134] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"316.295758ms","start":"2026-10-26T01:10:46.635015Z","end":"2026-10-26T01:10:46.95131Z","steps":["trace[1123379134] 'process raft request' (duration: 306.543169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:10:46.951582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:10:46.63499Z","time spent":"316.498984ms","remote":"127.0.0.1:58748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":879,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T01:10:46.963985Z","caller":"traceutil/trace.go:171","msg":"trace[1304722245] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"327.090415ms","start":"2026-10-26T01:10:46.636863Z","end":"2026-10-26T01:10:46.963953Z","steps":["trace[1304722245] 'process raft request' (duration: 326.941461ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:10:46.964236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:10:46.636844Z","time spent":"327.25306ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3705,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:10:56.164682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2939} {"level":"info","ts":"2026-10-26T01:10:56.247355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2939,"took":"80.458448ms","hash":753767965,"current-db-size-bytes":54513664,"current-db-size":"54 MB","current-db-size-in-use-bytes":30814208,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T01:10:56.247431Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":753767965,"revision":2939,"compact-revision":-1} {"level":"info","ts":"2026-10-26T01:11:01.886633Z","caller":"traceutil/trace.go:171","msg":"trace[1294020882] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"102.575118ms","start":"2026-10-26T01:11:01.783999Z","end":"2026-10-26T01:11:01.886574Z","steps":["trace[1294020882] 'process raft request' (duration: 102.338681ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:02.02867Z","caller":"traceutil/trace.go:171","msg":"trace[1861384961] transaction","detail":"{read_only:false; response_revision:5654; number_of_response:1; }","duration":"135.420012ms","start":"2026-10-26T01:11:01.893219Z","end":"2026-10-26T01:11:02.028639Z","steps":["trace[1861384961] 'process raft request' (duration: 133.18152ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:02.028845Z","caller":"traceutil/trace.go:171","msg":"trace[1406277309] linearizableReadLoop","detail":"{readStateIndex:5870; appliedIndex:5868; }","duration":"135.540446ms","start":"2026-10-26T01:11:01.893279Z","end":"2026-10-26T01:11:02.028819Z","steps":["trace[1406277309] 'read index received' (duration: 21.817441ms)","trace[1406277309] 'applied index is now lower than readState.Index' (duration: 113.721095ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:11:02.029093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.789062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-nb8k5\" ","response":"range_response_count:1 size:5851"} {"level":"info","ts":"2026-10-26T01:11:02.029129Z","caller":"traceutil/trace.go:171","msg":"trace[138588752] range","detail":"{range_begin:/registry/pods/monitoring/vector-nb8k5; range_end:; response_count:1; response_revision:5656; }","duration":"135.901976ms","start":"2026-10-26T01:11:01.893218Z","end":"2026-10-26T01:11:02.02912Z","steps":["trace[138588752] 'agreement among raft nodes before linearized reading' (duration: 135.705231ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:02.029163Z","caller":"traceutil/trace.go:171","msg":"trace[902034970] transaction","detail":"{read_only:false; response_revision:5655; number_of_response:1; }","duration":"135.71445ms","start":"2026-10-26T01:11:01.893417Z","end":"2026-10-26T01:11:02.029131Z","steps":["trace[902034970] 'process raft request' (duration: 135.157875ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:02.029277Z","caller":"traceutil/trace.go:171","msg":"trace[301502939] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"135.828833ms","start":"2026-10-26T01:11:01.893413Z","end":"2026-10-26T01:11:02.029241Z","steps":["trace[301502939] 'process raft request' (duration: 135.33231ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:10.333959Z","caller":"traceutil/trace.go:171","msg":"trace[42256135] linearizableReadLoop","detail":"{readStateIndex:6047; appliedIndex:6046; }","duration":"189.192999ms","start":"2026-10-26T01:11:10.144734Z","end":"2026-10-26T01:11:10.333927Z","steps":["trace[42256135] 'read index received' (duration: 158.252855ms)","trace[42256135] 'applied index is now lower than readState.Index' (duration: 30.938384ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:11:10.334222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.478217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f912907e-8267-4bd8-93a4-b2a5da70e1ce\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-26T01:11:10.334262Z","caller":"traceutil/trace.go:171","msg":"trace[1558440141] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f912907e-8267-4bd8-93a4-b2a5da70e1ce; range_end:; response_count:1; response_revision:5826; }","duration":"189.581779ms","start":"2026-10-26T01:11:10.144667Z","end":"2026-10-26T01:11:10.334249Z","steps":["trace[1558440141] 'agreement among raft nodes before linearized reading' (duration: 189.372453ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:10.334261Z","caller":"traceutil/trace.go:171","msg":"trace[1965432223] transaction","detail":"{read_only:false; response_revision:5826; number_of_response:1; }","duration":"227.65072ms","start":"2026-10-26T01:11:10.106563Z","end":"2026-10-26T01:11:10.334213Z","steps":["trace[1965432223] 'process raft request' (duration: 196.408748ms)","trace[1965432223] 'compare' (duration: 30.82286ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:11:10.365798Z","caller":"traceutil/trace.go:171","msg":"trace[1852485177] transaction","detail":"{read_only:false; response_revision:5827; number_of_response:1; }","duration":"109.990064ms","start":"2026-10-26T01:11:10.255779Z","end":"2026-10-26T01:11:10.365769Z","steps":["trace[1852485177] 'process raft request' (duration: 109.809569ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:10.704287Z","caller":"traceutil/trace.go:171","msg":"trace[829694785] linearizableReadLoop","detail":"{readStateIndex:6051; appliedIndex:6050; }","duration":"129.555123ms","start":"2026-10-26T01:11:10.574691Z","end":"2026-10-26T01:11:10.704247Z","steps":["trace[829694785] 'read index received' (duration: 36.115886ms)","trace[829694785] 'applied index is now lower than readState.Index' (duration: 93.437637ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:11:10.704443Z","caller":"traceutil/trace.go:171","msg":"trace[296541943] transaction","detail":"{read_only:false; response_revision:5830; number_of_response:1; }","duration":"131.414254ms","start":"2026-10-26T01:11:10.573012Z","end":"2026-10-26T01:11:10.704426Z","steps":["trace[296541943] 'process raft request' (duration: 37.833383ms)","trace[296541943] 'compare' (duration: 93.196101ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:11:10.704612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.896693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-26T01:11:10.704701Z","caller":"traceutil/trace.go:171","msg":"trace[940376331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5830; }","duration":"130.037207ms","start":"2026-10-26T01:11:10.574646Z","end":"2026-10-26T01:11:10.704683Z","steps":["trace[940376331] 'agreement among raft nodes before linearized reading' (duration: 129.780799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:11:10.767982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.892091ms","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-26T01:11:10.768063Z","caller":"traceutil/trace.go:171","msg":"trace[13952148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5831; }","duration":"193.003593ms","start":"2026-10-26T01:11:10.575043Z","end":"2026-10-26T01:11:10.768046Z","steps":["trace[13952148] 'agreement among raft nodes before linearized reading' (duration: 192.764377ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:10.768327Z","caller":"traceutil/trace.go:171","msg":"trace[32246057] transaction","detail":"{read_only:false; response_revision:5831; number_of_response:1; }","duration":"191.4075ms","start":"2026-10-26T01:11:10.576909Z","end":"2026-10-26T01:11:10.768316Z","steps":["trace[32246057] 'process raft request' (duration: 190.783033ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:11:22.506277Z","caller":"traceutil/trace.go:171","msg":"trace[1343749349] transaction","detail":"{read_only:false; response_revision:5985; number_of_response:1; }","duration":"101.782708ms","start":"2026-10-26T01:11:22.404467Z","end":"2026-10-26T01:11:22.50625Z","steps":["trace[1343749349] 'process raft request' (duration: 101.623873ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:15:56.17397Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5580} {"level":"info","ts":"2026-10-26T01:15:56.265867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5580,"took":"89.905755ms","hash":2880841231,"current-db-size-bytes":54513664,"current-db-size":"54 MB","current-db-size-in-use-bytes":29306880,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T01:15:56.26595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2880841231,"revision":5580,"compact-revision":2939} {"level":"info","ts":"2026-10-26T01:16:24.187382Z","caller":"traceutil/trace.go:171","msg":"trace[1895545305] transaction","detail":"{read_only:false; response_revision:8160; number_of_response:1; }","duration":"107.73349ms","start":"2026-10-26T01:16:24.079629Z","end":"2026-10-26T01:16:24.187362Z","steps":["trace[1895545305] 'process raft request' (duration: 107.608056ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:24.208372Z","caller":"traceutil/trace.go:171","msg":"trace[316724480] transaction","detail":"{read_only:false; response_revision:8161; number_of_response:1; }","duration":"127.676583ms","start":"2026-10-26T01:16:24.08067Z","end":"2026-10-26T01:16:24.208347Z","steps":["trace[316724480] 'process raft request' (duration: 127.301103ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:24.208665Z","caller":"traceutil/trace.go:171","msg":"trace[1951794202] transaction","detail":"{read_only:false; response_revision:8162; number_of_response:1; }","duration":"121.848402ms","start":"2026-10-26T01:16:24.086777Z","end":"2026-10-26T01:16:24.208626Z","steps":["trace[1951794202] 'process raft request' (duration: 121.320717ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:24.35683Z","caller":"traceutil/trace.go:171","msg":"trace[1129309309] transaction","detail":"{read_only:false; response_revision:8165; number_of_response:1; }","duration":"137.772673ms","start":"2026-10-26T01:16:24.219035Z","end":"2026-10-26T01:16:24.356808Z","steps":["trace[1129309309] 'process raft request' (duration: 137.525656ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:24.357001Z","caller":"traceutil/trace.go:171","msg":"trace[1376693588] transaction","detail":"{read_only:false; response_revision:8166; number_of_response:1; }","duration":"133.283069ms","start":"2026-10-26T01:16:24.223705Z","end":"2026-10-26T01:16:24.356988Z","steps":["trace[1376693588] 'process raft request' (duration: 133.022392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:24.585212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.093841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-26T01:16:24.585303Z","caller":"traceutil/trace.go:171","msg":"trace[1393122348] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8166; }","duration":"112.210354ms","start":"2026-10-26T01:16:24.473067Z","end":"2026-10-26T01:16:24.585278Z","steps":["trace[1393122348] 'range keys from in-memory index tree' (duration: 111.957578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:24.585436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.407747ms","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-26T01:16:24.585553Z","caller":"traceutil/trace.go:171","msg":"trace[1539904945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8166; }","duration":"122.543441ms","start":"2026-10-26T01:16:24.462967Z","end":"2026-10-26T01:16:24.58551Z","steps":["trace[1539904945] 'range keys from in-memory index tree' (duration: 122.190941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:24.585661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.321538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:6011"} {"level":"info","ts":"2026-10-26T01:16:24.585789Z","caller":"traceutil/trace.go:171","msg":"trace[367312360] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8166; }","duration":"125.438511ms","start":"2026-10-26T01:16:24.460282Z","end":"2026-10-26T01:16:24.58572Z","steps":["trace[367312360] 'range keys from in-memory index tree' (duration: 125.070491ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:25.899344Z","caller":"traceutil/trace.go:171","msg":"trace[1487885751] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"164.777993ms","start":"2026-10-26T01:16:25.734537Z","end":"2026-10-26T01:16:25.899315Z","steps":["trace[1487885751] 'process raft request' (duration: 80.513014ms)","trace[1487885751] 'compare' (duration: 84.153826ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:27.036306Z","caller":"traceutil/trace.go:171","msg":"trace[634584981] linearizableReadLoop","detail":"{readStateIndex:8483; appliedIndex:8482; }","duration":"102.179725ms","start":"2026-10-26T01:16:26.934099Z","end":"2026-10-26T01:16:27.036279Z","steps":["trace[634584981] 'read index received' (duration: 101.97412ms)","trace[634584981] 'applied index is now lower than readState.Index' (duration: 204.195µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:16:27.036445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.33048ms","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-26T01:16:27.036487Z","caller":"traceutil/trace.go:171","msg":"trace[510658336] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8187; }","duration":"102.436143ms","start":"2026-10-26T01:16:26.934037Z","end":"2026-10-26T01:16:27.036473Z","steps":["trace[510658336] 'agreement among raft nodes before linearized reading' (duration: 102.3467ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:27.036646Z","caller":"traceutil/trace.go:171","msg":"trace[437761677] transaction","detail":"{read_only:false; response_revision:8187; number_of_response:1; }","duration":"180.702515ms","start":"2026-10-26T01:16:26.85591Z","end":"2026-10-26T01:16:27.036613Z","steps":["trace[437761677] 'process raft request' (duration: 180.203391ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:33.663201Z","caller":"traceutil/trace.go:171","msg":"trace[1045230986] transaction","detail":"{read_only:false; response_revision:8232; number_of_response:1; }","duration":"205.580465ms","start":"2026-10-26T01:16:33.457567Z","end":"2026-10-26T01:16:33.663148Z","steps":["trace[1045230986] 'process raft request' (duration: 205.088452ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:36.962478Z","caller":"traceutil/trace.go:171","msg":"trace[867858425] linearizableReadLoop","detail":"{readStateIndex:8546; appliedIndex:8544; }","duration":"111.149885ms","start":"2026-10-26T01:16:36.851305Z","end":"2026-10-26T01:16:36.962455Z","steps":["trace[867858425] 'read index received' (duration: 19.827251ms)","trace[867858425] 'applied index is now lower than readState.Index' (duration: 91.321384ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:36.962549Z","caller":"traceutil/trace.go:171","msg":"trace[1239536746] transaction","detail":"{read_only:false; response_revision:8248; number_of_response:1; }","duration":"113.717885ms","start":"2026-10-26T01:16:36.848797Z","end":"2026-10-26T01:16:36.962515Z","steps":["trace[1239536746] 'process raft request' (duration: 113.571981ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:36.962568Z","caller":"traceutil/trace.go:171","msg":"trace[51828156] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"136.793686ms","start":"2026-10-26T01:16:36.825714Z","end":"2026-10-26T01:16:36.962508Z","steps":["trace[51828156] 'process raft request' (duration: 45.39267ms)","trace[51828156] 'compare' (duration: 91.010315ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:16:36.962699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.380801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-26T01:16:36.96274Z","caller":"traceutil/trace.go:171","msg":"trace[2010479900] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8248; }","duration":"111.465213ms","start":"2026-10-26T01:16:36.85126Z","end":"2026-10-26T01:16:36.962726Z","steps":["trace[2010479900] 'agreement among raft nodes before linearized reading' (duration: 111.282148ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:37.214563Z","caller":"traceutil/trace.go:171","msg":"trace[982574290] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"243.497957ms","start":"2026-10-26T01:16:36.97102Z","end":"2026-10-26T01:16:37.214518Z","steps":["trace[982574290] 'process raft request' (duration: 213.816003ms)","trace[982574290] 'compare' (duration: 29.507949ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:37.216646Z","caller":"traceutil/trace.go:171","msg":"trace[255558592] transaction","detail":"{read_only:false; response_revision:8250; number_of_response:1; }","duration":"118.348904ms","start":"2026-10-26T01:16:37.098269Z","end":"2026-10-26T01:16:37.216618Z","steps":["trace[255558592] 'process raft request' (duration: 118.19158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:37.757085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.236803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:1 size:12713"} {"level":"info","ts":"2026-10-26T01:16:37.757204Z","caller":"traceutil/trace.go:171","msg":"trace[246925326] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:1; response_revision:8251; }","duration":"433.435279ms","start":"2026-10-26T01:16:37.323743Z","end":"2026-10-26T01:16:37.757178Z","steps":["trace[246925326] 'range keys from in-memory index tree' (duration: 432.983556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:37.75726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:16:37.323718Z","time spent":"433.527721ms","remote":"127.0.0.1:58800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":12737,"request content":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" "} {"level":"info","ts":"2026-10-26T01:16:37.89901Z","caller":"traceutil/trace.go:171","msg":"trace[1459634609] linearizableReadLoop","detail":"{readStateIndex:8550; appliedIndex:8549; }","duration":"190.335122ms","start":"2026-10-26T01:16:37.708651Z","end":"2026-10-26T01:16:37.898986Z","steps":["trace[1459634609] 'read index received' (duration: 190.123927ms)","trace[1459634609] 'applied index is now lower than readState.Index' (duration: 209.795µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:37.899151Z","caller":"traceutil/trace.go:171","msg":"trace[676230073] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"196.496564ms","start":"2026-10-26T01:16:37.702612Z","end":"2026-10-26T01:16:37.899108Z","steps":["trace[676230073] 'process raft request' (duration: 196.224096ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:37.899949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.284428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tq2c7\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-26T01:16:37.90003Z","caller":"traceutil/trace.go:171","msg":"trace[1180877331] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tq2c7; range_end:; response_count:1; response_revision:8252; }","duration":"191.419462ms","start":"2026-10-26T01:16:37.708592Z","end":"2026-10-26T01:16:37.900011Z","steps":["trace[1180877331] 'agreement among raft nodes before linearized reading' (duration: 190.487486ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:38.965636Z","caller":"traceutil/trace.go:171","msg":"trace[573098837] transaction","detail":"{read_only:false; response_revision:8258; number_of_response:1; }","duration":"150.757814ms","start":"2026-10-26T01:16:38.814856Z","end":"2026-10-26T01:16:38.965614Z","steps":["trace[573098837] 'process raft request' (duration: 150.694152ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:38.965663Z","caller":"traceutil/trace.go:171","msg":"trace[2044230677] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"151.613888ms","start":"2026-10-26T01:16:38.814022Z","end":"2026-10-26T01:16:38.965636Z","steps":["trace[2044230677] 'process raft request' (duration: 81.214924ms)","trace[2044230677] 'compare' (duration: 70.180868ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:40.137052Z","caller":"traceutil/trace.go:171","msg":"trace[45427115] linearizableReadLoop","detail":"{readStateIndex:8562; appliedIndex:8561; }","duration":"230.898808ms","start":"2026-10-26T01:16:39.906129Z","end":"2026-10-26T01:16:40.137028Z","steps":["trace[45427115] 'read index received' (duration: 230.791995ms)","trace[45427115] 'applied index is now lower than readState.Index' (duration: 105.303µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:40.137102Z","caller":"traceutil/trace.go:171","msg":"trace[865926367] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"232.670807ms","start":"2026-10-26T01:16:39.904403Z","end":"2026-10-26T01:16:40.137073Z","steps":["trace[865926367] 'process raft request' (duration: 232.474792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:40.137362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.182896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12431"} {"level":"info","ts":"2026-10-26T01:16:40.137409Z","caller":"traceutil/trace.go:171","msg":"trace[171397771] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8263; }","duration":"231.304699ms","start":"2026-10-26T01:16:39.906092Z","end":"2026-10-26T01:16:40.137397Z","steps":["trace[171397771] 'agreement among raft nodes before linearized reading' (duration: 231.046992ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:40.177448Z","caller":"traceutil/trace.go:171","msg":"trace[1398499063] transaction","detail":"{read_only:false; response_revision:8264; number_of_response:1; }","duration":"198.860688ms","start":"2026-10-26T01:16:39.978564Z","end":"2026-10-26T01:16:40.177425Z","steps":["trace[1398499063] 'process raft request' (duration: 198.676913ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:40.177645Z","caller":"traceutil/trace.go:171","msg":"trace[195742417] transaction","detail":"{read_only:false; response_revision:8265; number_of_response:1; }","duration":"130.544513ms","start":"2026-10-26T01:16:40.047077Z","end":"2026-10-26T01:16:40.177621Z","steps":["trace[195742417] 'process raft request' (duration: 130.296746ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:40.177725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.315884ms","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-26T01:16:40.177848Z","caller":"traceutil/trace.go:171","msg":"trace[1386398838] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8265; }","duration":"229.477428ms","start":"2026-10-26T01:16:39.948352Z","end":"2026-10-26T01:16:40.17783Z","steps":["trace[1386398838] 'agreement among raft nodes before linearized reading' (duration: 229.285173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:40.178007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.316703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tq2c7\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-26T01:16:40.178044Z","caller":"traceutil/trace.go:171","msg":"trace[1832229024] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tq2c7; range_end:; response_count:1; response_revision:8265; }","duration":"265.422316ms","start":"2026-10-26T01:16:39.912611Z","end":"2026-10-26T01:16:40.178033Z","steps":["trace[1832229024] 'agreement among raft nodes before linearized reading' (duration: 265.252981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:40.894325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.783077ms","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-26T01:16:40.894424Z","caller":"traceutil/trace.go:171","msg":"trace[1967424013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8268; }","duration":"100.937612ms","start":"2026-10-26T01:16:40.793468Z","end":"2026-10-26T01:16:40.894405Z","steps":["trace[1967424013] 'range keys from in-memory index tree' (duration: 100.565341ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:41.021921Z","caller":"traceutil/trace.go:171","msg":"trace[829228615] transaction","detail":"{read_only:false; response_revision:8270; number_of_response:1; }","duration":"112.846372ms","start":"2026-10-26T01:16:40.909051Z","end":"2026-10-26T01:16:41.021897Z","steps":["trace[829228615] 'process raft request' (duration: 85.086301ms)","trace[829228615] 'compare' (duration: 27.587736ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:41.501295Z","caller":"traceutil/trace.go:171","msg":"trace[1223440296] linearizableReadLoop","detail":"{readStateIndex:8575; appliedIndex:8574; }","duration":"181.542608ms","start":"2026-10-26T01:16:41.319727Z","end":"2026-10-26T01:16:41.50127Z","steps":["trace[1223440296] 'read index received' (duration: 86.098719ms)","trace[1223440296] 'applied index is now lower than readState.Index' (duration: 95.442919ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:16:41.501407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.667852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:16:41.501384Z","caller":"traceutil/trace.go:171","msg":"trace[1981483644] transaction","detail":"{read_only:false; response_revision:8276; number_of_response:1; }","duration":"208.759634ms","start":"2026-10-26T01:16:41.292559Z","end":"2026-10-26T01:16:41.501319Z","steps":["trace[1981483644] 'process raft request' (duration: 113.261913ms)","trace[1981483644] 'compare' (duration: 95.350177ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:41.501438Z","caller":"traceutil/trace.go:171","msg":"trace[2051839414] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:8276; }","duration":"181.714593ms","start":"2026-10-26T01:16:41.319713Z","end":"2026-10-26T01:16:41.501428Z","steps":["trace[2051839414] 'agreement among raft nodes before linearized reading' (duration: 181.648311ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:41.504482Z","caller":"traceutil/trace.go:171","msg":"trace[1361223914] transaction","detail":"{read_only:false; response_revision:8277; number_of_response:1; }","duration":"181.050785ms","start":"2026-10-26T01:16:41.323409Z","end":"2026-10-26T01:16:41.50446Z","steps":["trace[1361223914] 'process raft request' (duration: 180.941412ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:41.699306Z","caller":"traceutil/trace.go:171","msg":"trace[1735892736] transaction","detail":"{read_only:false; response_revision:8279; number_of_response:1; }","duration":"144.279054ms","start":"2026-10-26T01:16:41.554997Z","end":"2026-10-26T01:16:41.699276Z","steps":["trace[1735892736] 'process raft request' (duration: 89.237517ms)","trace[1735892736] 'compare' (duration: 54.265156ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:43.229881Z","caller":"traceutil/trace.go:171","msg":"trace[592899170] linearizableReadLoop","detail":"{readStateIndex:8594; appliedIndex:8593; }","duration":"156.821462ms","start":"2026-10-26T01:16:43.073037Z","end":"2026-10-26T01:16:43.229858Z","steps":["trace[592899170] 'read index received' (duration: 156.804991ms)","trace[592899170] 'applied index is now lower than readState.Index' (duration: 15.351µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:16:43.229919Z","caller":"traceutil/trace.go:171","msg":"trace[866253000] transaction","detail":"{read_only:false; response_revision:8295; number_of_response:1; }","duration":"176.791317ms","start":"2026-10-26T01:16:43.053097Z","end":"2026-10-26T01:16:43.229889Z","steps":["trace[866253000] 'process raft request' (duration: 176.605932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:16:43.230069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.019348ms","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-26T01:16:43.2301Z","caller":"traceutil/trace.go:171","msg":"trace[1626035259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8295; }","duration":"157.10982ms","start":"2026-10-26T01:16:43.072982Z","end":"2026-10-26T01:16:43.230091Z","steps":["trace[1626035259] 'agreement among raft nodes before linearized reading' (duration: 156.967676ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:43.340061Z","caller":"traceutil/trace.go:171","msg":"trace[392001167] transaction","detail":"{read_only:false; response_revision:8296; number_of_response:1; }","duration":"125.629486ms","start":"2026-10-26T01:16:43.214404Z","end":"2026-10-26T01:16:43.340033Z","steps":["trace[392001167] 'process raft request' (duration: 125.251976ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:43.344687Z","caller":"traceutil/trace.go:171","msg":"trace[557711885] transaction","detail":"{read_only:false; response_revision:8297; number_of_response:1; }","duration":"110.723263ms","start":"2026-10-26T01:16:43.233941Z","end":"2026-10-26T01:16:43.344665Z","steps":["trace[557711885] 'process raft request' (duration: 110.553948ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:43.983823Z","caller":"traceutil/trace.go:171","msg":"trace[1339666891] transaction","detail":"{read_only:false; response_revision:8303; number_of_response:1; }","duration":"123.899859ms","start":"2026-10-26T01:16:43.859898Z","end":"2026-10-26T01:16:43.983798Z","steps":["trace[1339666891] 'process raft request' (duration: 123.620571ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:16:50.355032Z","caller":"traceutil/trace.go:171","msg":"trace[779377635] transaction","detail":"{read_only:false; response_revision:8342; number_of_response:1; }","duration":"123.518787ms","start":"2026-10-26T01:16:50.231487Z","end":"2026-10-26T01:16:50.355006Z","steps":["trace[779377635] 'process raft request' (duration: 123.378924ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:17:27.920991Z","caller":"traceutil/trace.go:171","msg":"trace[1661208850] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"374.606446ms","start":"2026-10-26T01:17:27.546353Z","end":"2026-10-26T01:17:27.920959Z","steps":["trace[1661208850] 'process raft request' (duration: 374.464692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:27.921187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:17:27.546336Z","time spent":"374.779721ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:17:28.019093Z","caller":"traceutil/trace.go:171","msg":"trace[1237848709] linearizableReadLoop","detail":"{readStateIndex:9024; appliedIndex:9022; }","duration":"341.313832ms","start":"2026-10-26T01:17:27.677752Z","end":"2026-10-26T01:17:28.019066Z","steps":["trace[1237848709] 'read index received' (duration: 243.138947ms)","trace[1237848709] 'applied index is now lower than readState.Index' (duration: 98.173805ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:17:28.019208Z","caller":"traceutil/trace.go:171","msg":"trace[203542948] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"409.554576ms","start":"2026-10-26T01:17:27.609633Z","end":"2026-10-26T01:17:28.019187Z","steps":["trace[203542948] 'process raft request' (duration: 391.217877ms)","trace[203542948] 'compare' (duration: 17.832545ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:17:28.019323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.567869ms","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":"warn","ts":"2026-10-26T01:17:28.019357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:17:27.609616Z","time spent":"409.635478ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:17:28.019364Z","caller":"traceutil/trace.go:171","msg":"trace[1691913888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8711; }","duration":"341.651442ms","start":"2026-10-26T01:17:27.677701Z","end":"2026-10-26T01:17:28.019353Z","steps":["trace[1691913888] 'agreement among raft nodes before linearized reading' (duration: 341.505898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:28.019461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:17:27.677681Z","time spent":"341.771535ms","remote":"127.0.0.1:58818","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-26T01:17:28.019873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.933982ms","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:7"} {"level":"info","ts":"2026-10-26T01:17:28.019926Z","caller":"traceutil/trace.go:171","msg":"trace[262479003] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8711; }","duration":"234.450326ms","start":"2026-10-26T01:17:27.78546Z","end":"2026-10-26T01:17:28.019911Z","steps":["trace[262479003] 'agreement among raft nodes before linearized reading' (duration: 233.927092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:39.959868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.842031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T01:17:39.959999Z","caller":"traceutil/trace.go:171","msg":"trace[2016623523] linearizableReadLoop","detail":"{readStateIndex:9091; appliedIndex:9090; }","duration":"271.741677ms","start":"2026-10-26T01:17:39.688237Z","end":"2026-10-26T01:17:39.959978Z","steps":["trace[2016623523] 'read index received' (duration: 128.635188ms)","trace[2016623523] 'applied index is now lower than readState.Index' (duration: 143.104149ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:17:39.960029Z","caller":"traceutil/trace.go:171","msg":"trace[1197952119] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"274.058491ms","start":"2026-10-26T01:17:39.685928Z","end":"2026-10-26T01:17:39.959986Z","steps":["trace[1197952119] 'process raft request' (duration: 130.969663ms)","trace[1197952119] 'compare' (duration: 142.579074ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:17:39.96016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.913882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T01:17:39.960203Z","caller":"traceutil/trace.go:171","msg":"trace[1642626014] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8775; }","duration":"271.996104ms","start":"2026-10-26T01:17:39.688197Z","end":"2026-10-26T01:17:39.960193Z","steps":["trace[1642626014] 'agreement among raft nodes before linearized reading' (duration: 271.85494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:41.342935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.339663ms","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-26T01:17:41.343022Z","caller":"traceutil/trace.go:171","msg":"trace[1993068861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8782; }","duration":"109.477377ms","start":"2026-10-26T01:17:41.233523Z","end":"2026-10-26T01:17:41.343Z","steps":["trace[1993068861] 'range keys from in-memory index tree' (duration: 109.121507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:17:41.343076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.468861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:17:41.343192Z","caller":"traceutil/trace.go:171","msg":"trace[938453755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8782; }","duration":"209.641056ms","start":"2026-10-26T01:17:41.133525Z","end":"2026-10-26T01:17:41.343166Z","steps":["trace[938453755] 'range keys from in-memory index tree' (duration: 209.332317ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:20.552872Z","caller":"traceutil/trace.go:171","msg":"trace[167610916] linearizableReadLoop","detail":"{readStateIndex:9375; appliedIndex:9374; }","duration":"155.041291ms","start":"2026-10-26T01:18:20.397779Z","end":"2026-10-26T01:18:20.55282Z","steps":["trace[167610916] 'read index received' (duration: 71.416521ms)","trace[167610916] 'applied index is now lower than readState.Index' (duration: 83.62152ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:18:20.552934Z","caller":"traceutil/trace.go:171","msg":"trace[1295747069] transaction","detail":"{read_only:false; response_revision:9048; number_of_response:1; }","duration":"163.968739ms","start":"2026-10-26T01:18:20.388925Z","end":"2026-10-26T01:18:20.552894Z","steps":["trace[1295747069] 'process raft request' (duration: 80.235376ms)","trace[1295747069] 'compare' (duration: 83.500266ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:18:20.553156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.35826ms","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-26T01:18:20.553193Z","caller":"traceutil/trace.go:171","msg":"trace[646155146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9048; }","duration":"155.462453ms","start":"2026-10-26T01:18:20.39772Z","end":"2026-10-26T01:18:20.553182Z","steps":["trace[646155146] 'agreement among raft nodes before linearized reading' (duration: 155.289598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:18:20.553277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.662111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-26T01:18:20.55335Z","caller":"traceutil/trace.go:171","msg":"trace[847102923] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9048; }","duration":"123.780524ms","start":"2026-10-26T01:18:20.42955Z","end":"2026-10-26T01:18:20.55333Z","steps":["trace[847102923] 'agreement among raft nodes before linearized reading' (duration: 123.596299ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:20.956534Z","caller":"traceutil/trace.go:171","msg":"trace[953539421] transaction","detail":"{read_only:false; response_revision:9051; number_of_response:1; }","duration":"131.499288ms","start":"2026-10-26T01:18:20.824975Z","end":"2026-10-26T01:18:20.956474Z","steps":["trace[953539421] 'process raft request' (duration: 131.260922ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:23.921991Z","caller":"traceutil/trace.go:171","msg":"trace[1750094250] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9076; }","duration":"203.418383ms","start":"2026-10-26T01:18:23.71855Z","end":"2026-10-26T01:18:23.921968Z","steps":["trace[1750094250] 'process raft request' (duration: 203.32885ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:23.922049Z","caller":"traceutil/trace.go:171","msg":"trace[1311289669] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9075; }","duration":"212.763132ms","start":"2026-10-26T01:18:23.709248Z","end":"2026-10-26T01:18:23.922012Z","steps":["trace[1311289669] 'process raft request' (duration: 212.482504ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:18:29.075702Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000250a.wal"} {"level":"info","ts":"2026-10-26T01:19:22.22897Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T01:19:22.239099Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T01:19:22.239289Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T01:19:48.198898Z","caller":"traceutil/trace.go:171","msg":"trace[219919317] transaction","detail":"{read_only:false; response_revision:9900; number_of_response:1; }","duration":"130.634957ms","start":"2026-10-26T01:19:48.068226Z","end":"2026-10-26T01:19:48.198861Z","steps":["trace[219919317] 'process raft request' (duration: 107.366502ms)","trace[219919317] 'compare' (duration: 23.09632ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:20:56.181953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7976} {"level":"info","ts":"2026-10-26T01:20:56.282191Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7976,"took":"97.817978ms","hash":1917150714,"current-db-size-bytes":54513664,"current-db-size":"54 MB","current-db-size-in-use-bytes":24977408,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-26T01:20:56.282313Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1917150714,"revision":7976,"compact-revision":5580} {"level":"info","ts":"2026-10-26T01:23:14.20521Z","caller":"traceutil/trace.go:171","msg":"trace[452511570] transaction","detail":"{read_only:false; response_revision:11645; number_of_response:1; }","duration":"123.590352ms","start":"2026-10-26T01:23:14.081565Z","end":"2026-10-26T01:23:14.205156Z","steps":["trace[452511570] 'process raft request' (duration: 109.361628ms)","trace[452511570] 'compare' (duration: 13.960176ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:23:14.218462Z","caller":"traceutil/trace.go:171","msg":"trace[996304815] transaction","detail":"{read_only:false; response_revision:11646; number_of_response:1; }","duration":"126.884673ms","start":"2026-10-26T01:23:14.091548Z","end":"2026-10-26T01:23:14.218433Z","steps":["trace[996304815] 'process raft request' (duration: 126.717639ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:25:56.190789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10371} {"level":"info","ts":"2026-10-26T01:25:56.288112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10371,"took":"93.96601ms","hash":913958272,"current-db-size-bytes":54513664,"current-db-size":"54 MB","current-db-size-in-use-bytes":28082176,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T01:25:56.288239Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":913958272,"revision":10371,"compact-revision":7976} {"level":"info","ts":"2026-10-26T01:29:24.457315Z","caller":"traceutil/trace.go:171","msg":"trace[795752157] transaction","detail":"{read_only:false; response_revision:14150; number_of_response:1; }","duration":"157.547816ms","start":"2026-10-26T01:29:24.299745Z","end":"2026-10-26T01:29:24.457293Z","steps":["trace[795752157] 'process raft request' (duration: 157.107404ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:29:27.498776Z","caller":"traceutil/trace.go:171","msg":"trace[1655504938] transaction","detail":"{read_only:false; response_revision:14164; number_of_response:1; }","duration":"112.505119ms","start":"2026-10-26T01:29:27.386228Z","end":"2026-10-26T01:29:27.498733Z","steps":["trace[1655504938] 'process raft request' (duration: 112.285073ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:30:05.974458Z","caller":"traceutil/trace.go:171","msg":"trace[564135653] transaction","detail":"{read_only:false; response_revision:14384; number_of_response:1; }","duration":"102.484753ms","start":"2026-10-26T01:30:05.871942Z","end":"2026-10-26T01:30:05.974427Z","steps":["trace[564135653] 'process raft request' (duration: 48.979374ms)","trace[564135653] 'compare' (duration: 53.275113ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:30:06.411471Z","caller":"traceutil/trace.go:171","msg":"trace[1800228516] linearizableReadLoop","detail":"{readStateIndex:14886; appliedIndex:14885; }","duration":"277.442578ms","start":"2026-10-26T01:30:06.133992Z","end":"2026-10-26T01:30:06.411434Z","steps":["trace[1800228516] 'read index received' (duration: 249.070409ms)","trace[1800228516] 'applied index is now lower than readState.Index' (duration: 28.370789ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:30:06.411519Z","caller":"traceutil/trace.go:171","msg":"trace[732906314] transaction","detail":"{read_only:false; response_revision:14385; number_of_response:1; }","duration":"426.615883ms","start":"2026-10-26T01:30:05.984875Z","end":"2026-10-26T01:30:06.411491Z","steps":["trace[732906314] 'process raft request' (duration: 398.251115ms)","trace[732906314] 'compare' (duration: 28.173963ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:30:06.411619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.614302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:30:06.411674Z","caller":"traceutil/trace.go:171","msg":"trace[1837455969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14385; }","duration":"277.697405ms","start":"2026-10-26T01:30:06.133958Z","end":"2026-10-26T01:30:06.411656Z","steps":["trace[1837455969] 'agreement among raft nodes before linearized reading' (duration: 277.597322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:30:06.411687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:30:05.98483Z","time spent":"426.757417ms","remote":"127.0.0.1:58854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":843,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:30:06.411782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.47466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-26T01:30:06.41183Z","caller":"traceutil/trace.go:171","msg":"trace[890678759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14385; }","duration":"229.563853ms","start":"2026-10-26T01:30:06.18225Z","end":"2026-10-26T01:30:06.411814Z","steps":["trace[890678759] 'agreement among raft nodes before linearized reading' (duration: 229.378638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:30:06.41195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.028088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-szjkw\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-10-26T01:30:06.412068Z","caller":"traceutil/trace.go:171","msg":"trace[1358232551] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-szjkw; range_end:; response_count:1; response_revision:14385; }","duration":"131.211542ms","start":"2026-10-26T01:30:06.280829Z","end":"2026-10-26T01:30:06.412041Z","steps":["trace[1358232551] 'agreement among raft nodes before linearized reading' (duration: 130.867993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:30:07.795457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.742923ms","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:7"} {"level":"info","ts":"2026-10-26T01:30:07.795549Z","caller":"traceutil/trace.go:171","msg":"trace[994529527] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14397; }","duration":"105.895797ms","start":"2026-10-26T01:30:07.689633Z","end":"2026-10-26T01:30:07.795529Z","steps":["trace[994529527] 'count revisions from in-memory index tree' (duration: 105.649211ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:30:30.879892Z","caller":"traceutil/trace.go:171","msg":"trace[1341804018] linearizableReadLoop","detail":"{readStateIndex:15215; appliedIndex:15214; }","duration":"256.243145ms","start":"2026-10-26T01:30:30.623622Z","end":"2026-10-26T01:30:30.879865Z","steps":["trace[1341804018] 'read index received' (duration: 256.07263ms)","trace[1341804018] 'applied index is now lower than readState.Index' (duration: 169.285µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:30:30.879937Z","caller":"traceutil/trace.go:171","msg":"trace[670223890] transaction","detail":"{read_only:false; response_revision:14703; number_of_response:1; }","duration":"263.189416ms","start":"2026-10-26T01:30:30.616722Z","end":"2026-10-26T01:30:30.879911Z","steps":["trace[670223890] 'process raft request' (duration: 262.921989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:30:30.880062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.4093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:30:30.880307Z","caller":"traceutil/trace.go:171","msg":"trace[2045068232] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:14703; }","duration":"256.683077ms","start":"2026-10-26T01:30:30.623577Z","end":"2026-10-26T01:30:30.88026Z","steps":["trace[2045068232] 'agreement among raft nodes before linearized reading' (duration: 256.42003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:30:30.894288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.084133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e24108e-f7f5-42d6-b833-642b8725b997\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-10-26T01:30:30.894348Z","caller":"traceutil/trace.go:171","msg":"trace[360606498] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e24108e-f7f5-42d6-b833-642b8725b997; range_end:; response_count:1; response_revision:14704; }","duration":"247.184006ms","start":"2026-10-26T01:30:30.647148Z","end":"2026-10-26T01:30:30.894332Z","steps":["trace[360606498] 'agreement among raft nodes before linearized reading' (duration: 246.999591ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:30:30.894371Z","caller":"traceutil/trace.go:171","msg":"trace[1442231281] transaction","detail":"{read_only:false; response_revision:14704; number_of_response:1; }","duration":"230.492668ms","start":"2026-10-26T01:30:30.663859Z","end":"2026-10-26T01:30:30.894352Z","steps":["trace[1442231281] 'process raft request' (duration: 230.20448ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:30:56.197312Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12729} {"level":"info","ts":"2026-10-26T01:30:56.290123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12729,"took":"90.079193ms","hash":2490728131,"current-db-size-bytes":54513664,"current-db-size":"54 MB","current-db-size-in-use-bytes":29413376,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-26T01:30:56.290222Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2490728131,"revision":12729,"compact-revision":10371} {"level":"info","ts":"2026-10-26T01:31:23.564374Z","caller":"traceutil/trace.go:171","msg":"trace[250997639] transaction","detail":"{read_only:false; response_revision:15342; number_of_response:1; }","duration":"122.73455ms","start":"2026-10-26T01:31:23.441619Z","end":"2026-10-26T01:31:23.564354Z","steps":["trace[250997639] 'process raft request' (duration: 122.541795ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:31:48.061846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.375918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-n5pm5\" ","response":"range_response_count:1 size:20802"} {"level":"info","ts":"2026-10-26T01:31:48.062106Z","caller":"traceutil/trace.go:171","msg":"trace[483435408] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-n5pm5; range_end:; response_count:1; response_revision:15472; }","duration":"103.700747ms","start":"2026-10-26T01:31:47.958379Z","end":"2026-10-26T01:31:48.06208Z","steps":["trace[483435408] 'range keys from in-memory index tree' (duration: 103.113751ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:31:48.523373Z","caller":"traceutil/trace.go:171","msg":"trace[1781776744] linearizableReadLoop","detail":"{readStateIndex:16006; appliedIndex:16005; }","duration":"148.534428ms","start":"2026-10-26T01:31:48.374786Z","end":"2026-10-26T01:31:48.52332Z","steps":["trace[1781776744] 'read index received' (duration: 134.211005ms)","trace[1781776744] 'applied index is now lower than readState.Index' (duration: 14.321943ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:31:48.523615Z","caller":"traceutil/trace.go:171","msg":"trace[438548594] transaction","detail":"{read_only:false; response_revision:15474; number_of_response:1; }","duration":"159.487719ms","start":"2026-10-26T01:31:48.364111Z","end":"2026-10-26T01:31:48.523599Z","steps":["trace[438548594] 'process raft request' (duration: 144.871928ms)","trace[438548594] 'compare' (duration: 14.103667ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:31:48.523866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.017932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:31:48.523962Z","caller":"traceutil/trace.go:171","msg":"trace[1222618944] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15474; }","duration":"149.158145ms","start":"2026-10-26T01:31:48.374782Z","end":"2026-10-26T01:31:48.52394Z","steps":["trace[1222618944] 'agreement among raft nodes before linearized reading' (duration: 148.97577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:31:48.542472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.619253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:31:48.542529Z","caller":"traceutil/trace.go:171","msg":"trace[1183832390] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15475; }","duration":"167.692544ms","start":"2026-10-26T01:31:48.374822Z","end":"2026-10-26T01:31:48.542514Z","steps":["trace[1183832390] 'agreement among raft nodes before linearized reading' (duration: 167.568451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:31:48.542926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.642205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-26T01:31:48.542966Z","caller":"traceutil/trace.go:171","msg":"trace[700641023] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15475; }","duration":"158.726679ms","start":"2026-10-26T01:31:48.384229Z","end":"2026-10-26T01:31:48.542956Z","steps":["trace[700641023] 'agreement among raft nodes before linearized reading' (duration: 158.603825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:31:48.54311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.580682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T01:31:48.543144Z","caller":"traceutil/trace.go:171","msg":"trace[1292026946] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15475; }","duration":"167.696854ms","start":"2026-10-26T01:31:48.375438Z","end":"2026-10-26T01:31:48.543135Z","steps":["trace[1292026946] 'agreement among raft nodes before linearized reading' (duration: 167.643653ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:31:49.460955Z","caller":"traceutil/trace.go:171","msg":"trace[1524008416] transaction","detail":"{read_only:false; response_revision:15477; number_of_response:1; }","duration":"132.96162ms","start":"2026-10-26T01:31:49.327955Z","end":"2026-10-26T01:31:49.460917Z","steps":["trace[1524008416] 'process raft request' (duration: 132.768645ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:35:56.208594Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14883} {"level":"info","ts":"2026-10-26T01:35:56.324506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14883,"took":"112.897064ms","hash":3115804317,"current-db-size-bytes":54513664,"current-db-size":"54 MB","current-db-size-in-use-bytes":31002624,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T01:35:56.324604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3115804317,"revision":14883,"compact-revision":12729} {"level":"warn","ts":"2026-10-26T01:37:52.033646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.207745ms","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-26T01:37:52.033781Z","caller":"traceutil/trace.go:171","msg":"trace[999007995] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:18135; }","duration":"148.353999ms","start":"2026-10-26T01:37:51.885402Z","end":"2026-10-26T01:37:52.033756Z","steps":["trace[999007995] 'range keys from in-memory index tree' (duration: 148.190405ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:37:59.219551Z","caller":"traceutil/trace.go:171","msg":"trace[1705009238] transaction","detail":"{read_only:false; response_revision:18171; number_of_response:1; }","duration":"149.731407ms","start":"2026-10-26T01:37:59.069787Z","end":"2026-10-26T01:37:59.219518Z","steps":["trace[1705009238] 'process raft request' (duration: 79.456999ms)","trace[1705009238] 'compare' (duration: 70.126064ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:37:59.633027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.595357ms","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-26T01:37:59.633154Z","caller":"traceutil/trace.go:171","msg":"trace[1479145811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18171; }","duration":"245.774701ms","start":"2026-10-26T01:37:59.387358Z","end":"2026-10-26T01:37:59.633133Z","steps":["trace[1479145811] 'range keys from in-memory index tree' (duration: 245.357641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:38:00.263284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.275488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:38:00.263412Z","caller":"traceutil/trace.go:171","msg":"trace[965419412] transaction","detail":"{read_only:false; response_revision:18175; number_of_response:1; }","duration":"544.158597ms","start":"2026-10-26T01:37:59.719242Z","end":"2026-10-26T01:38:00.2634Z","steps":["trace[965419412] 'process raft request' (duration: 92.706263ms)","trace[965419412] 'compare' (duration: 450.982151ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:38:00.26345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:37:59.71922Z","time spent":"544.213988ms","remote":"127.0.0.1:58818","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-26T01:38:00.263759Z","caller":"traceutil/trace.go:171","msg":"trace[606885811] transaction","detail":"{read_only:false; response_revision:18176; number_of_response:1; }","duration":"533.849363ms","start":"2026-10-26T01:37:59.729901Z","end":"2026-10-26T01:38:00.263751Z","steps":["trace[606885811] 'process raft request' (duration: 533.454593ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:38:00.263819Z","caller":"traceutil/trace.go:171","msg":"trace[2002531931] transaction","detail":"{read_only:false; response_revision:18177; number_of_response:1; }","duration":"498.528765ms","start":"2026-10-26T01:37:59.765283Z","end":"2026-10-26T01:38:00.263811Z","steps":["trace[2002531931] 'process raft request' (duration: 498.441553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:38:00.26386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:37:59.765246Z","time spent":"498.597797ms","remote":"127.0.0.1:58818","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-26T01:38:00.263761Z","caller":"traceutil/trace.go:171","msg":"trace[632234640] linearizableReadLoop","detail":"{readStateIndex:18800; appliedIndex:18797; }","duration":"497.912139ms","start":"2026-10-26T01:37:59.765842Z","end":"2026-10-26T01:38:00.263754Z","steps":["trace[632234640] 'read index received' (duration: 46.095395ms)","trace[632234640] 'applied index is now lower than readState.Index' (duration: 451.815943ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:38:00.263949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.088823ms","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-26T01:38:00.263917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:37:59.729887Z","time spent":"533.891795ms","remote":"127.0.0.1:58636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T01:38:00.263977Z","caller":"traceutil/trace.go:171","msg":"trace[1883482621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18177; }","duration":"498.129254ms","start":"2026-10-26T01:37:59.765837Z","end":"2026-10-26T01:38:00.263966Z","steps":["trace[1883482621] 'agreement among raft nodes before linearized reading' (duration: 497.936999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:38:00.264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:37:59.765784Z","time spent":"498.211546ms","remote":"127.0.0.1:58818","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-26T01:38:00.296063Z","caller":"traceutil/trace.go:171","msg":"trace[1028284484] transaction","detail":"{read_only:false; response_revision:18178; number_of_response:1; }","duration":"224.712274ms","start":"2026-10-26T01:38:00.071325Z","end":"2026-10-26T01:38:00.296037Z","steps":["trace[1028284484] 'process raft request' (duration: 224.544189ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:38:00.296123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.10808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-vt97h\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-26T01:38:00.296163Z","caller":"traceutil/trace.go:171","msg":"trace[755537659] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-vt97h; range_end:; response_count:1; response_revision:18178; }","duration":"507.187802ms","start":"2026-10-26T01:37:59.788965Z","end":"2026-10-26T01:38:00.296153Z","steps":["trace[755537659] 'agreement among raft nodes before linearized reading' (duration: 507.028378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:38:00.296184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:37:59.788952Z","time spent":"507.228674ms","remote":"127.0.0.1:58736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-vt97h\" "} {"level":"info","ts":"2026-10-26T01:40:01.179553Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T01:40:01.187263Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T01:40:01.187399Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T01:40:56.217059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17337} {"level":"info","ts":"2026-10-26T01:40:56.341613Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17337,"took":"121.186224ms","hash":1332664161,"current-db-size-bytes":54513664,"current-db-size":"54 MB","current-db-size-in-use-bytes":34926592,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-26T01:40:56.341688Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1332664161,"revision":17337,"compact-revision":14883} {"level":"warn","ts":"2026-10-26T01:43:37.774174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.959725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:43:37.77434Z","caller":"traceutil/trace.go:171","msg":"trace[221605413] transaction","detail":"{read_only:false; response_revision:20810; number_of_response:1; }","duration":"164.79738ms","start":"2026-10-26T01:43:37.609515Z","end":"2026-10-26T01:43:37.774312Z","steps":["trace[221605413] 'process raft request' (duration: 20.568957ms)","trace[221605413] 'compare' (duration: 143.638316ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:43:37.775215Z","caller":"traceutil/trace.go:171","msg":"trace[370697099] transaction","detail":"{read_only:false; response_revision:20811; number_of_response:1; }","duration":"163.499414ms","start":"2026-10-26T01:43:37.611664Z","end":"2026-10-26T01:43:37.775163Z","steps":["trace[370697099] 'process raft request' (duration: 163.174315ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:43:43.732534Z","caller":"traceutil/trace.go:171","msg":"trace[2012334067] transaction","detail":"{read_only:false; response_revision:20839; number_of_response:1; }","duration":"138.189557ms","start":"2026-10-26T01:43:43.594315Z","end":"2026-10-26T01:43:43.732505Z","steps":["trace[2012334067] 'process raft request' (duration: 138.034353ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:43:43.94598Z","caller":"traceutil/trace.go:171","msg":"trace[1622714425] transaction","detail":"{read_only:false; response_revision:20840; number_of_response:1; }","duration":"149.462698ms","start":"2026-10-26T01:43:43.796477Z","end":"2026-10-26T01:43:43.94594Z","steps":["trace[1622714425] 'process raft request' (duration: 124.741836ms)","trace[1622714425] 'compare' (duration: 24.499045ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:43:43.946206Z","caller":"traceutil/trace.go:171","msg":"trace[143990178] linearizableReadLoop","detail":"{readStateIndex:21552; appliedIndex:21550; }","duration":"144.383918ms","start":"2026-10-26T01:43:43.801771Z","end":"2026-10-26T01:43:43.946155Z","steps":["trace[143990178] 'read index received' (duration: 119.456111ms)","trace[143990178] 'applied index is now lower than readState.Index' (duration: 24.924396ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:43:43.946382Z","caller":"traceutil/trace.go:171","msg":"trace[932163683] transaction","detail":"{read_only:false; response_revision:20841; number_of_response:1; }","duration":"147.554735ms","start":"2026-10-26T01:43:43.798786Z","end":"2026-10-26T01:43:43.946341Z","steps":["trace[932163683] 'process raft request' (duration: 147.096102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:43:43.946557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.752598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-26T01:43:43.946699Z","caller":"traceutil/trace.go:171","msg":"trace[1265110889] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20841; }","duration":"144.935463ms","start":"2026-10-26T01:43:43.801735Z","end":"2026-10-26T01:43:43.946671Z","steps":["trace[1265110889] 'agreement among raft nodes before linearized reading' (duration: 144.645945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:43:43.946758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.996127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-26T01:43:43.946845Z","caller":"traceutil/trace.go:171","msg":"trace[1829678797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20841; }","duration":"144.132061ms","start":"2026-10-26T01:43:43.802686Z","end":"2026-10-26T01:43:43.946818Z","steps":["trace[1829678797] 'agreement among raft nodes before linearized reading' (duration: 143.74673ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:43:44.125127Z","caller":"traceutil/trace.go:171","msg":"trace[299254767] transaction","detail":"{read_only:false; response_revision:20842; number_of_response:1; }","duration":"173.670223ms","start":"2026-10-26T01:43:43.951405Z","end":"2026-10-26T01:43:44.125076Z","steps":["trace[299254767] 'process raft request' (duration: 165.150718ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:43:44.125492Z","caller":"traceutil/trace.go:171","msg":"trace[1077539222] transaction","detail":"{read_only:false; response_revision:20843; number_of_response:1; }","duration":"170.589008ms","start":"2026-10-26T01:43:43.95487Z","end":"2026-10-26T01:43:44.125459Z","steps":["trace[1077539222] 'process raft request' (duration: 170.072224ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:43:44.376345Z","caller":"traceutil/trace.go:171","msg":"trace[1030813588] linearizableReadLoop","detail":"{readStateIndex:21556; appliedIndex:21554; }","duration":"241.145833ms","start":"2026-10-26T01:43:44.135158Z","end":"2026-10-26T01:43:44.376304Z","steps":["trace[1030813588] 'read index received' (duration: 37.819872ms)","trace[1030813588] 'applied index is now lower than readState.Index' (duration: 203.324101ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:43:44.376414Z","caller":"traceutil/trace.go:171","msg":"trace[2059886549] transaction","detail":"{read_only:false; response_revision:20845; number_of_response:1; }","duration":"245.910474ms","start":"2026-10-26T01:43:44.13047Z","end":"2026-10-26T01:43:44.37638Z","steps":["trace[2059886549] 'process raft request' (duration: 211.108795ms)","trace[2059886549] 'compare' (duration: 34.48777ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:43:44.376669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.338358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:43:44.376735Z","caller":"traceutil/trace.go:171","msg":"trace[794427793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20845; }","duration":"241.634406ms","start":"2026-10-26T01:43:44.135082Z","end":"2026-10-26T01:43:44.376716Z","steps":["trace[794427793] 'agreement among raft nodes before linearized reading' (duration: 241.363679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:43:44.721918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.881469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:43:44.722002Z","caller":"traceutil/trace.go:171","msg":"trace[939665121] transaction","detail":"{read_only:false; response_revision:20846; number_of_response:1; }","duration":"298.668937ms","start":"2026-10-26T01:43:44.423319Z","end":"2026-10-26T01:43:44.721987Z","steps":["trace[939665121] 'process raft request' (duration: 95.652945ms)","trace[939665121] 'compare' (duration: 202.793926ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:45:56.225881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19685} {"level":"info","ts":"2026-10-26T01:45:56.36206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19685,"took":"131.06823ms","hash":1033781208,"current-db-size-bytes":54513664,"current-db-size":"54 MB","current-db-size-in-use-bytes":30339072,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T01:45:56.362226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1033781208,"revision":19685,"compact-revision":17337} {"level":"info","ts":"2026-10-26T01:47:43.103822Z","caller":"traceutil/trace.go:171","msg":"trace[29939741] transaction","detail":"{read_only:false; response_revision:23329; number_of_response:1; }","duration":"125.104666ms","start":"2026-10-26T01:47:42.97868Z","end":"2026-10-26T01:47:43.103785Z","steps":["trace[29939741] 'process raft request' (duration: 124.86975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:47:43.524828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.12415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:47:43.524924Z","caller":"traceutil/trace.go:171","msg":"trace[488160620] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:23331; }","duration":"364.274744ms","start":"2026-10-26T01:47:43.160622Z","end":"2026-10-26T01:47:43.524897Z","steps":["trace[488160620] 'count revisions from in-memory index tree' (duration: 364.025628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:47:43.524968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:47:43.160601Z","time spent":"364.353267ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-10-26T01:49:59.836169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.332872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:49:59.836282Z","caller":"traceutil/trace.go:171","msg":"trace[1888752339] linearizableReadLoop","detail":"{readStateIndex:25501; appliedIndex:25500; }","duration":"102.75238ms","start":"2026-10-26T01:49:59.733514Z","end":"2026-10-26T01:49:59.836266Z","steps":["trace[1888752339] 'read index received' (duration: 2.140769ms)","trace[1888752339] 'applied index is now lower than readState.Index' (duration: 100.610411ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:49:59.836348Z","caller":"traceutil/trace.go:171","msg":"trace[724661686] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"129.189168ms","start":"2026-10-26T01:49:59.707148Z","end":"2026-10-26T01:49:59.836337Z","steps":["trace[724661686] 'process raft request' (duration: 28.561957ms)","trace[724661686] 'compare' (duration: 100.211489ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:49:59.836497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.962579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-26T01:49:59.836555Z","caller":"traceutil/trace.go:171","msg":"trace[688158690] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:24685; }","duration":"126.055911ms","start":"2026-10-26T01:49:59.710485Z","end":"2026-10-26T01:49:59.836541Z","steps":["trace[688158690] 'agreement among raft nodes before linearized reading' (duration: 125.882707ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:49:59.850384Z","caller":"traceutil/trace.go:171","msg":"trace[2057785007] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"103.49032ms","start":"2026-10-26T01:49:59.746877Z","end":"2026-10-26T01:49:59.850367Z","steps":["trace[2057785007] 'process raft request' (duration: 103.304985ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:50:56.235162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21934} {"level":"info","ts":"2026-10-26T01:50:56.359504Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21934,"took":"120.14651ms","hash":2720558309,"current-db-size-bytes":54513664,"current-db-size":"54 MB","current-db-size-in-use-bytes":45047808,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-26T01:50:56.35959Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2720558309,"revision":21934,"compact-revision":19685} {"level":"info","ts":"2026-10-26T01:51:12.162041Z","caller":"traceutil/trace.go:171","msg":"trace[429151488] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"151.290467ms","start":"2026-10-26T01:51:12.010713Z","end":"2026-10-26T01:51:12.162004Z","steps":["trace[429151488] 'process raft request' (duration: 149.20635ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:17.700626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.308533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:51:17.700728Z","caller":"traceutil/trace.go:171","msg":"trace[1533071881] linearizableReadLoop","detail":"{readStateIndex:26269; appliedIndex:26268; }","duration":"249.165394ms","start":"2026-10-26T01:51:17.451545Z","end":"2026-10-26T01:51:17.70071Z","steps":["trace[1533071881] 'read index received' (duration: 38.631024ms)","trace[1533071881] 'applied index is now lower than readState.Index' (duration: 210.53342ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:51:17.700792Z","caller":"traceutil/trace.go:171","msg":"trace[280000863] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"341.724304ms","start":"2026-10-26T01:51:17.359056Z","end":"2026-10-26T01:51:17.700781Z","steps":["trace[280000863] 'process raft request' (duration: 131.178484ms)","trace[280000863] 'compare' (duration: 209.809959ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:51:17.700847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:51:17.359034Z","time spent":"341.785315ms","remote":"127.0.0.1:58818","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-26T01:51:17.701089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.542194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-26T01:51:17.701147Z","caller":"traceutil/trace.go:171","msg":"trace[381554587] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25432; }","duration":"249.615676ms","start":"2026-10-26T01:51:17.451499Z","end":"2026-10-26T01:51:17.701115Z","steps":["trace[381554587] 'agreement among raft nodes before linearized reading' (duration: 249.497422ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:18.090927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.216812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:51:18.091169Z","caller":"traceutil/trace.go:171","msg":"trace[1689157114] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"416.898144ms","start":"2026-10-26T01:51:17.674196Z","end":"2026-10-26T01:51:18.091094Z","steps":["trace[1689157114] 'process raft request' (duration: 231.402634ms)","trace[1689157114] 'compare' (duration: 185.029777ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:51:18.091198Z","caller":"traceutil/trace.go:171","msg":"trace[1712267183] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"120.732706ms","start":"2026-10-26T01:51:17.97044Z","end":"2026-10-26T01:51:18.091173Z","steps":["trace[1712267183] 'process raft request' (duration: 120.634423ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:51:18.091301Z","caller":"traceutil/trace.go:171","msg":"trace[258488386] linearizableReadLoop","detail":"{readStateIndex:26270; appliedIndex:26269; }","duration":"390.514497ms","start":"2026-10-26T01:51:17.700754Z","end":"2026-10-26T01:51:18.091268Z","steps":["trace[258488386] 'read index received' (duration: 204.738189ms)","trace[258488386] 'applied index is now lower than readState.Index' (duration: 185.746997ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:51:18.091412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:51:17.674153Z","time spent":"417.097829ms","remote":"127.0.0.1:58818","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-26T01:51:18.091414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.780212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:51:18.091474Z","caller":"traceutil/trace.go:171","msg":"trace[316182237] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25434; }","duration":"501.884415ms","start":"2026-10-26T01:51:17.589574Z","end":"2026-10-26T01:51:18.091459Z","steps":["trace[316182237] 'agreement among raft nodes before linearized reading' (duration: 501.771422ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:18.091516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:51:17.589551Z","time spent":"501.956806ms","remote":"127.0.0.1:53716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-10-26T01:51:18.091598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.270948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-55e81a98-12f9-4e3d-9476-0cdf0efc3a27\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-10-26T01:51:18.091646Z","caller":"traceutil/trace.go:171","msg":"trace[1795740189] range","detail":"{range_begin:/registry/persistentvolumes/pvc-55e81a98-12f9-4e3d-9476-0cdf0efc3a27; range_end:; response_count:1; response_revision:25434; }","duration":"387.35509ms","start":"2026-10-26T01:51:17.704277Z","end":"2026-10-26T01:51:18.091632Z","steps":["trace[1795740189] 'agreement among raft nodes before linearized reading' (duration: 387.186425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:18.091679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:51:17.704258Z","time spent":"387.412762ms","remote":"127.0.0.1:58710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1348,"request content":"key:\"/registry/persistentvolumes/pvc-55e81a98-12f9-4e3d-9476-0cdf0efc3a27\" "} {"level":"info","ts":"2026-10-26T01:51:18.255339Z","caller":"traceutil/trace.go:171","msg":"trace[157917941] linearizableReadLoop","detail":"{readStateIndex:26272; appliedIndex:26271; }","duration":"122.144725ms","start":"2026-10-26T01:51:18.133164Z","end":"2026-10-26T01:51:18.255309Z","steps":["trace[157917941] 'read index received' (duration: 115.092271ms)","trace[157917941] 'applied index is now lower than readState.Index' (duration: 7.050614ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:51:18.255553Z","caller":"traceutil/trace.go:171","msg":"trace[1394461566] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"134.269209ms","start":"2026-10-26T01:51:18.121259Z","end":"2026-10-26T01:51:18.255528Z","steps":["trace[1394461566] 'process raft request' (duration: 126.980278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:51:18.256151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.32118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:51:18.256221Z","caller":"traceutil/trace.go:171","msg":"trace[652498064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25435; }","duration":"123.04921ms","start":"2026-10-26T01:51:18.133148Z","end":"2026-10-26T01:51:18.256197Z","steps":["trace[652498064] 'agreement among raft nodes before linearized reading' (duration: 122.278728ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:51:47.072813Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067d4.wal"} {"level":"info","ts":"2026-10-26T01:52:19.227463Z","caller":"traceutil/trace.go:171","msg":"trace[941877715] transaction","detail":"{read_only:false; response_revision:25983; number_of_response:1; }","duration":"161.424946ms","start":"2026-10-26T01:52:19.066004Z","end":"2026-10-26T01:52:19.227429Z","steps":["trace[941877715] 'process raft request' (duration: 161.18763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:52:19.530389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.335605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:52:19.530521Z","caller":"traceutil/trace.go:171","msg":"trace[371481523] transaction","detail":"{read_only:false; response_revision:25984; number_of_response:1; }","duration":"295.343244ms","start":"2026-10-26T01:52:19.235155Z","end":"2026-10-26T01:52:19.530498Z","steps":["trace[371481523] 'process raft request' (duration: 162.798773ms)","trace[371481523] 'compare' (duration: 132.029517ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:52:19.536291Z","caller":"traceutil/trace.go:171","msg":"trace[1495947305] linearizableReadLoop","detail":"{readStateIndex:26835; appliedIndex:26833; }","duration":"246.041616ms","start":"2026-10-26T01:52:19.290223Z","end":"2026-10-26T01:52:19.536265Z","steps":["trace[1495947305] 'read index received' (duration: 107.757667ms)","trace[1495947305] 'applied index is now lower than readState.Index' (duration: 138.282909ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:52:19.536329Z","caller":"traceutil/trace.go:171","msg":"trace[1662793801] transaction","detail":"{read_only:false; response_revision:25985; number_of_response:1; }","duration":"292.338802ms","start":"2026-10-26T01:52:19.243964Z","end":"2026-10-26T01:52:19.536303Z","steps":["trace[1662793801] 'process raft request' (duration: 292.140297ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:52:19.536374Z","caller":"traceutil/trace.go:171","msg":"trace[170717317] transaction","detail":"{read_only:false; response_revision:25986; number_of_response:1; }","duration":"244.831043ms","start":"2026-10-26T01:52:19.291513Z","end":"2026-10-26T01:52:19.536344Z","steps":["trace[170717317] 'process raft request' (duration: 244.71514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:52:19.536466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.229131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-26T01:52:19.536511Z","caller":"traceutil/trace.go:171","msg":"trace[1927308246] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25986; }","duration":"246.325655ms","start":"2026-10-26T01:52:19.290174Z","end":"2026-10-26T01:52:19.5365Z","steps":["trace[1927308246] 'agreement among raft nodes before linearized reading' (duration: 246.194671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:52:19.536583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.141234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-26T01:52:19.53662Z","caller":"traceutil/trace.go:171","msg":"trace[1198510685] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25986; }","duration":"110.221966ms","start":"2026-10-26T01:52:19.426386Z","end":"2026-10-26T01:52:19.536608Z","steps":["trace[1198510685] 'agreement among raft nodes before linearized reading' (duration: 110.099642ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:52:19.903122Z","caller":"traceutil/trace.go:171","msg":"trace[1792781739] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"345.934978ms","start":"2026-10-26T01:52:19.557154Z","end":"2026-10-26T01:52:19.903089Z","steps":["trace[1792781739] 'process raft request' (duration: 345.706602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:52:19.903349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:52:19.557113Z","time spent":"346.122644ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:52:20.148726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.242134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T01:52:20.148833Z","caller":"traceutil/trace.go:171","msg":"trace[1410927921] linearizableReadLoop","detail":"{readStateIndex:26838; appliedIndex:26836; }","duration":"473.38215ms","start":"2026-10-26T01:52:19.675433Z","end":"2026-10-26T01:52:20.148815Z","steps":["trace[1410927921] 'read index received' (duration: 227.472056ms)","trace[1410927921] 'applied index is now lower than readState.Index' (duration: 245.908484ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T01:52:20.149074Z","caller":"traceutil/trace.go:171","msg":"trace[1369003993] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"491.805707ms","start":"2026-10-26T01:52:19.657255Z","end":"2026-10-26T01:52:20.149061Z","steps":["trace[1369003993] 'process raft request' (duration: 290.127441ms)","trace[1369003993] 'compare' (duration: 201.128891ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:52:20.149171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:52:19.657241Z","time spent":"491.870719ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T01:52:20.149457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.031107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-26T01:52:20.149489Z","caller":"traceutil/trace.go:171","msg":"trace[924920916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25988; }","duration":"474.065008ms","start":"2026-10-26T01:52:19.675414Z","end":"2026-10-26T01:52:20.149479Z","steps":["trace[924920916] 'agreement among raft nodes before linearized reading' (duration: 473.939115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:52:20.149516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:52:19.675343Z","time spent":"474.167521ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T01:52:20.149756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.778384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-26T01:52:20.149804Z","caller":"traceutil/trace.go:171","msg":"trace[546915988] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25988; }","duration":"439.870316ms","start":"2026-10-26T01:52:19.709921Z","end":"2026-10-26T01:52:20.149792Z","steps":["trace[546915988] 'agreement among raft nodes before linearized reading' (duration: 439.741742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:52:20.149839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:52:19.709902Z","time spent":"439.927468ms","remote":"127.0.0.1:58818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-26T01:52:20.150316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.539171ms","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":"warn","ts":"2026-10-26T01:52:20.150336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.607367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-10-26T01:52:20.150351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.287683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:52:20.150386Z","caller":"traceutil/trace.go:171","msg":"trace[905478722] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:25988; }","duration":"432.680958ms","start":"2026-10-26T01:52:19.717694Z","end":"2026-10-26T01:52:20.150375Z","steps":["trace[905478722] 'agreement among raft nodes before linearized reading' (duration: 432.551644ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:52:20.150394Z","caller":"traceutil/trace.go:171","msg":"trace[512475439] 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:25988; }","duration":"352.672615ms","start":"2026-10-26T01:52:19.797698Z","end":"2026-10-26T01:52:20.15037Z","steps":["trace[512475439] 'agreement among raft nodes before linearized reading' (duration: 352.545281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:52:20.150423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:52:19.717685Z","time spent":"432.7323ms","remote":"127.0.0.1:58636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/162.253.55.84\" "} {"level":"warn","ts":"2026-10-26T01:52:20.150358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.030246ms","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:6"} {"level":"info","ts":"2026-10-26T01:52:20.150442Z","caller":"traceutil/trace.go:171","msg":"trace[912373206] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25988; }","duration":"332.389136ms","start":"2026-10-26T01:52:19.818022Z","end":"2026-10-26T01:52:20.150411Z","steps":["trace[912373206] 'agreement among raft nodes before linearized reading' (duration: 332.233702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:52:20.150449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:52:19.797653Z","time spent":"352.779638ms","remote":"127.0.0.1:54084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":124,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true "} {"level":"warn","ts":"2026-10-26T01:52:20.150499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T01:52:19.818001Z","time spent":"332.481328ms","remote":"127.0.0.1:34998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-10-26T01:52:20.150483Z","caller":"traceutil/trace.go:171","msg":"trace[1463976163] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:25988; }","duration":"112.17994ms","start":"2026-10-26T01:52:20.03828Z","end":"2026-10-26T01:52:20.15046Z","steps":["trace[1463976163] 'agreement among raft nodes before linearized reading' (duration: 111.961254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T01:52:20.438415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.619707ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-10-26T01:52:20.451291Z","caller":"traceutil/trace.go:171","msg":"trace[473094141] transaction","detail":"{read_only:false; response_revision:25989; number_of_response:1; }","duration":"296.459336ms","start":"2026-10-26T01:52:20.154806Z","end":"2026-10-26T01:52:20.451265Z","steps":["trace[473094141] 'process raft request' (duration: 296.179318ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:52:20.451423Z","caller":"traceutil/trace.go:171","msg":"trace[1950327554] transaction","detail":"{read_only:false; response_revision:25990; number_of_response:1; }","duration":"296.186108ms","start":"2026-10-26T01:52:20.155201Z","end":"2026-10-26T01:52:20.451387Z","steps":["trace[1950327554] 'process raft request' (duration: 296.000563ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:52:20.588319Z","caller":"traceutil/trace.go:171","msg":"trace[345035369] transaction","detail":"{read_only:false; response_revision:25991; number_of_response:1; }","duration":"249.173324ms","start":"2026-10-26T01:52:20.339118Z","end":"2026-10-26T01:52:20.588292Z","steps":["trace[345035369] 'process raft request' (duration: 248.964058ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:52:20.588644Z","caller":"traceutil/trace.go:171","msg":"trace[1384683267] transaction","detail":"{read_only:false; response_revision:25993; number_of_response:1; }","duration":"148.785519ms","start":"2026-10-26T01:52:20.439847Z","end":"2026-10-26T01:52:20.588633Z","steps":["trace[1384683267] 'process raft request' (duration: 148.622174ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:52:20.588626Z","caller":"traceutil/trace.go:171","msg":"trace[1717708597] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"249.347319ms","start":"2026-10-26T01:52:20.339236Z","end":"2026-10-26T01:52:20.588583Z","steps":["trace[1717708597] 'process raft request' (duration: 249.014769ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:52:20.588651Z","caller":"traceutil/trace.go:171","msg":"trace[1990753915] linearizableReadLoop","detail":"{readStateIndex:26843; appliedIndex:26839; }","duration":"243.96328ms","start":"2026-10-26T01:52:20.344664Z","end":"2026-10-26T01:52:20.588627Z","steps":["trace[1990753915] 'read index received' (duration: 106.350369ms)","trace[1990753915] 'applied index is now lower than readState.Index' (duration: 137.611911ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T01:52:20.588801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.121725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T01:52:20.588868Z","caller":"traceutil/trace.go:171","msg":"trace[2137054612] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25993; }","duration":"244.226637ms","start":"2026-10-26T01:52:20.344604Z","end":"2026-10-26T01:52:20.588831Z","steps":["trace[2137054612] 'agreement among raft nodes before linearized reading' (duration: 244.114964ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T01:55:56.264089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25201} {"level":"info","ts":"2026-10-26T01:55:56.420363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25201,"took":"151.84714ms","hash":3191344339,"current-db-size-bytes":57483264,"current-db-size":"58 MB","current-db-size-in-use-bytes":38109184,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-26T01:55:56.420438Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3191344339,"revision":25201,"compact-revision":21934} {"level":"info","ts":"2026-10-26T01:57:49.752377Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T01:57:49.759278Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T01:57:49.759388Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T02:00:56.284753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28114} {"level":"info","ts":"2026-10-26T02:00:56.440466Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28114,"took":"150.693577ms","hash":222885588,"current-db-size-bytes":57483264,"current-db-size":"58 MB","current-db-size-in-use-bytes":32108544,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T02:00:56.442756Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":222885588,"revision":28114,"compact-revision":25201} {"level":"info","ts":"2026-10-26T02:05:56.293377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30532} {"level":"info","ts":"2026-10-26T02:05:56.436462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30532,"took":"137.97568ms","hash":4074800880,"current-db-size-bytes":57483264,"current-db-size":"58 MB","current-db-size-in-use-bytes":30593024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T02:05:56.43653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4074800880,"revision":30532,"compact-revision":28114} {"level":"info","ts":"2026-10-26T02:10:56.302489Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32824} {"level":"info","ts":"2026-10-26T02:10:56.465595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32824,"took":"158.406633ms","hash":1847009882,"current-db-size-bytes":57483264,"current-db-size":"58 MB","current-db-size-in-use-bytes":30248960,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-26T02:10:56.465663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1847009882,"revision":32824,"compact-revision":30532} {"level":"info","ts":"2026-10-26T02:15:56.311108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35049} {"level":"info","ts":"2026-10-26T02:15:56.464697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35049,"took":"143.216894ms","hash":3465152566,"current-db-size-bytes":57483264,"current-db-size":"58 MB","current-db-size-in-use-bytes":32604160,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T02:15:56.464773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3465152566,"revision":35049,"compact-revision":32824} {"level":"info","ts":"2026-10-26T02:19:08.605059Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca4c710a8d5349d7","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T02:19:08.611257Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T02:19:08.611632Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T02:20:56.322536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37342} {"level":"info","ts":"2026-10-26T02:20:56.458034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37342,"took":"131.348065ms","hash":4032154770,"current-db-size-bytes":57483264,"current-db-size":"58 MB","current-db-size-in-use-bytes":33230848,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T02:20:56.458125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4032154770,"revision":37342,"compact-revision":35049} {"level":"info","ts":"2026-10-26T02:25:56.328949Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39623} {"level":"info","ts":"2026-10-26T02:25:56.479964Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39623,"took":"146.036184ms","hash":1649106712,"current-db-size-bytes":57483264,"current-db-size":"58 MB","current-db-size-in-use-bytes":30965760,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T02:25:56.480038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1649106712,"revision":39623,"compact-revision":37342}