{"level":"warn","ts":"2026-03-02T21:24:11.045958Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T21:24:11.046039Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.145:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.145:2380","--initial-cluster=instance=https://199.19.213.145:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.145:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.145: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-03-02T21:24:11.046129Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T21:24:11.046143Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.145:2380"]} {"level":"info","ts":"2026-03-02T21:24:11.04616Z","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-03-02T21:24:11.046752Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"]} {"level":"info","ts":"2026-03-02T21:24:11.046823Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.145:2380"],"listen-peer-urls":["https://199.19.213.145:2380"],"advertise-client-urls":["https://199.19.213.145:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.145: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-03-02T21:24:11.05071Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.711066ms"} {"level":"info","ts":"2026-03-02T21:24:11.059768Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"76a9cbc7029401f7","cluster-id":"ebd7ebb015925037"} {"level":"info","ts":"2026-03-02T21:24:11.059878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T21:24:11.05991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became follower at term 0"} {"level":"info","ts":"2026-03-02T21:24:11.059935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 76a9cbc7029401f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T21:24:11.059957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became follower at term 1"} {"level":"info","ts":"2026-03-02T21:24:11.060014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=(8550589423141782007)"} {"level":"warn","ts":"2026-03-02T21:24:11.064692Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T21:24:11.067264Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T21:24:11.06891Z","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-03-02T21:24:11.070776Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"76a9cbc7029401f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T21:24:11.070876Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"76a9cbc7029401f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T21:24:11.071035Z","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-03-02T21:24:11.071133Z","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-03-02T21:24:11.071158Z","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-03-02T21:24:11.072804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=(8550589423141782007)"} {"level":"info","ts":"2026-03-02T21:24:11.073232Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ebd7ebb015925037","local-member-id":"76a9cbc7029401f7","added-peer-id":"76a9cbc7029401f7","added-peer-peer-urls":["https://199.19.213.145:2380"]} {"level":"info","ts":"2026-03-02T21:24:11.074892Z","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-03-02T21:24:11.075Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.145:2380"} {"level":"info","ts":"2026-03-02T21:24:11.075071Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.145:2380"} {"level":"info","ts":"2026-03-02T21:24:11.075167Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"76a9cbc7029401f7","initial-advertise-peer-urls":["https://199.19.213.145:2380"],"listen-peer-urls":["https://199.19.213.145:2380"],"advertise-client-urls":["https://199.19.213.145:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T21:24:11.075201Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T21:24:11.361018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T21:24:11.361067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T21:24:11.361093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 received MsgPreVoteResp from 76a9cbc7029401f7 at term 1"} {"level":"info","ts":"2026-03-02T21:24:11.361107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became candidate at term 2"} {"level":"info","ts":"2026-03-02T21:24:11.361115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 received MsgVoteResp from 76a9cbc7029401f7 at term 2"} {"level":"info","ts":"2026-03-02T21:24:11.361126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became leader at term 2"} {"level":"info","ts":"2026-03-02T21:24:11.361135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 76a9cbc7029401f7 elected leader 76a9cbc7029401f7 at term 2"} {"level":"info","ts":"2026-03-02T21:24:11.361915Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:24:11.362562Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"76a9cbc7029401f7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.145:2379]}","request-path":"/0/members/76a9cbc7029401f7/attributes","cluster-id":"ebd7ebb015925037","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T21:24:11.362691Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:24:11.362774Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:24:11.363066Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T21:24:11.36313Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T21:24:11.36351Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ebd7ebb015925037","local-member-id":"76a9cbc7029401f7","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:24:11.363714Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:24:11.363782Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:24:11.367177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T21:24:11.367615Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.145:2379"} {"level":"info","ts":"2026-03-02T21:24:16.069751Z","caller":"traceutil/trace.go:171","msg":"trace[1258084126] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"102.975844ms","start":"2026-03-02T21:24:15.966738Z","end":"2026-03-02T21:24:16.069714Z","steps":["trace[1258084126] 'process raft request' (duration: 102.76558ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:24:23.307244Z","caller":"traceutil/trace.go:171","msg":"trace[356168140] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"143.866575ms","start":"2026-03-02T21:24:23.16335Z","end":"2026-03-02T21:24:23.307216Z","steps":["trace[356168140] 'process raft request' (duration: 143.684401ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:24:47.392808Z","caller":"traceutil/trace.go:171","msg":"trace[1998414892] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"163.869443ms","start":"2026-03-02T21:24:47.228919Z","end":"2026-03-02T21:24:47.392788Z","steps":["trace[1998414892] 'process raft request' (duration: 163.72321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:24:47.717931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.638561ms","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-03-02T21:24:47.71806Z","caller":"traceutil/trace.go:171","msg":"trace[1192259451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"168.836405ms","start":"2026-03-02T21:24:47.549203Z","end":"2026-03-02T21:24:47.718039Z","steps":["trace[1192259451] 'range keys from in-memory index tree' (duration: 168.474247ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:24:47.961641Z","caller":"traceutil/trace.go:171","msg":"trace[99741035] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"239.228882ms","start":"2026-03-02T21:24:47.722378Z","end":"2026-03-02T21:24:47.961607Z","steps":["trace[99741035] 'process raft request' (duration: 239.030689ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:24:47.967672Z","caller":"traceutil/trace.go:171","msg":"trace[1139273066] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"240.67267ms","start":"2026-03-02T21:24:47.726986Z","end":"2026-03-02T21:24:47.967658Z","steps":["trace[1139273066] 'process raft request' (duration: 240.633039ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:24:47.967762Z","caller":"traceutil/trace.go:171","msg":"trace[880279673] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"245.121663ms","start":"2026-03-02T21:24:47.722598Z","end":"2026-03-02T21:24:47.967719Z","steps":["trace[880279673] 'process raft request' (duration: 244.94504ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:26:02.876425Z","caller":"traceutil/trace.go:171","msg":"trace[1725779699] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"264.673586ms","start":"2026-03-02T21:26:02.611715Z","end":"2026-03-02T21:26:02.876388Z","steps":["trace[1725779699] 'process raft request' (duration: 264.423602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:26:03.240747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.279239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:26:03.240862Z","caller":"traceutil/trace.go:171","msg":"trace[2006497208] linearizableReadLoop","detail":"{readStateIndex:1650; appliedIndex:1649; }","duration":"247.919625ms","start":"2026-03-02T21:26:02.992924Z","end":"2026-03-02T21:26:03.240844Z","steps":["trace[2006497208] 'read index received' (duration: 2.897282ms)","trace[2006497208] 'applied index is now lower than readState.Index' (duration: 245.021062ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:26:03.240999Z","caller":"traceutil/trace.go:171","msg":"trace[394902828] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"345.317578ms","start":"2026-03-02T21:26:02.895611Z","end":"2026-03-02T21:26:03.240928Z","steps":["trace[394902828] 'process raft request' (duration: 100.220354ms)","trace[394902828] 'compare' (duration: 244.144507ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:26:03.241127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.206149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-02T21:26:03.241174Z","caller":"traceutil/trace.go:171","msg":"trace[704698317] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1587; }","duration":"248.287461ms","start":"2026-03-02T21:26:02.992872Z","end":"2026-03-02T21:26:03.241159Z","steps":["trace[704698317] 'agreement among raft nodes before linearized reading' (duration: 248.066477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:26:03.24121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.550009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-02T21:26:03.241247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:26:02.895571Z","time spent":"345.539471ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:26:03.241322Z","caller":"traceutil/trace.go:171","msg":"trace[1695003300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1587; }","duration":"136.710822ms","start":"2026-03-02T21:26:03.104575Z","end":"2026-03-02T21:26:03.241285Z","steps":["trace[1695003300] 'agreement among raft nodes before linearized reading' (duration: 136.414167ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:26:19.519949Z","caller":"traceutil/trace.go:171","msg":"trace[1033587984] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"194.085294ms","start":"2026-03-02T21:26:19.325833Z","end":"2026-03-02T21:26:19.519918Z","steps":["trace[1033587984] 'process raft request' (duration: 193.86646ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:26:19.521293Z","caller":"traceutil/trace.go:171","msg":"trace[1413320730] linearizableReadLoop","detail":"{readStateIndex:1787; appliedIndex:1786; }","duration":"168.489554ms","start":"2026-03-02T21:26:19.352787Z","end":"2026-03-02T21:26:19.521277Z","steps":["trace[1413320730] 'read index received' (duration: 167.11095ms)","trace[1413320730] 'applied index is now lower than readState.Index' (duration: 1.377794ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:26:19.521369Z","caller":"traceutil/trace.go:171","msg":"trace[856885676] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"187.015458ms","start":"2026-03-02T21:26:19.334312Z","end":"2026-03-02T21:26:19.521327Z","steps":["trace[856885676] 'process raft request' (duration: 186.775823ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:26:19.521486Z","caller":"traceutil/trace.go:171","msg":"trace[1979840099] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"144.067015ms","start":"2026-03-02T21:26:19.377399Z","end":"2026-03-02T21:26:19.521466Z","steps":["trace[1979840099] 'process raft request' (duration: 143.83824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:26:19.521492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.692649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-02T21:26:19.52155Z","caller":"traceutil/trace.go:171","msg":"trace[684415986] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1721; }","duration":"168.846812ms","start":"2026-03-02T21:26:19.352688Z","end":"2026-03-02T21:26:19.521535Z","steps":["trace[684415986] 'agreement among raft nodes before linearized reading' (duration: 168.667619ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:26:58.001788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.169602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:26:58.001957Z","caller":"traceutil/trace.go:171","msg":"trace[1491952765] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1987; }","duration":"216.644881ms","start":"2026-03-02T21:26:57.785295Z","end":"2026-03-02T21:26:58.00194Z","steps":["trace[1491952765] 'read index received' (duration: 60.160543ms)","trace[1491952765] 'applied index is now lower than readState.Index' (duration: 156.483008ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:26:58.00199Z","caller":"traceutil/trace.go:171","msg":"trace[2016022818] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"288.619377ms","start":"2026-03-02T21:26:57.713339Z","end":"2026-03-02T21:26:58.001958Z","steps":["trace[2016022818] 'process raft request' (duration: 132.122709ms)","trace[2016022818] 'compare' (duration: 155.922778ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:26:58.002157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.865435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-02T21:26:58.002201Z","caller":"traceutil/trace.go:171","msg":"trace[1238481732] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1912; }","duration":"216.949996ms","start":"2026-03-02T21:26:57.785238Z","end":"2026-03-02T21:26:58.002188Z","steps":["trace[1238481732] 'agreement among raft nodes before linearized reading' (duration: 216.819404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:26:58.002203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.652677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:26:58.002314Z","caller":"traceutil/trace.go:171","msg":"trace[234439691] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1912; }","duration":"138.8342ms","start":"2026-03-02T21:26:57.863444Z","end":"2026-03-02T21:26:58.002279Z","steps":["trace[234439691] 'agreement among raft nodes before linearized reading' (duration: 138.656747ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:26:58.292491Z","caller":"traceutil/trace.go:171","msg":"trace[589971024] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"154.972012ms","start":"2026-03-02T21:26:58.137495Z","end":"2026-03-02T21:26:58.292467Z","steps":["trace[589971024] 'process raft request' (duration: 154.821529ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:26:58.379612Z","caller":"traceutil/trace.go:171","msg":"trace[1315406556] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1988; }","duration":"120.607282ms","start":"2026-03-02T21:26:58.258968Z","end":"2026-03-02T21:26:58.379575Z","steps":["trace[1315406556] 'read index received' (duration: 33.436872ms)","trace[1315406556] 'applied index is now lower than readState.Index' (duration: 87.16898ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:26:58.379698Z","caller":"traceutil/trace.go:171","msg":"trace[1475850082] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"239.228569ms","start":"2026-03-02T21:26:58.140433Z","end":"2026-03-02T21:26:58.379662Z","steps":["trace[1475850082] 'process raft request' (duration: 222.938355ms)","trace[1475850082] 'compare' (duration: 16.05617ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:26:58.379763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.769695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:26:58.379913Z","caller":"traceutil/trace.go:171","msg":"trace[483455194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1914; }","duration":"120.984589ms","start":"2026-03-02T21:26:58.258911Z","end":"2026-03-02T21:26:58.379896Z","steps":["trace[483455194] 'agreement among raft nodes before linearized reading' (duration: 120.784115ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:28:08.198038Z","caller":"traceutil/trace.go:171","msg":"trace[722402149] transaction","detail":"{read_only:false; response_revision:2454; number_of_response:1; }","duration":"135.841118ms","start":"2026-03-02T21:28:08.062171Z","end":"2026-03-02T21:28:08.198012Z","steps":["trace[722402149] 'process raft request' (duration: 135.703136ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:30:33.231348Z","caller":"traceutil/trace.go:171","msg":"trace[1644832315] transaction","detail":"{read_only:false; response_revision:3345; number_of_response:1; }","duration":"111.357002ms","start":"2026-03-02T21:30:33.119956Z","end":"2026-03-02T21:30:33.231313Z","steps":["trace[1644832315] 'process raft request' (duration: 98.613015ms)","trace[1644832315] 'compare' (duration: 12.532633ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:31:58.693358Z","caller":"traceutil/trace.go:171","msg":"trace[1182865770] linearizableReadLoop","detail":"{readStateIndex:4688; appliedIndex:4686; }","duration":"284.360089ms","start":"2026-03-02T21:31:58.408968Z","end":"2026-03-02T21:31:58.693328Z","steps":["trace[1182865770] 'read index received' (duration: 86.142575ms)","trace[1182865770] 'applied index is now lower than readState.Index' (duration: 198.216244ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:31:58.693579Z","caller":"traceutil/trace.go:171","msg":"trace[2046380103] transaction","detail":"{read_only:false; response_revision:4511; number_of_response:1; }","duration":"291.161885ms","start":"2026-03-02T21:31:58.402388Z","end":"2026-03-02T21:31:58.69355Z","steps":["trace[2046380103] 'process raft request' (duration: 250.436156ms)","trace[2046380103] 'compare' (duration: 40.326791ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:31:58.693625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.634535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-03-02T21:31:58.693667Z","caller":"traceutil/trace.go:171","msg":"trace[1436428318] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4511; }","duration":"284.704096ms","start":"2026-03-02T21:31:58.40895Z","end":"2026-03-02T21:31:58.693654Z","steps":["trace[1436428318] 'agreement among raft nodes before linearized reading' (duration: 284.479002ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:31:58.697683Z","caller":"traceutil/trace.go:171","msg":"trace[15370313] transaction","detail":"{read_only:false; response_revision:4512; number_of_response:1; }","duration":"125.019589ms","start":"2026-03-02T21:31:58.572636Z","end":"2026-03-02T21:31:58.697656Z","steps":["trace[15370313] 'process raft request' (duration: 124.800086ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:32:30.46675Z","caller":"traceutil/trace.go:171","msg":"trace[1192619955] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5256; }","duration":"103.089323ms","start":"2026-03-02T21:32:30.363636Z","end":"2026-03-02T21:32:30.466726Z","steps":["trace[1192619955] 'read index received' (duration: 102.904579ms)","trace[1192619955] 'applied index is now lower than readState.Index' (duration: 183.754µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:32:30.466798Z","caller":"traceutil/trace.go:171","msg":"trace[98069436] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"108.207718ms","start":"2026-03-02T21:32:30.358557Z","end":"2026-03-02T21:32:30.466765Z","steps":["trace[98069436] 'process raft request' (duration: 108.021515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:32:30.466925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.276447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-02T21:32:30.466958Z","caller":"traceutil/trace.go:171","msg":"trace[1905920681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5064; }","duration":"103.347598ms","start":"2026-03-02T21:32:30.363602Z","end":"2026-03-02T21:32:30.46695Z","steps":["trace[1905920681] 'agreement among raft nodes before linearized reading' (duration: 103.214945ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:34:12.076378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2767} {"level":"info","ts":"2026-03-02T21:34:12.135371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2767,"took":"57.412711ms","hash":1463300086,"current-db-size-bytes":41975808,"current-db-size":"42 MB","current-db-size-in-use-bytes":31879168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-02T21:34:12.135465Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1463300086,"revision":2767,"compact-revision":-1} {"level":"info","ts":"2026-03-02T21:38:16.54682Z","caller":"traceutil/trace.go:171","msg":"trace[1435738937] transaction","detail":"{read_only:false; response_revision:7520; number_of_response:1; }","duration":"318.836761ms","start":"2026-03-02T21:38:16.227958Z","end":"2026-03-02T21:38:16.546795Z","steps":["trace[1435738937] 'process raft request' (duration: 318.685639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:38:16.547011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:38:16.227939Z","time spent":"318.976394ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:38:16.671479Z","caller":"traceutil/trace.go:171","msg":"trace[908138095] linearizableReadLoop","detail":"{readStateIndex:7796; appliedIndex:7794; }","duration":"412.944385ms","start":"2026-03-02T21:38:16.258509Z","end":"2026-03-02T21:38:16.671454Z","steps":["trace[908138095] 'read index received' (duration: 288.227861ms)","trace[908138095] 'applied index is now lower than readState.Index' (duration: 124.715584ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:38:16.671588Z","caller":"traceutil/trace.go:171","msg":"trace[1413813515] transaction","detail":"{read_only:false; response_revision:7521; number_of_response:1; }","duration":"441.073218ms","start":"2026-03-02T21:38:16.230464Z","end":"2026-03-02T21:38:16.671538Z","steps":["trace[1413813515] 'process raft request' (duration: 381.784394ms)","trace[1413813515] 'compare' (duration: 59.008769ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:38:16.671643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.120868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:38:16.671695Z","caller":"traceutil/trace.go:171","msg":"trace[760645441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7521; }","duration":"413.21626ms","start":"2026-03-02T21:38:16.258467Z","end":"2026-03-02T21:38:16.671683Z","steps":["trace[760645441] 'agreement among raft nodes before linearized reading' (duration: 413.127468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:38:16.671727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:38:16.23044Z","time spent":"441.215462ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:38:16.67173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:38:16.258447Z","time spent":"413.274611ms","remote":"127.0.0.1:59750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T21:38:27.788727Z","caller":"traceutil/trace.go:171","msg":"trace[2507374] transaction","detail":"{read_only:false; response_revision:7575; number_of_response:1; }","duration":"109.112513ms","start":"2026-03-02T21:38:27.679591Z","end":"2026-03-02T21:38:27.788704Z","steps":["trace[2507374] 'process raft request' (duration: 108.92428ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:38:28.000713Z","caller":"traceutil/trace.go:171","msg":"trace[685519462] transaction","detail":"{read_only:false; response_revision:7576; number_of_response:1; }","duration":"252.191569ms","start":"2026-03-02T21:38:27.748497Z","end":"2026-03-02T21:38:28.000689Z","steps":["trace[685519462] 'process raft request' (duration: 194.958943ms)","trace[685519462] 'compare' (duration: 56.734757ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:38:28.920661Z","caller":"traceutil/trace.go:171","msg":"trace[1612681324] linearizableReadLoop","detail":"{readStateIndex:7855; appliedIndex:7854; }","duration":"198.290206ms","start":"2026-03-02T21:38:28.722344Z","end":"2026-03-02T21:38:28.920634Z","steps":["trace[1612681324] 'read index received' (duration: 198.109702ms)","trace[1612681324] 'applied index is now lower than readState.Index' (duration: 179.364µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:38:28.920709Z","caller":"traceutil/trace.go:171","msg":"trace[1361502242] transaction","detail":"{read_only:false; response_revision:7577; number_of_response:1; }","duration":"304.958363ms","start":"2026-03-02T21:38:28.61572Z","end":"2026-03-02T21:38:28.920679Z","steps":["trace[1361502242] 'process raft request' (duration: 304.730618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:38:28.920859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:38:28.615688Z","time spent":"305.083425ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:38:28.921142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.788275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-02T21:38:28.921195Z","caller":"traceutil/trace.go:171","msg":"trace[1834612100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7577; }","duration":"198.857736ms","start":"2026-03-02T21:38:28.722323Z","end":"2026-03-02T21:38:28.92118Z","steps":["trace[1834612100] 'agreement among raft nodes before linearized reading' (duration: 198.437428ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:38:28.93851Z","caller":"traceutil/trace.go:171","msg":"trace[787293414] transaction","detail":"{read_only:false; response_revision:7578; number_of_response:1; }","duration":"100.218698ms","start":"2026-03-02T21:38:28.838277Z","end":"2026-03-02T21:38:28.938495Z","steps":["trace[787293414] 'process raft request' (duration: 99.978824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:38:28.938565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.038881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-02T21:38:28.938654Z","caller":"traceutil/trace.go:171","msg":"trace[357539661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7578; }","duration":"117.132863ms","start":"2026-03-02T21:38:28.8215Z","end":"2026-03-02T21:38:28.938633Z","steps":["trace[357539661] 'agreement among raft nodes before linearized reading' (duration: 116.906558ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:39:09.230128Z","caller":"traceutil/trace.go:171","msg":"trace[1885187447] linearizableReadLoop","detail":"{readStateIndex:8160; appliedIndex:8159; }","duration":"100.211387ms","start":"2026-03-02T21:39:09.129891Z","end":"2026-03-02T21:39:09.230102Z","steps":["trace[1885187447] 'read index received' (duration: 2.936824ms)","trace[1885187447] 'applied index is now lower than readState.Index' (duration: 97.273603ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:39:09.230219Z","caller":"traceutil/trace.go:171","msg":"trace[820895968] transaction","detail":"{read_only:false; response_revision:7870; number_of_response:1; }","duration":"157.531696ms","start":"2026-03-02T21:39:09.072644Z","end":"2026-03-02T21:39:09.230175Z","steps":["trace[820895968] 'process raft request' (duration: 60.215452ms)","trace[820895968] 'compare' (duration: 97.142051ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:39:09.230353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.444552ms","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-03-02T21:39:09.230405Z","caller":"traceutil/trace.go:171","msg":"trace[955562959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7870; }","duration":"100.534314ms","start":"2026-03-02T21:39:09.129856Z","end":"2026-03-02T21:39:09.23039Z","steps":["trace[955562959] 'agreement among raft nodes before linearized reading' (duration: 100.380091ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:39:09.367632Z","caller":"traceutil/trace.go:171","msg":"trace[1158011501] transaction","detail":"{read_only:false; response_revision:7872; number_of_response:1; }","duration":"132.657452ms","start":"2026-03-02T21:39:09.234955Z","end":"2026-03-02T21:39:09.367613Z","steps":["trace[1158011501] 'process raft request' (duration: 119.103709ms)","trace[1158011501] 'compare' (duration: 13.41092ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:39:09.367789Z","caller":"traceutil/trace.go:171","msg":"trace[1828463372] transaction","detail":"{read_only:false; response_revision:7874; number_of_response:1; }","duration":"129.743507ms","start":"2026-03-02T21:39:09.238039Z","end":"2026-03-02T21:39:09.367782Z","steps":["trace[1828463372] 'process raft request' (duration: 129.717186ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:39:09.367903Z","caller":"traceutil/trace.go:171","msg":"trace[1895030825] transaction","detail":"{read_only:false; response_revision:7873; number_of_response:1; }","duration":"129.911ms","start":"2026-03-02T21:39:09.237987Z","end":"2026-03-02T21:39:09.367898Z","steps":["trace[1895030825] 'process raft request' (duration: 129.596254ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:39:12.08218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5816} {"level":"info","ts":"2026-03-02T21:39:12.18276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5816,"took":"97.10357ms","hash":2900848830,"current-db-size-bytes":43872256,"current-db-size":"44 MB","current-db-size-in-use-bytes":23699456,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-02T21:39:12.18284Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2900848830,"revision":5816,"compact-revision":2767} {"level":"info","ts":"2026-03-02T21:40:11.795718Z","caller":"traceutil/trace.go:171","msg":"trace[890429040] transaction","detail":"{read_only:false; response_revision:8507; number_of_response:1; }","duration":"101.436257ms","start":"2026-03-02T21:40:11.694251Z","end":"2026-03-02T21:40:11.795687Z","steps":["trace[890429040] 'process raft request' (duration: 101.252243ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:40:11.796917Z","caller":"traceutil/trace.go:171","msg":"trace[169047757] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"102.552278ms","start":"2026-03-02T21:40:11.694344Z","end":"2026-03-02T21:40:11.796897Z","steps":["trace[169047757] 'process raft request' (duration: 102.345294ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:40:23.992068Z","caller":"traceutil/trace.go:171","msg":"trace[1900051611] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"104.77483ms","start":"2026-03-02T21:40:23.887255Z","end":"2026-03-02T21:40:23.99203Z","steps":["trace[1900051611] 'process raft request' (duration: 99.722196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:40:24.257705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.746549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:40:24.257807Z","caller":"traceutil/trace.go:171","msg":"trace[917539696] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8574; }","duration":"154.912901ms","start":"2026-03-02T21:40:24.102873Z","end":"2026-03-02T21:40:24.257785Z","steps":["trace[917539696] 'range keys from in-memory index tree' (duration: 154.629627ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:42:43.220609Z","caller":"traceutil/trace.go:171","msg":"trace[1247453765] transaction","detail":"{read_only:false; response_revision:9559; number_of_response:1; }","duration":"105.871803ms","start":"2026-03-02T21:42:43.114714Z","end":"2026-03-02T21:42:43.220586Z","steps":["trace[1247453765] 'process raft request' (duration: 105.739051ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:42:56.109593Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"76a9cbc7029401f7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-02T21:42:56.115874Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-02T21:42:56.115985Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-02T21:44:09.173291Z","caller":"traceutil/trace.go:171","msg":"trace[1783921058] transaction","detail":"{read_only:false; response_revision:10316; number_of_response:1; }","duration":"130.43829ms","start":"2026-03-02T21:44:09.042811Z","end":"2026-03-02T21:44:09.173249Z","steps":["trace[1783921058] 'process raft request' (duration: 124.151293ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:44:09.187769Z","caller":"traceutil/trace.go:171","msg":"trace[9746079] linearizableReadLoop","detail":"{readStateIndex:10681; appliedIndex:10679; }","duration":"124.385709ms","start":"2026-03-02T21:44:09.063355Z","end":"2026-03-02T21:44:09.18774Z","steps":["trace[9746079] 'read index received' (duration: 103.623371ms)","trace[9746079] 'applied index is now lower than readState.Index' (duration: 20.760928ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:44:09.187836Z","caller":"traceutil/trace.go:171","msg":"trace[1584162391] transaction","detail":"{read_only:false; response_revision:10317; number_of_response:1; }","duration":"144.116916ms","start":"2026-03-02T21:44:09.043691Z","end":"2026-03-02T21:44:09.187808Z","steps":["trace[1584162391] 'process raft request' (duration: 143.910742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:44:09.187972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.602482ms","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-03-02T21:44:09.188011Z","caller":"traceutil/trace.go:171","msg":"trace[1016102635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10317; }","duration":"124.723624ms","start":"2026-03-02T21:44:09.063277Z","end":"2026-03-02T21:44:09.188Z","steps":["trace[1016102635] 'agreement among raft nodes before linearized reading' (duration: 124.569472ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:44:12.095514Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7898} {"level":"info","ts":"2026-03-02T21:44:12.162068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7898,"took":"62.995804ms","hash":3576378064,"current-db-size-bytes":43872256,"current-db-size":"44 MB","current-db-size-in-use-bytes":27926528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-02T21:44:12.16215Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3576378064,"revision":7898,"compact-revision":5816} {"level":"info","ts":"2026-03-02T21:49:12.102407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10330} {"level":"info","ts":"2026-03-02T21:49:12.180703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10330,"took":"75.935489ms","hash":1650189390,"current-db-size-bytes":43872256,"current-db-size":"44 MB","current-db-size-in-use-bytes":24825856,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-02T21:49:12.180777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1650189390,"revision":10330,"compact-revision":7898} {"level":"info","ts":"2026-03-02T21:50:03.127223Z","caller":"traceutil/trace.go:171","msg":"trace[189588891] transaction","detail":"{read_only:false; response_revision:12546; number_of_response:1; }","duration":"143.008683ms","start":"2026-03-02T21:50:02.984133Z","end":"2026-03-02T21:50:03.127142Z","steps":["trace[189588891] 'process raft request' (duration: 140.614359ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:03.129047Z","caller":"traceutil/trace.go:171","msg":"trace[1062738224] linearizableReadLoop","detail":"{readStateIndex:12997; appliedIndex:12992; }","duration":"117.213474ms","start":"2026-03-02T21:50:03.011815Z","end":"2026-03-02T21:50:03.129028Z","steps":["trace[1062738224] 'read index received' (duration: 113.112578ms)","trace[1062738224] 'applied index is now lower than readState.Index' (duration: 4.099736ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:50:03.129254Z","caller":"traceutil/trace.go:171","msg":"trace[2122445674] transaction","detail":"{read_only:false; response_revision:12547; number_of_response:1; }","duration":"125.165442ms","start":"2026-03-02T21:50:03.00408Z","end":"2026-03-02T21:50:03.129246Z","steps":["trace[2122445674] 'process raft request' (duration: 124.730824ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:03.129456Z","caller":"traceutil/trace.go:171","msg":"trace[1293611644] transaction","detail":"{read_only:false; response_revision:12548; number_of_response:1; }","duration":"124.400498ms","start":"2026-03-02T21:50:03.00505Z","end":"2026-03-02T21:50:03.129451Z","steps":["trace[1293611644] 'process raft request' (duration: 123.872638ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:03.129591Z","caller":"traceutil/trace.go:171","msg":"trace[2066489465] transaction","detail":"{read_only:false; response_revision:12549; number_of_response:1; }","duration":"124.168864ms","start":"2026-03-02T21:50:03.005417Z","end":"2026-03-02T21:50:03.129586Z","steps":["trace[2066489465] 'process raft request' (duration: 123.544092ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:03.129716Z","caller":"traceutil/trace.go:171","msg":"trace[1438890831] transaction","detail":"{read_only:false; response_revision:12550; number_of_response:1; }","duration":"124.155473ms","start":"2026-03-02T21:50:03.005551Z","end":"2026-03-02T21:50:03.129707Z","steps":["trace[1438890831] 'process raft request' (duration: 123.44921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:50:03.12986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.038829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-02T21:50:03.129883Z","caller":"traceutil/trace.go:171","msg":"trace[1682982949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12550; }","duration":"118.109731ms","start":"2026-03-02T21:50:03.011768Z","end":"2026-03-02T21:50:03.129878Z","steps":["trace[1682982949] 'agreement among raft nodes before linearized reading' (duration: 118.011689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:50:03.130078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.474706ms","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-03-02T21:50:03.130099Z","caller":"traceutil/trace.go:171","msg":"trace[1429050261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12550; }","duration":"112.541698ms","start":"2026-03-02T21:50:03.017553Z","end":"2026-03-02T21:50:03.130095Z","steps":["trace[1429050261] 'agreement among raft nodes before linearized reading' (duration: 112.396825ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:23.341008Z","caller":"traceutil/trace.go:171","msg":"trace[375750516] linearizableReadLoop","detail":"{readStateIndex:13097; appliedIndex:13096; }","duration":"120.22864ms","start":"2026-03-02T21:50:23.22076Z","end":"2026-03-02T21:50:23.340988Z","steps":["trace[375750516] 'read index received' (duration: 120.053647ms)","trace[375750516] 'applied index is now lower than readState.Index' (duration: 174.193µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:50:23.341066Z","caller":"traceutil/trace.go:171","msg":"trace[133434290] transaction","detail":"{read_only:false; response_revision:12646; number_of_response:1; }","duration":"121.665708ms","start":"2026-03-02T21:50:23.219356Z","end":"2026-03-02T21:50:23.341022Z","steps":["trace[133434290] 'process raft request' (duration: 121.505955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:50:23.341218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.439354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-02T21:50:23.341258Z","caller":"traceutil/trace.go:171","msg":"trace[352244861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12646; }","duration":"120.507766ms","start":"2026-03-02T21:50:23.220741Z","end":"2026-03-02T21:50:23.341249Z","steps":["trace[352244861] 'agreement among raft nodes before linearized reading' (duration: 120.366773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:50:23.34148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.432265ms","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-03-02T21:50:23.341612Z","caller":"traceutil/trace.go:171","msg":"trace[954753122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12646; }","duration":"120.589547ms","start":"2026-03-02T21:50:23.221Z","end":"2026-03-02T21:50:23.34159Z","steps":["trace[954753122] 'agreement among raft nodes before linearized reading' (duration: 120.232751ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:23.458025Z","caller":"traceutil/trace.go:171","msg":"trace[1193282816] transaction","detail":"{read_only:false; response_revision:12647; number_of_response:1; }","duration":"111.860206ms","start":"2026-03-02T21:50:23.346141Z","end":"2026-03-02T21:50:23.458001Z","steps":["trace[1193282816] 'process raft request' (duration: 110.132253ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:23.458334Z","caller":"traceutil/trace.go:171","msg":"trace[124891623] transaction","detail":"{read_only:false; response_revision:12648; number_of_response:1; }","duration":"112.124981ms","start":"2026-03-02T21:50:23.346198Z","end":"2026-03-02T21:50:23.458323Z","steps":["trace[124891623] 'process raft request' (duration: 111.763924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:50:39.137621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.000372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-02T21:50:39.137733Z","caller":"traceutil/trace.go:171","msg":"trace[1459290700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12736; }","duration":"206.155105ms","start":"2026-03-02T21:50:38.93156Z","end":"2026-03-02T21:50:39.137716Z","steps":["trace[1459290700] 'range keys from in-memory index tree' (duration: 205.779148ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:50:50.347566Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000033ef.wal"} {"level":"info","ts":"2026-03-02T21:51:00.613188Z","caller":"traceutil/trace.go:171","msg":"trace[851615855] linearizableReadLoop","detail":"{readStateIndex:13501; appliedIndex:13500; }","duration":"186.508294ms","start":"2026-03-02T21:51:00.426656Z","end":"2026-03-02T21:51:00.613164Z","steps":["trace[851615855] 'read index received' (duration: 186.361852ms)","trace[851615855] 'applied index is now lower than readState.Index' (duration: 145.422µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:51:00.613421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.750478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dc4eb351-7da7-4e4b-aa5e-084e6d90ab3b\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-03-02T21:51:00.613464Z","caller":"traceutil/trace.go:171","msg":"trace[1159629367] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dc4eb351-7da7-4e4b-aa5e-084e6d90ab3b; range_end:; response_count:1; response_revision:13032; }","duration":"186.812809ms","start":"2026-03-02T21:51:00.426639Z","end":"2026-03-02T21:51:00.613452Z","steps":["trace[1159629367] 'agreement among raft nodes before linearized reading' (duration: 186.631616ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:51:00.613502Z","caller":"traceutil/trace.go:171","msg":"trace[1037818069] transaction","detail":"{read_only:false; response_revision:13032; number_of_response:1; }","duration":"229.79212ms","start":"2026-03-02T21:51:00.383681Z","end":"2026-03-02T21:51:00.613473Z","steps":["trace[1037818069] 'process raft request' (duration: 229.318111ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:51:54.348922Z","caller":"traceutil/trace.go:171","msg":"trace[1817312796] transaction","detail":"{read_only:false; response_revision:13646; number_of_response:1; }","duration":"121.673587ms","start":"2026-03-02T21:51:54.227192Z","end":"2026-03-02T21:51:54.348866Z","steps":["trace[1817312796] 'process raft request' (duration: 121.442613ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:52:27.181587Z","caller":"traceutil/trace.go:171","msg":"trace[638423406] linearizableReadLoop","detail":"{readStateIndex:14292; appliedIndex:14291; }","duration":"175.06187ms","start":"2026-03-02T21:52:27.006502Z","end":"2026-03-02T21:52:27.181564Z","steps":["trace[638423406] 'read index received' (duration: 174.895747ms)","trace[638423406] 'applied index is now lower than readState.Index' (duration: 165.093µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:52:27.181655Z","caller":"traceutil/trace.go:171","msg":"trace[795572689] transaction","detail":"{read_only:false; response_revision:13800; number_of_response:1; }","duration":"229.683977ms","start":"2026-03-02T21:52:26.95194Z","end":"2026-03-02T21:52:27.181624Z","steps":["trace[795572689] 'process raft request' (duration: 229.469964ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:52:27.181736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.217463ms","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-03-02T21:52:27.181769Z","caller":"traceutil/trace.go:171","msg":"trace[402006126] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:13800; }","duration":"175.300975ms","start":"2026-03-02T21:52:27.006458Z","end":"2026-03-02T21:52:27.181759Z","steps":["trace[402006126] 'agreement among raft nodes before linearized reading' (duration: 175.236694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:52:27.182023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.289849ms","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-03-02T21:52:27.182065Z","caller":"traceutil/trace.go:171","msg":"trace[1883226123] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13800; }","duration":"121.36982ms","start":"2026-03-02T21:52:27.060687Z","end":"2026-03-02T21:52:27.182056Z","steps":["trace[1883226123] 'agreement among raft nodes before linearized reading' (duration: 121.311979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:52:27.182195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.55622ms","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-03-02T21:52:27.182256Z","caller":"traceutil/trace.go:171","msg":"trace[2141287039] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13800; }","duration":"175.624501ms","start":"2026-03-02T21:52:27.006615Z","end":"2026-03-02T21:52:27.182239Z","steps":["trace[2141287039] 'agreement among raft nodes before linearized reading' (duration: 175.480478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:52:27.182283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.395207ms","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-03-02T21:52:27.182314Z","caller":"traceutil/trace.go:171","msg":"trace[345489048] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13800; }","duration":"116.426638ms","start":"2026-03-02T21:52:27.065879Z","end":"2026-03-02T21:52:27.182306Z","steps":["trace[345489048] 'agreement among raft nodes before linearized reading' (duration: 116.319296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:52:27.182461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.862704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T21:52:27.18248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.645585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-03-02T21:52:27.182498Z","caller":"traceutil/trace.go:171","msg":"trace[2108296065] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13800; }","duration":"175.931276ms","start":"2026-03-02T21:52:27.006559Z","end":"2026-03-02T21:52:27.18249Z","steps":["trace[2108296065] 'agreement among raft nodes before linearized reading' (duration: 175.244604ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:52:27.18251Z","caller":"traceutil/trace.go:171","msg":"trace[318133770] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:13800; }","duration":"121.691976ms","start":"2026-03-02T21:52:27.06081Z","end":"2026-03-02T21:52:27.182502Z","steps":["trace[318133770] 'agreement among raft nodes before linearized reading' (duration: 121.596515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:52:27.182791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.178642ms","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-03-02T21:52:27.182845Z","caller":"traceutil/trace.go:171","msg":"trace[1078511541] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:13800; }","duration":"176.243833ms","start":"2026-03-02T21:52:27.006591Z","end":"2026-03-02T21:52:27.182835Z","steps":["trace[1078511541] 'agreement among raft nodes before linearized reading' (duration: 176.175991ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:54:12.109675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12235} {"level":"info","ts":"2026-03-02T21:54:12.19117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12235,"took":"79.231035ms","hash":1544788213,"current-db-size-bytes":43872256,"current-db-size":"44 MB","current-db-size-in-use-bytes":28037120,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-02T21:54:12.19123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1544788213,"revision":12235,"compact-revision":10330} {"level":"info","ts":"2026-03-02T21:58:22.651252Z","caller":"traceutil/trace.go:171","msg":"trace[1111692207] transaction","detail":"{read_only:false; response_revision:16250; number_of_response:1; }","duration":"267.749587ms","start":"2026-03-02T21:58:22.383485Z","end":"2026-03-02T21:58:22.651235Z","steps":["trace[1111692207] 'process raft request' (duration: 267.609724ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:58:22.708662Z","caller":"traceutil/trace.go:171","msg":"trace[1141563661] transaction","detail":"{read_only:false; response_revision:16251; number_of_response:1; }","duration":"310.972961ms","start":"2026-03-02T21:58:22.397663Z","end":"2026-03-02T21:58:22.708636Z","steps":["trace[1141563661] 'process raft request' (duration: 310.152516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:58:22.709822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:58:22.397649Z","time spent":"312.076203ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20988,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:58:22.709008Z","caller":"traceutil/trace.go:171","msg":"trace[364027680] transaction","detail":"{read_only:false; response_revision:16252; number_of_response:1; }","duration":"228.465574ms","start":"2026-03-02T21:58:22.480518Z","end":"2026-03-02T21:58:22.708984Z","steps":["trace[364027680] 'process raft request' (duration: 227.455965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:58:23.324842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.647647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:58:23.324937Z","caller":"traceutil/trace.go:171","msg":"trace[128637348] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:16252; }","duration":"462.761709ms","start":"2026-03-02T21:58:22.862153Z","end":"2026-03-02T21:58:23.324915Z","steps":["trace[128637348] 'count revisions from in-memory index tree' (duration: 462.549014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:58:23.324981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:58:22.8621Z","time spent":"462.869581ms","remote":"127.0.0.1:52640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"info","ts":"2026-03-02T21:59:12.117226Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14414} {"level":"info","ts":"2026-03-02T21:59:12.2105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14414,"took":"90.707709ms","hash":3712124232,"current-db-size-bytes":43872256,"current-db-size":"44 MB","current-db-size-in-use-bytes":30040064,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-02T21:59:12.210582Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3712124232,"revision":14414,"compact-revision":12235} {"level":"info","ts":"2026-03-02T22:04:04.586894Z","caller":"traceutil/trace.go:171","msg":"trace[1238607074] transaction","detail":"{read_only:false; response_revision:18803; number_of_response:1; }","duration":"205.950982ms","start":"2026-03-02T22:04:04.380914Z","end":"2026-03-02T22:04:04.586865Z","steps":["trace[1238607074] 'process raft request' (duration: 133.395592ms)","trace[1238607074] 'compare' (duration: 72.440428ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:04:10.109079Z","caller":"traceutil/trace.go:171","msg":"trace[777773759] transaction","detail":"{read_only:false; response_revision:18829; number_of_response:1; }","duration":"154.923054ms","start":"2026-03-02T22:04:09.953986Z","end":"2026-03-02T22:04:10.108909Z","steps":["trace[777773759] 'process raft request' (duration: 154.650578ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:04:10.109286Z","caller":"traceutil/trace.go:171","msg":"trace[358376981] transaction","detail":"{read_only:false; response_revision:18830; number_of_response:1; }","duration":"107.109354ms","start":"2026-03-02T22:04:10.002138Z","end":"2026-03-02T22:04:10.109247Z","steps":["trace[358376981] 'process raft request' (duration: 106.715567ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:04:10.248964Z","caller":"traceutil/trace.go:171","msg":"trace[159572389] transaction","detail":"{read_only:false; response_revision:18831; number_of_response:1; }","duration":"137.229274ms","start":"2026-03-02T22:04:10.111706Z","end":"2026-03-02T22:04:10.248935Z","steps":["trace[159572389] 'process raft request' (duration: 110.833952ms)","trace[159572389] 'compare' (duration: 25.936932ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:04:10.762681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.755208ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-02T22:04:10.762844Z","caller":"traceutil/trace.go:171","msg":"trace[844931943] linearizableReadLoop","detail":"{readStateIndex:19500; appliedIndex:19499; }","duration":"146.879633ms","start":"2026-03-02T22:04:10.615936Z","end":"2026-03-02T22:04:10.762816Z","steps":["trace[844931943] 'read index received' (duration: 55.171µs)","trace[844931943] 'applied index is now lower than readState.Index' (duration: 146.822732ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:04:10.763116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.158569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-02T22:04:10.763187Z","caller":"traceutil/trace.go:171","msg":"trace[1406148493] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18832; }","duration":"147.26351ms","start":"2026-03-02T22:04:10.615899Z","end":"2026-03-02T22:04:10.763163Z","steps":["trace[1406148493] 'agreement among raft nodes before linearized reading' (duration: 146.981085ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:04:12.142879Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16869} {"level":"info","ts":"2026-03-02T22:04:12.253708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16869,"took":"106.89175ms","hash":3159948570,"current-db-size-bytes":43872256,"current-db-size":"44 MB","current-db-size-in-use-bytes":29888512,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-02T22:04:12.253875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3159948570,"revision":16869,"compact-revision":14414} {"level":"info","ts":"2026-03-02T22:04:55.667432Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"76a9cbc7029401f7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-02T22:04:55.67309Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-03-02T22:04:55.673161Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-03-02T22:08:04.154757Z","caller":"traceutil/trace.go:171","msg":"trace[1725192596] linearizableReadLoop","detail":"{readStateIndex:21918; appliedIndex:21917; }","duration":"119.49461ms","start":"2026-03-02T22:08:04.035236Z","end":"2026-03-02T22:08:04.154731Z","steps":["trace[1725192596] 'read index received' (duration: 119.262286ms)","trace[1725192596] 'applied index is now lower than readState.Index' (duration: 231.454µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:08:04.154816Z","caller":"traceutil/trace.go:171","msg":"trace[198469561] transaction","detail":"{read_only:false; response_revision:21177; number_of_response:1; }","duration":"125.806127ms","start":"2026-03-02T22:08:04.02898Z","end":"2026-03-02T22:08:04.154786Z","steps":["trace[198469561] 'process raft request' (duration: 125.569422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:08:04.154968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.711734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-manager\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-02T22:08:04.155041Z","caller":"traceutil/trace.go:171","msg":"trace[1516309265] range","detail":"{range_begin:/registry/serviceaccounts/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-manager; range_end:; response_count:1; response_revision:21177; }","duration":"119.823515ms","start":"2026-03-02T22:08:04.035204Z","end":"2026-03-02T22:08:04.155027Z","steps":["trace[1516309265] 'agreement among raft nodes before linearized reading' (duration: 119.636812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:08:04.578741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.480088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-02T22:08:04.578778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.24365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18242"} {"level":"info","ts":"2026-03-02T22:08:04.578825Z","caller":"traceutil/trace.go:171","msg":"trace[1697645916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21177; }","duration":"320.60144ms","start":"2026-03-02T22:08:04.258205Z","end":"2026-03-02T22:08:04.578806Z","steps":["trace[1697645916] 'range keys from in-memory index tree' (duration: 320.414947ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:08:04.578865Z","caller":"traceutil/trace.go:171","msg":"trace[1476270829] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21177; }","duration":"234.386053ms","start":"2026-03-02T22:08:04.34446Z","end":"2026-03-02T22:08:04.578846Z","steps":["trace[1476270829] 'range keys from in-memory index tree' (duration: 234.051597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:08:04.578887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:08:04.258187Z","time spent":"320.689741ms","remote":"127.0.0.1:59750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T22:08:04.578896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.235365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-02T22:08:04.579687Z","caller":"traceutil/trace.go:171","msg":"trace[1481279590] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:21177; }","duration":"246.202642ms","start":"2026-03-02T22:08:04.333453Z","end":"2026-03-02T22:08:04.579655Z","steps":["trace[1481279590] 'count revisions from in-memory index tree' (duration: 245.148112ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:09:12.15129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18841} {"level":"info","ts":"2026-03-02T22:09:12.272821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18841,"took":"114.515446ms","hash":392775788,"current-db-size-bytes":45572096,"current-db-size":"46 MB","current-db-size-in-use-bytes":38432768,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-03-02T22:09:12.272919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":392775788,"revision":18841,"compact-revision":16869} {"level":"info","ts":"2026-03-02T22:11:34.787864Z","caller":"traceutil/trace.go:171","msg":"trace[323493664] transaction","detail":"{read_only:false; response_revision:23206; number_of_response:1; }","duration":"157.106861ms","start":"2026-03-02T22:11:34.630737Z","end":"2026-03-02T22:11:34.787844Z","steps":["trace[323493664] 'process raft request' (duration: 156.978929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.938738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.030958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-02T22:11:47.938855Z","caller":"traceutil/trace.go:171","msg":"trace[1661225295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23298; }","duration":"144.17944ms","start":"2026-03-02T22:11:47.794648Z","end":"2026-03-02T22:11:47.938828Z","steps":["trace[1661225295] 'range keys from in-memory index tree' (duration: 143.874395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:47.940059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.217971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:47.940139Z","caller":"traceutil/trace.go:171","msg":"trace[2062723907] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23298; }","duration":"173.326184ms","start":"2026-03-02T22:11:47.766796Z","end":"2026-03-02T22:11:47.940122Z","steps":["trace[2062723907] 'range keys from in-memory index tree' (duration: 173.16078ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:48.010294Z","caller":"traceutil/trace.go:171","msg":"trace[1159960523] transaction","detail":"{read_only:false; response_revision:23299; number_of_response:1; }","duration":"127.734422ms","start":"2026-03-02T22:11:47.882539Z","end":"2026-03-02T22:11:48.010273Z","steps":["trace[1159960523] 'process raft request' (duration: 127.6092ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:48.212564Z","caller":"traceutil/trace.go:171","msg":"trace[137573676] transaction","detail":"{read_only:false; response_revision:23300; number_of_response:1; }","duration":"270.089248ms","start":"2026-03-02T22:11:47.942428Z","end":"2026-03-02T22:11:48.212517Z","steps":["trace[137573676] 'process raft request' (duration: 269.848483ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:48.213123Z","caller":"traceutil/trace.go:171","msg":"trace[2124025886] transaction","detail":"{read_only:false; response_revision:23301; number_of_response:1; }","duration":"265.795969ms","start":"2026-03-02T22:11:47.947317Z","end":"2026-03-02T22:11:48.213113Z","steps":["trace[2124025886] 'process raft request' (duration: 265.098295ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:48.213583Z","caller":"traceutil/trace.go:171","msg":"trace[2106705687] transaction","detail":"{read_only:false; response_revision:23302; number_of_response:1; }","duration":"248.238661ms","start":"2026-03-02T22:11:47.965329Z","end":"2026-03-02T22:11:48.213567Z","steps":["trace[2106705687] 'process raft request' (duration: 247.735802ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:48.213526Z","caller":"traceutil/trace.go:171","msg":"trace[1656669969] linearizableReadLoop","detail":"{readStateIndex:24102; appliedIndex:24099; }","duration":"202.818454ms","start":"2026-03-02T22:11:48.01068Z","end":"2026-03-02T22:11:48.213499Z","steps":["trace[1656669969] 'read index received' (duration: 201.57566ms)","trace[1656669969] 'applied index is now lower than readState.Index' (duration: 1.241764ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:48.213967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.460966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:48.214092Z","caller":"traceutil/trace.go:171","msg":"trace[1876662463] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23302; }","duration":"270.664459ms","start":"2026-03-02T22:11:47.943403Z","end":"2026-03-02T22:11:48.214067Z","steps":["trace[1876662463] 'agreement among raft nodes before linearized reading' (duration: 270.279212ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:48.299813Z","caller":"traceutil/trace.go:171","msg":"trace[751261211] transaction","detail":"{read_only:false; response_revision:23303; number_of_response:1; }","duration":"284.751812ms","start":"2026-03-02T22:11:48.015028Z","end":"2026-03-02T22:11:48.29978Z","steps":["trace[751261211] 'process raft request' (duration: 233.927653ms)","trace[751261211] 'compare' (duration: 50.030084ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:48.300057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.996694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:48.300135Z","caller":"traceutil/trace.go:171","msg":"trace[1363824021] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:23303; }","duration":"227.136767ms","start":"2026-03-02T22:11:48.072982Z","end":"2026-03-02T22:11:48.300119Z","steps":["trace[1363824021] 'agreement among raft nodes before linearized reading' (duration: 226.962643ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:53.000101Z","caller":"traceutil/trace.go:171","msg":"trace[1889158505] transaction","detail":"{read_only:false; response_revision:23335; number_of_response:1; }","duration":"166.393123ms","start":"2026-03-02T22:11:52.833678Z","end":"2026-03-02T22:11:53.000071Z","steps":["trace[1889158505] 'process raft request' (duration: 115.885421ms)","trace[1889158505] 'compare' (duration: 50.3771ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:53.034329Z","caller":"traceutil/trace.go:171","msg":"trace[901743152] transaction","detail":"{read_only:false; response_revision:23336; number_of_response:1; }","duration":"124.551115ms","start":"2026-03-02T22:11:52.909752Z","end":"2026-03-02T22:11:53.034303Z","steps":["trace[901743152] 'process raft request' (duration: 124.396032ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:53.302052Z","caller":"traceutil/trace.go:171","msg":"trace[1498717898] transaction","detail":"{read_only:false; response_revision:23338; number_of_response:1; }","duration":"311.921558ms","start":"2026-03-02T22:11:52.9901Z","end":"2026-03-02T22:11:53.302021Z","steps":["trace[1498717898] 'process raft request' (duration: 311.831427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:53.30225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:52.990069Z","time spent":"312.071131ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:53.302074Z","caller":"traceutil/trace.go:171","msg":"trace[801186397] transaction","detail":"{read_only:false; response_revision:23337; number_of_response:1; }","duration":"313.234744ms","start":"2026-03-02T22:11:52.988817Z","end":"2026-03-02T22:11:53.302051Z","steps":["trace[801186397] 'process raft request' (duration: 234.346592ms)","trace[801186397] 'compare' (duration: 78.327062ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:11:53.302488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:52.988796Z","time spent":"313.59108ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:53.590877Z","caller":"traceutil/trace.go:171","msg":"trace[700489531] transaction","detail":"{read_only:false; response_revision:23339; number_of_response:1; }","duration":"105.835203ms","start":"2026-03-02T22:11:53.485016Z","end":"2026-03-02T22:11:53.590851Z","steps":["trace[700489531] 'process raft request' (duration: 105.629469ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:53.862467Z","caller":"traceutil/trace.go:171","msg":"trace[1780820829] transaction","detail":"{read_only:false; response_revision:23340; number_of_response:1; }","duration":"134.275045ms","start":"2026-03-02T22:11:53.728148Z","end":"2026-03-02T22:11:53.862423Z","steps":["trace[1780820829] 'process raft request' (duration: 133.537202ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:54.117132Z","caller":"traceutil/trace.go:171","msg":"trace[853350793] transaction","detail":"{read_only:false; response_revision:23341; number_of_response:1; }","duration":"110.269497ms","start":"2026-03-02T22:11:54.006823Z","end":"2026-03-02T22:11:54.117092Z","steps":["trace[853350793] 'process raft request' (duration: 110.012572ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:11:54.581924Z","caller":"traceutil/trace.go:171","msg":"trace[1422914506] linearizableReadLoop","detail":"{readStateIndex:24144; appliedIndex:24143; }","duration":"311.052062ms","start":"2026-03-02T22:11:54.270826Z","end":"2026-03-02T22:11:54.581878Z","steps":["trace[1422914506] 'read index received' (duration: 310.763577ms)","trace[1422914506] 'applied index is now lower than readState.Index' (duration: 286.615µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:54.582048Z","caller":"traceutil/trace.go:171","msg":"trace[2122128681] transaction","detail":"{read_only:false; response_revision:23343; number_of_response:1; }","duration":"320.352886ms","start":"2026-03-02T22:11:54.261644Z","end":"2026-03-02T22:11:54.581997Z","steps":["trace[2122128681] 'process raft request' (duration: 319.871807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:54.582359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:54.261571Z","time spent":"320.642161ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:11:54.582572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.703155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-02T22:11:54.582677Z","caller":"traceutil/trace.go:171","msg":"trace[1119067975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23343; }","duration":"311.867057ms","start":"2026-03-02T22:11:54.270785Z","end":"2026-03-02T22:11:54.582653Z","steps":["trace[1119067975] 'agreement among raft nodes before linearized reading' (duration: 311.262576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:54.582736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:54.270736Z","time spent":"311.990289ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T22:11:54.600063Z","caller":"traceutil/trace.go:171","msg":"trace[1947412988] transaction","detail":"{read_only:false; response_revision:23344; number_of_response:1; }","duration":"304.335077ms","start":"2026-03-02T22:11:54.295708Z","end":"2026-03-02T22:11:54.600043Z","steps":["trace[1947412988] 'process raft request' (duration: 304.091713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:54.600073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.116764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:54.600147Z","caller":"traceutil/trace.go:171","msg":"trace[452711693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23344; }","duration":"326.231446ms","start":"2026-03-02T22:11:54.273888Z","end":"2026-03-02T22:11:54.600119Z","steps":["trace[452711693] 'agreement among raft nodes before linearized reading' (duration: 326.104523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:54.600184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:54.273863Z","time spent":"326.314758ms","remote":"127.0.0.1:59750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T22:11:54.600197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:54.295678Z","time spent":"304.431909ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:54.78935Z","caller":"traceutil/trace.go:171","msg":"trace[1380630088] transaction","detail":"{read_only:false; response_revision:23346; number_of_response:1; }","duration":"183.748257ms","start":"2026-03-02T22:11:54.605559Z","end":"2026-03-02T22:11:54.789307Z","steps":["trace[1380630088] 'process raft request' (duration: 105.682261ms)","trace[1380630088] 'compare' (duration: 77.413184ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:55.349417Z","caller":"traceutil/trace.go:171","msg":"trace[370896188] transaction","detail":"{read_only:false; response_revision:23349; number_of_response:1; }","duration":"336.721321ms","start":"2026-03-02T22:11:55.012671Z","end":"2026-03-02T22:11:55.349392Z","steps":["trace[370896188] 'process raft request' (duration: 336.544458ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:55.349572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.012659Z","time spent":"336.833043ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:11:56.013155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.062717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:11:56.013477Z","caller":"traceutil/trace.go:171","msg":"trace[832933757] transaction","detail":"{read_only:false; response_revision:23350; number_of_response:1; }","duration":"973.406907ms","start":"2026-03-02T22:11:55.040016Z","end":"2026-03-02T22:11:56.013423Z","steps":["trace[832933757] 'process raft request' (duration: 575.81235ms)","trace[832933757] 'compare' (duration: 396.07669ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:56.013681Z","caller":"traceutil/trace.go:171","msg":"trace[1209556419] transaction","detail":"{read_only:false; response_revision:23351; number_of_response:1; }","duration":"704.135995ms","start":"2026-03-02T22:11:55.309518Z","end":"2026-03-02T22:11:56.013654Z","steps":["trace[1209556419] 'process raft request' (duration: 703.817789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.013762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.039991Z","time spent":"973.596361ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:11:56.013803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.309492Z","time spent":"704.248667ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:56.014211Z","caller":"traceutil/trace.go:171","msg":"trace[1330469402] linearizableReadLoop","detail":"{readStateIndex:24153; appliedIndex:24150; }","duration":"697.342937ms","start":"2026-03-02T22:11:55.316855Z","end":"2026-03-02T22:11:56.014198Z","steps":["trace[1330469402] 'read index received' (duration: 32.519216ms)","trace[1330469402] 'applied index is now lower than readState.Index' (duration: 664.822511ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:56.014328Z","caller":"traceutil/trace.go:171","msg":"trace[1378738704] transaction","detail":"{read_only:false; response_revision:23352; number_of_response:1; }","duration":"703.32176ms","start":"2026-03-02T22:11:55.310996Z","end":"2026-03-02T22:11:56.014317Z","steps":["trace[1378738704] 'process raft request' (duration: 702.594176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.01438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.310971Z","time spent":"703.376551ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:11:56.014771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.905399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.145\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-03-02T22:11:56.014868Z","caller":"traceutil/trace.go:171","msg":"trace[61825150] range","detail":"{range_begin:/registry/masterleases/199.19.213.145; range_end:; response_count:1; response_revision:23352; }","duration":"698.002951ms","start":"2026-03-02T22:11:55.316829Z","end":"2026-03-02T22:11:56.014832Z","steps":["trace[61825150] 'agreement among raft nodes before linearized reading' (duration: 697.644544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.014944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.316818Z","time spent":"698.104894ms","remote":"127.0.0.1:59772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.19.213.145\" "} {"level":"info","ts":"2026-03-02T22:11:56.029737Z","caller":"traceutil/trace.go:171","msg":"trace[692419696] transaction","detail":"{read_only:false; response_revision:23353; number_of_response:1; }","duration":"160.382541ms","start":"2026-03-02T22:11:55.869338Z","end":"2026-03-02T22:11:56.02972Z","steps":["trace[692419696] 'process raft request' (duration: 159.896433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.029772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.733683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:56.029872Z","caller":"traceutil/trace.go:171","msg":"trace[1656363270] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:23353; }","duration":"172.878336ms","start":"2026-03-02T22:11:55.856965Z","end":"2026-03-02T22:11:56.029843Z","steps":["trace[1656363270] 'agreement among raft nodes before linearized reading' (duration: 172.630121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.029744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.031828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-02T22:11:56.030424Z","caller":"traceutil/trace.go:171","msg":"trace[1614783948] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23353; }","duration":"433.767502ms","start":"2026-03-02T22:11:55.596629Z","end":"2026-03-02T22:11:56.030397Z","steps":["trace[1614783948] 'agreement among raft nodes before linearized reading' (duration: 432.864975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.030685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:55.596608Z","time spent":"433.909354ms","remote":"127.0.0.1:60000","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-03-02T22:11:56.539346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.903793ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-02T22:11:56.53964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:56.016561Z","time spent":"523.071758ms","remote":"127.0.0.1:59772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-02T22:11:56.543854Z","caller":"traceutil/trace.go:171","msg":"trace[1224137517] linearizableReadLoop","detail":"{readStateIndex:24156; appliedIndex:24154; }","duration":"506.919275ms","start":"2026-03-02T22:11:56.036895Z","end":"2026-03-02T22:11:56.543814Z","steps":["trace[1224137517] 'read index received' (duration: 282.432637ms)","trace[1224137517] 'applied index is now lower than readState.Index' (duration: 224.485068ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:11:56.544682Z","caller":"traceutil/trace.go:171","msg":"trace[558604583] transaction","detail":"{read_only:false; response_revision:23354; number_of_response:1; }","duration":"507.889605ms","start":"2026-03-02T22:11:56.036756Z","end":"2026-03-02T22:11:56.544646Z","steps":["trace[558604583] 'process raft request' (duration: 506.667902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.544887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:56.036723Z","time spent":"508.043907ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:11:56.545207Z","caller":"traceutil/trace.go:171","msg":"trace[1676625730] transaction","detail":"{read_only:false; response_revision:23355; number_of_response:1; }","duration":"415.751285ms","start":"2026-03-02T22:11:56.129434Z","end":"2026-03-02T22:11:56.545186Z","steps":["trace[1676625730] 'process raft request' (duration: 414.295307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.545314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:56.12939Z","time spent":"415.856107ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:11:56.545483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.553086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:56.545586Z","caller":"traceutil/trace.go:171","msg":"trace[289643584] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:23355; }","duration":"508.740839ms","start":"2026-03-02T22:11:56.036823Z","end":"2026-03-02T22:11:56.545564Z","steps":["trace[289643584] 'agreement among raft nodes before linearized reading' (duration: 508.548935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.545651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:56.036786Z","time spent":"508.851441ms","remote":"127.0.0.1:59934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-03-02T22:11:56.605075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.740904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:11:56.605572Z","caller":"traceutil/trace.go:171","msg":"trace[1144937657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23356; }","duration":"320.283785ms","start":"2026-03-02T22:11:56.285261Z","end":"2026-03-02T22:11:56.605545Z","steps":["trace[1144937657] 'agreement among raft nodes before linearized reading' (duration: 319.685983ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:11:56.60563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:11:56.285248Z","time spent":"320.366187ms","remote":"127.0.0.1:59750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T22:12:07.105724Z","caller":"traceutil/trace.go:171","msg":"trace[728660127] transaction","detail":"{read_only:false; response_revision:23431; number_of_response:1; }","duration":"135.12767ms","start":"2026-03-02T22:12:06.970566Z","end":"2026-03-02T22:12:07.105694Z","steps":["trace[728660127] 'process raft request' (duration: 134.431248ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:14.854964Z","caller":"traceutil/trace.go:171","msg":"trace[935895339] transaction","detail":"{read_only:false; response_revision:23474; number_of_response:1; }","duration":"281.844058ms","start":"2026-03-02T22:12:14.573097Z","end":"2026-03-02T22:12:14.854941Z","steps":["trace[935895339] 'process raft request' (duration: 281.676515ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:14.858674Z","caller":"traceutil/trace.go:171","msg":"trace[631911724] transaction","detail":"{read_only:false; response_revision:23475; number_of_response:1; }","duration":"282.963139ms","start":"2026-03-02T22:12:14.575686Z","end":"2026-03-02T22:12:14.858649Z","steps":["trace[631911724] 'process raft request' (duration: 282.732415ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:14.858868Z","caller":"traceutil/trace.go:171","msg":"trace[2062801114] transaction","detail":"{read_only:false; response_revision:23477; number_of_response:1; }","duration":"281.645024ms","start":"2026-03-02T22:12:14.577206Z","end":"2026-03-02T22:12:14.858852Z","steps":["trace[2062801114] 'process raft request' (duration: 281.400679ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:14.858827Z","caller":"traceutil/trace.go:171","msg":"trace[114625264] transaction","detail":"{read_only:false; response_revision:23476; number_of_response:1; }","duration":"282.519039ms","start":"2026-03-02T22:12:14.576251Z","end":"2026-03-02T22:12:14.85877Z","steps":["trace[114625264] 'process raft request' (duration: 282.309185ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:14.858878Z","caller":"traceutil/trace.go:171","msg":"trace[613405227] transaction","detail":"{read_only:false; response_revision:23478; number_of_response:1; }","duration":"244.503051ms","start":"2026-03-02T22:12:14.614313Z","end":"2026-03-02T22:12:14.858816Z","steps":["trace[613405227] 'process raft request' (duration: 244.42029ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:14.859052Z","caller":"traceutil/trace.go:171","msg":"trace[57381114] linearizableReadLoop","detail":"{readStateIndex:24284; appliedIndex:24279; }","duration":"199.576533ms","start":"2026-03-02T22:12:14.65946Z","end":"2026-03-02T22:12:14.859037Z","steps":["trace[57381114] 'read index received' (duration: 195.341715ms)","trace[57381114] 'applied index is now lower than readState.Index' (duration: 4.233898ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:14.85905Z","caller":"traceutil/trace.go:171","msg":"trace[27032832] transaction","detail":"{read_only:false; response_revision:23479; number_of_response:1; }","duration":"165.130889ms","start":"2026-03-02T22:12:14.693911Z","end":"2026-03-02T22:12:14.859042Z","steps":["trace[27032832] 'process raft request' (duration: 164.870554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:14.85918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.694346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:12:14.859459Z","caller":"traceutil/trace.go:171","msg":"trace[277592851] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:23480; }","duration":"199.991532ms","start":"2026-03-02T22:12:14.659454Z","end":"2026-03-02T22:12:14.859445Z","steps":["trace[277592851] 'agreement among raft nodes before linearized reading' (duration: 199.662595ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:17.126119Z","caller":"traceutil/trace.go:171","msg":"trace[1755432856] transaction","detail":"{read_only:false; response_revision:23490; number_of_response:1; }","duration":"264.110988ms","start":"2026-03-02T22:12:16.861975Z","end":"2026-03-02T22:12:17.126086Z","steps":["trace[1755432856] 'process raft request' (duration: 263.009697ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:17.127315Z","caller":"traceutil/trace.go:171","msg":"trace[2048422327] linearizableReadLoop","detail":"{readStateIndex:24302; appliedIndex:24296; }","duration":"242.582125ms","start":"2026-03-02T22:12:16.884704Z","end":"2026-03-02T22:12:17.127286Z","steps":["trace[2048422327] 'read index received' (duration: 240.16438ms)","trace[2048422327] 'applied index is now lower than readState.Index' (duration: 2.416745ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:17.127546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.818379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-02T22:12:17.127577Z","caller":"traceutil/trace.go:171","msg":"trace[167268485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23496; }","duration":"242.90649ms","start":"2026-03-02T22:12:16.88466Z","end":"2026-03-02T22:12:17.127566Z","steps":["trace[167268485] 'agreement among raft nodes before linearized reading' (duration: 242.731087ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:17.127946Z","caller":"traceutil/trace.go:171","msg":"trace[1628106107] transaction","detail":"{read_only:false; response_revision:23491; number_of_response:1; }","duration":"262.536497ms","start":"2026-03-02T22:12:16.865399Z","end":"2026-03-02T22:12:17.127935Z","steps":["trace[1628106107] 'process raft request' (duration: 261.539459ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:17.128062Z","caller":"traceutil/trace.go:171","msg":"trace[1356955289] transaction","detail":"{read_only:false; response_revision:23492; number_of_response:1; }","duration":"260.890987ms","start":"2026-03-02T22:12:16.867163Z","end":"2026-03-02T22:12:17.128054Z","steps":["trace[1356955289] 'process raft request' (duration: 259.925169ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:17.128161Z","caller":"traceutil/trace.go:171","msg":"trace[1297787942] transaction","detail":"{read_only:false; response_revision:23493; number_of_response:1; }","duration":"259.846577ms","start":"2026-03-02T22:12:16.868307Z","end":"2026-03-02T22:12:17.128154Z","steps":["trace[1297787942] 'process raft request' (duration: 258.824868ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:17.128285Z","caller":"traceutil/trace.go:171","msg":"trace[686143140] transaction","detail":"{read_only:false; response_revision:23494; number_of_response:1; }","duration":"259.629563ms","start":"2026-03-02T22:12:16.868647Z","end":"2026-03-02T22:12:17.128276Z","steps":["trace[686143140] 'process raft request' (duration: 258.528702ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:17.128397Z","caller":"traceutil/trace.go:171","msg":"trace[1346691868] transaction","detail":"{read_only:false; response_revision:23495; number_of_response:1; }","duration":"258.394461ms","start":"2026-03-02T22:12:16.869995Z","end":"2026-03-02T22:12:17.128389Z","steps":["trace[1346691868] 'process raft request' (duration: 257.220379ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:17.128498Z","caller":"traceutil/trace.go:171","msg":"trace[1340123849] transaction","detail":"{read_only:false; response_revision:23496; number_of_response:1; }","duration":"242.434543ms","start":"2026-03-02T22:12:16.886056Z","end":"2026-03-02T22:12:17.12849Z","steps":["trace[1340123849] 'process raft request' (duration: 241.193739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:19.481796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.930316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:12:19.48228Z","caller":"traceutil/trace.go:171","msg":"trace[1832890470] transaction","detail":"{read_only:false; response_revision:23506; number_of_response:1; }","duration":"343.501148ms","start":"2026-03-02T22:12:19.138755Z","end":"2026-03-02T22:12:19.482256Z","steps":["trace[1832890470] 'process raft request' (duration: 343.355516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:19.482402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:19.13872Z","time spent":"343.639491ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:12:19.48241Z","caller":"traceutil/trace.go:171","msg":"trace[1539196212] transaction","detail":"{read_only:false; response_revision:23504; number_of_response:1; }","duration":"345.777811ms","start":"2026-03-02T22:12:19.136605Z","end":"2026-03-02T22:12:19.482382Z","steps":["trace[1539196212] 'process raft request' (duration: 157.110091ms)","trace[1539196212] 'compare' (duration: 187.542218ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:19.482571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:19.136564Z","time spent":"345.918613ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:12:19.482562Z","caller":"traceutil/trace.go:171","msg":"trace[977781727] transaction","detail":"{read_only:false; response_revision:23505; number_of_response:1; }","duration":"345.72997ms","start":"2026-03-02T22:12:19.136767Z","end":"2026-03-02T22:12:19.482497Z","steps":["trace[977781727] 'process raft request' (duration: 345.170389ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:19.482677Z","caller":"traceutil/trace.go:171","msg":"trace[452431507] transaction","detail":"{read_only:false; response_revision:23507; number_of_response:1; }","duration":"343.647411ms","start":"2026-03-02T22:12:19.139022Z","end":"2026-03-02T22:12:19.48267Z","steps":["trace[452431507] 'process raft request' (duration: 343.152382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:19.482719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:19.139012Z","time spent":"343.682331ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:12:19.482758Z","caller":"traceutil/trace.go:171","msg":"trace[1712536448] linearizableReadLoop","detail":"{readStateIndex:24316; appliedIndex:24310; }","duration":"333.774336ms","start":"2026-03-02T22:12:19.148959Z","end":"2026-03-02T22:12:19.482733Z","steps":["trace[1712536448] 'read index received' (duration: 144.75342ms)","trace[1712536448] 'applied index is now lower than readState.Index' (duration: 189.019146ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:19.482786Z","caller":"traceutil/trace.go:171","msg":"trace[2006343109] transaction","detail":"{read_only:false; response_revision:23509; number_of_response:1; }","duration":"342.923127ms","start":"2026-03-02T22:12:19.139831Z","end":"2026-03-02T22:12:19.482754Z","steps":["trace[2006343109] 'process raft request' (duration: 342.806825ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:19.482809Z","caller":"traceutil/trace.go:171","msg":"trace[100603250] transaction","detail":"{read_only:false; response_revision:23508; number_of_response:1; }","duration":"343.443217ms","start":"2026-03-02T22:12:19.139312Z","end":"2026-03-02T22:12:19.482755Z","steps":["trace[100603250] 'process raft request' (duration: 343.182193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:19.482835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:19.13674Z","time spent":"345.953254ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:12:19.482928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:19.139822Z","time spent":"343.023789ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:12:19.483019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:19.139224Z","time spent":"343.670032ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:12:19.483117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.777551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-02T22:12:19.483053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.835049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-03-02T22:12:19.483177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.725543ms","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-03-02T22:12:19.483235Z","caller":"traceutil/trace.go:171","msg":"trace[1031411761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23509; }","duration":"312.792396ms","start":"2026-03-02T22:12:19.170416Z","end":"2026-03-02T22:12:19.483208Z","steps":["trace[1031411761] 'agreement among raft nodes before linearized reading' (duration: 312.644863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:19.483154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.704079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-02T22:12:19.483264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:19.170409Z","time spent":"312.850917ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T22:12:19.483165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.167854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-02T22:12:19.483317Z","caller":"traceutil/trace.go:171","msg":"trace[1104135987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23509; }","duration":"204.857442ms","start":"2026-03-02T22:12:19.278421Z","end":"2026-03-02T22:12:19.483278Z","steps":["trace[1104135987] 'agreement among raft nodes before linearized reading' (duration: 204.632268ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:19.483264Z","caller":"traceutil/trace.go:171","msg":"trace[843238588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23509; }","duration":"319.046762ms","start":"2026-03-02T22:12:19.164142Z","end":"2026-03-02T22:12:19.483188Z","steps":["trace[843238588] 'agreement among raft nodes before linearized reading' (duration: 318.767937ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:19.483201Z","caller":"traceutil/trace.go:171","msg":"trace[361694902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23509; }","duration":"307.919854ms","start":"2026-03-02T22:12:19.175257Z","end":"2026-03-02T22:12:19.483177Z","steps":["trace[361694902] 'agreement among raft nodes before linearized reading' (duration: 307.69629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:19.483418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:19.164116Z","time spent":"319.295136ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T22:12:19.483489Z","caller":"traceutil/trace.go:171","msg":"trace[948190877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23509; }","duration":"334.571481ms","start":"2026-03-02T22:12:19.14887Z","end":"2026-03-02T22:12:19.483442Z","steps":["trace[948190877] 'agreement among raft nodes before linearized reading' (duration: 333.9577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:19.483485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:19.175247Z","time spent":"308.188209ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T22:12:19.483572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:19.14882Z","time spent":"334.737814ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-02T22:12:19.613858Z","caller":"traceutil/trace.go:171","msg":"trace[956686774] transaction","detail":"{read_only:false; response_revision:23512; number_of_response:1; }","duration":"122.763701ms","start":"2026-03-02T22:12:19.491064Z","end":"2026-03-02T22:12:19.613828Z","steps":["trace[956686774] 'process raft request' (duration: 122.634218ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:19.613879Z","caller":"traceutil/trace.go:171","msg":"trace[1076595663] transaction","detail":"{read_only:false; response_revision:23511; number_of_response:1; }","duration":"123.474134ms","start":"2026-03-02T22:12:19.490346Z","end":"2026-03-02T22:12:19.61382Z","steps":["trace[1076595663] 'process raft request' (duration: 123.124037ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:19.614268Z","caller":"traceutil/trace.go:171","msg":"trace[428880537] transaction","detail":"{read_only:false; response_revision:23513; number_of_response:1; }","duration":"122.642489ms","start":"2026-03-02T22:12:19.491615Z","end":"2026-03-02T22:12:19.614257Z","steps":["trace[428880537] 'process raft request' (duration: 122.16752ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:29.331161Z","caller":"traceutil/trace.go:171","msg":"trace[1174663394] transaction","detail":"{read_only:false; response_revision:23629; number_of_response:1; }","duration":"150.61978ms","start":"2026-03-02T22:12:29.180513Z","end":"2026-03-02T22:12:29.331132Z","steps":["trace[1174663394] 'process raft request' (duration: 150.360364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:29.673807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.032172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-02T22:12:29.673898Z","caller":"traceutil/trace.go:171","msg":"trace[409049848] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:23629; }","duration":"133.145884ms","start":"2026-03-02T22:12:29.540733Z","end":"2026-03-02T22:12:29.673879Z","steps":["trace[409049848] 'count revisions from in-memory index tree' (duration: 132.892509ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:29.674103Z","caller":"traceutil/trace.go:171","msg":"trace[1071598397] transaction","detail":"{read_only:false; response_revision:23630; number_of_response:1; }","duration":"131.979941ms","start":"2026-03-02T22:12:29.542103Z","end":"2026-03-02T22:12:29.674083Z","steps":["trace[1071598397] 'process raft request' (duration: 94.153185ms)","trace[1071598397] 'compare' (duration: 37.530361ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:30.044289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.961971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:12:30.044538Z","caller":"traceutil/trace.go:171","msg":"trace[723589450] transaction","detail":"{read_only:false; response_revision:23633; number_of_response:1; }","duration":"496.564263ms","start":"2026-03-02T22:12:29.547957Z","end":"2026-03-02T22:12:30.044521Z","steps":["trace[723589450] 'process raft request' (duration: 496.484021ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:30.044546Z","caller":"traceutil/trace.go:171","msg":"trace[1287116595] transaction","detail":"{read_only:false; response_revision:23632; number_of_response:1; }","duration":"496.656084ms","start":"2026-03-02T22:12:29.547855Z","end":"2026-03-02T22:12:30.044511Z","steps":["trace[1287116595] 'process raft request' (duration: 496.530532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:30.044604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:29.547929Z","time spent":"496.642404ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:12:30.044591Z","caller":"traceutil/trace.go:171","msg":"trace[1456155723] transaction","detail":"{read_only:false; response_revision:23631; number_of_response:1; }","duration":"499.283143ms","start":"2026-03-02T22:12:29.545218Z","end":"2026-03-02T22:12:30.044501Z","steps":["trace[1456155723] 'process raft request' (duration: 292.996516ms)","trace[1456155723] 'compare' (duration: 205.825429ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:30.044708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:29.547841Z","time spent":"496.779556ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:12:30.044802Z","caller":"traceutil/trace.go:171","msg":"trace[1055348544] transaction","detail":"{read_only:false; response_revision:23634; number_of_response:1; }","duration":"494.30641ms","start":"2026-03-02T22:12:29.550487Z","end":"2026-03-02T22:12:30.044793Z","steps":["trace[1055348544] 'process raft request' (duration: 494.001145ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:30.044856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:29.550457Z","time spent":"494.368872ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:12:30.044859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:29.545183Z","time spent":"499.537358ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:12:30.044984Z","caller":"traceutil/trace.go:171","msg":"trace[1524415189] linearizableReadLoop","detail":"{readStateIndex:24444; appliedIndex:24438; }","duration":"440.842543ms","start":"2026-03-02T22:12:29.604125Z","end":"2026-03-02T22:12:30.044967Z","steps":["trace[1524415189] 'read index received' (duration: 32.219441ms)","trace[1524415189] 'applied index is now lower than readState.Index' (duration: 408.621962ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:30.045048Z","caller":"traceutil/trace.go:171","msg":"trace[2074702994] transaction","detail":"{read_only:false; response_revision:23635; number_of_response:1; }","duration":"441.882242ms","start":"2026-03-02T22:12:29.603117Z","end":"2026-03-02T22:12:30.044999Z","steps":["trace[2074702994] 'process raft request' (duration: 441.632148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:30.045209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.757004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-03-02T22:12:30.045234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.099968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-02T22:12:30.045252Z","caller":"traceutil/trace.go:171","msg":"trace[2039530441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23635; }","duration":"372.836365ms","start":"2026-03-02T22:12:29.672405Z","end":"2026-03-02T22:12:30.045242Z","steps":["trace[2039530441] 'agreement among raft nodes before linearized reading' (duration: 372.726553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:30.045282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:29.672381Z","time spent":"372.894386ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-02T22:12:30.045281Z","caller":"traceutil/trace.go:171","msg":"trace[1316420230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23635; }","duration":"441.178169ms","start":"2026-03-02T22:12:29.604089Z","end":"2026-03-02T22:12:30.045267Z","steps":["trace[1316420230] 'agreement among raft nodes before linearized reading' (duration: 441.014786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:30.045337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:29.604077Z","time spent":"441.243581ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-02T22:12:30.045414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.945421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3783a355-39a9-4c40-957b-7aaf463b4043\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-03-02T22:12:30.045394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.227758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-03-02T22:12:30.04543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.957744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-03-02T22:12:30.045447Z","caller":"traceutil/trace.go:171","msg":"trace[475900497] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3783a355-39a9-4c40-957b-7aaf463b4043; range_end:; response_count:1; response_revision:23635; }","duration":"230.021931ms","start":"2026-03-02T22:12:29.815416Z","end":"2026-03-02T22:12:30.045438Z","steps":["trace[475900497] 'agreement among raft nodes before linearized reading' (duration: 229.93741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:30.045262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:29.603084Z","time spent":"442.065005ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:12:30.045527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.62177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-02T22:12:30.045614Z","caller":"traceutil/trace.go:171","msg":"trace[1038380205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23635; }","duration":"299.848623ms","start":"2026-03-02T22:12:29.74575Z","end":"2026-03-02T22:12:30.045598Z","steps":["trace[1038380205] 'agreement among raft nodes before linearized reading' (duration: 299.65624ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:30.045493Z","caller":"traceutil/trace.go:171","msg":"trace[988476951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23635; }","duration":"346.085277ms","start":"2026-03-02T22:12:29.699395Z","end":"2026-03-02T22:12:30.04548Z","steps":["trace[988476951] 'agreement among raft nodes before linearized reading' (duration: 345.858092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:30.045674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:29.699366Z","time spent":"346.29349ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T22:12:30.045486Z","caller":"traceutil/trace.go:171","msg":"trace[162702259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23635; }","duration":"295.408961ms","start":"2026-03-02T22:12:29.750056Z","end":"2026-03-02T22:12:30.045465Z","steps":["trace[162702259] 'agreement among raft nodes before linearized reading' (duration: 295.149575ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:30.188644Z","caller":"traceutil/trace.go:171","msg":"trace[737470800] transaction","detail":"{read_only:false; response_revision:23636; number_of_response:1; }","duration":"133.814006ms","start":"2026-03-02T22:12:30.054812Z","end":"2026-03-02T22:12:30.188626Z","steps":["trace[737470800] 'process raft request' (duration: 133.693644ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:30.218484Z","caller":"traceutil/trace.go:171","msg":"trace[612970065] linearizableReadLoop","detail":"{readStateIndex:24451; appliedIndex:24445; }","duration":"131.958571ms","start":"2026-03-02T22:12:30.0865Z","end":"2026-03-02T22:12:30.218459Z","steps":["trace[612970065] 'read index received' (duration: 102.133245ms)","trace[612970065] 'applied index is now lower than readState.Index' (duration: 29.823526ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:12:30.218633Z","caller":"traceutil/trace.go:171","msg":"trace[2007540582] transaction","detail":"{read_only:false; response_revision:23641; number_of_response:1; }","duration":"159.7374ms","start":"2026-03-02T22:12:30.058868Z","end":"2026-03-02T22:12:30.218606Z","steps":["trace[2007540582] 'process raft request' (duration: 159.479505ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:30.218747Z","caller":"traceutil/trace.go:171","msg":"trace[481095471] transaction","detail":"{read_only:false; response_revision:23642; number_of_response:1; }","duration":"159.587497ms","start":"2026-03-02T22:12:30.059149Z","end":"2026-03-02T22:12:30.218736Z","steps":["trace[481095471] 'process raft request' (duration: 159.235761ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:30.218808Z","caller":"traceutil/trace.go:171","msg":"trace[1993301730] transaction","detail":"{read_only:false; response_revision:23637; number_of_response:1; }","duration":"161.495702ms","start":"2026-03-02T22:12:30.057305Z","end":"2026-03-02T22:12:30.218801Z","steps":["trace[1993301730] 'process raft request' (duration: 160.791619ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:30.218876Z","caller":"traceutil/trace.go:171","msg":"trace[821639060] transaction","detail":"{read_only:false; response_revision:23638; number_of_response:1; }","duration":"161.178146ms","start":"2026-03-02T22:12:30.057692Z","end":"2026-03-02T22:12:30.21887Z","steps":["trace[821639060] 'process raft request' (duration: 160.535085ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:30.218922Z","caller":"traceutil/trace.go:171","msg":"trace[560047383] transaction","detail":"{read_only:false; response_revision:23643; number_of_response:1; }","duration":"126.037491ms","start":"2026-03-02T22:12:30.092875Z","end":"2026-03-02T22:12:30.218913Z","steps":["trace[560047383] 'process raft request' (duration: 125.546392ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:30.218971Z","caller":"traceutil/trace.go:171","msg":"trace[647563264] transaction","detail":"{read_only:false; response_revision:23639; number_of_response:1; }","duration":"160.560375ms","start":"2026-03-02T22:12:30.058404Z","end":"2026-03-02T22:12:30.218965Z","steps":["trace[647563264] 'process raft request' (duration: 159.859732ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:30.219056Z","caller":"traceutil/trace.go:171","msg":"trace[2065288684] transaction","detail":"{read_only:false; response_revision:23640; number_of_response:1; }","duration":"160.282451ms","start":"2026-03-02T22:12:30.058768Z","end":"2026-03-02T22:12:30.21905Z","steps":["trace[2065288684] 'process raft request' (duration: 159.540056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:30.219055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.542143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3783a355-39a9-4c40-957b-7aaf463b4043\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-03-02T22:12:30.2192Z","caller":"traceutil/trace.go:171","msg":"trace[1660527768] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3783a355-39a9-4c40-957b-7aaf463b4043; range_end:; response_count:1; response_revision:23643; }","duration":"132.725736ms","start":"2026-03-02T22:12:30.086459Z","end":"2026-03-02T22:12:30.219185Z","steps":["trace[1660527768] 'agreement among raft nodes before linearized reading' (duration: 132.548643ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:38.555674Z","caller":"traceutil/trace.go:171","msg":"trace[353623771] transaction","detail":"{read_only:false; response_revision:23717; number_of_response:1; }","duration":"237.793676ms","start":"2026-03-02T22:12:38.317846Z","end":"2026-03-02T22:12:38.555639Z","steps":["trace[353623771] 'process raft request' (duration: 237.576692ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:38.7243Z","caller":"traceutil/trace.go:171","msg":"trace[762758536] transaction","detail":"{read_only:false; response_revision:23718; number_of_response:1; }","duration":"406.187078ms","start":"2026-03-02T22:12:38.318097Z","end":"2026-03-02T22:12:38.724284Z","steps":["trace[762758536] 'process raft request' (duration: 401.292946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:38.724399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:38.31809Z","time spent":"406.252859ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:12:38.724501Z","caller":"traceutil/trace.go:171","msg":"trace[196012936] transaction","detail":"{read_only:false; response_revision:23719; number_of_response:1; }","duration":"403.848834ms","start":"2026-03-02T22:12:38.320631Z","end":"2026-03-02T22:12:38.72448Z","steps":["trace[196012936] 'process raft request' (duration: 403.62576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:38.724621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:38.320622Z","time spent":"403.932586ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:12:38.724674Z","caller":"traceutil/trace.go:171","msg":"trace[1258846580] transaction","detail":"{read_only:false; response_revision:23720; number_of_response:1; }","duration":"402.622222ms","start":"2026-03-02T22:12:38.322046Z","end":"2026-03-02T22:12:38.724669Z","steps":["trace[1258846580] 'process raft request' (duration: 402.366697ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:38.724706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:38.322038Z","time spent":"402.649892ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:12:38.724779Z","caller":"traceutil/trace.go:171","msg":"trace[953412698] linearizableReadLoop","detail":"{readStateIndex:24533; appliedIndex:24529; }","duration":"100.457264ms","start":"2026-03-02T22:12:38.624315Z","end":"2026-03-02T22:12:38.724773Z","steps":["trace[953412698] 'read index received' (duration: 95.014422ms)","trace[953412698] 'applied index is now lower than readState.Index' (duration: 5.442332ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:12:38.724818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.503576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:12:38.724834Z","caller":"traceutil/trace.go:171","msg":"trace[612522558] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:23721; }","duration":"100.570376ms","start":"2026-03-02T22:12:38.624258Z","end":"2026-03-02T22:12:38.724828Z","steps":["trace[612522558] 'agreement among raft nodes before linearized reading' (duration: 100.531905ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:12:38.724981Z","caller":"traceutil/trace.go:171","msg":"trace[1489994644] transaction","detail":"{read_only:false; response_revision:23721; number_of_response:1; }","duration":"402.778505ms","start":"2026-03-02T22:12:38.322198Z","end":"2026-03-02T22:12:38.724976Z","steps":["trace[1489994644] 'process raft request' (duration: 402.445289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:12:38.725005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:12:38.322163Z","time spent":"402.828556ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:07.928411Z","caller":"traceutil/trace.go:171","msg":"trace[794022278] linearizableReadLoop","detail":"{readStateIndex:24756; appliedIndex:24755; }","duration":"119.287756ms","start":"2026-03-02T22:13:07.809098Z","end":"2026-03-02T22:13:07.928385Z","steps":["trace[794022278] 'read index received' (duration: 102.952981ms)","trace[794022278] 'applied index is now lower than readState.Index' (duration: 16.333695ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:07.928547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.430148ms","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-03-02T22:13:07.92857Z","caller":"traceutil/trace.go:171","msg":"trace[2143775536] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:23938; }","duration":"119.49379ms","start":"2026-03-02T22:13:07.809069Z","end":"2026-03-02T22:13:07.928563Z","steps":["trace[2143775536] 'agreement among raft nodes before linearized reading' (duration: 119.409208ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:07.928696Z","caller":"traceutil/trace.go:171","msg":"trace[1777907831] transaction","detail":"{read_only:false; response_revision:23938; number_of_response:1; }","duration":"149.496799ms","start":"2026-03-02T22:13:07.779191Z","end":"2026-03-02T22:13:07.928687Z","steps":["trace[1777907831] 'process raft request' (duration: 132.791818ms)","trace[1777907831] 'compare' (duration: 15.854865ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:08.198605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.02734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:08.198713Z","caller":"traceutil/trace.go:171","msg":"trace[2076856109] linearizableReadLoop","detail":"{readStateIndex:24757; appliedIndex:24756; }","duration":"231.04141ms","start":"2026-03-02T22:13:07.967654Z","end":"2026-03-02T22:13:08.198696Z","steps":["trace[2076856109] 'read index received' (duration: 80.825687ms)","trace[2076856109] 'applied index is now lower than readState.Index' (duration: 150.214303ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:08.198773Z","caller":"traceutil/trace.go:171","msg":"trace[31612030] transaction","detail":"{read_only:false; response_revision:23939; number_of_response:1; }","duration":"345.77935ms","start":"2026-03-02T22:13:07.852962Z","end":"2026-03-02T22:13:08.198741Z","steps":["trace[31612030] 'process raft request' (duration: 195.493616ms)","trace[31612030] 'compare' (duration: 149.808076ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:08.198803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.132451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:08.198838Z","caller":"traceutil/trace.go:171","msg":"trace[765839368] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:23939; }","duration":"231.218523ms","start":"2026-03-02T22:13:07.967607Z","end":"2026-03-02T22:13:08.198826Z","steps":["trace[765839368] 'agreement among raft nodes before linearized reading' (duration: 231.135121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:08.198882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:07.852943Z","time spent":"345.890742ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:08.441429Z","caller":"traceutil/trace.go:171","msg":"trace[1284214062] transaction","detail":"{read_only:false; response_revision:23942; number_of_response:1; }","duration":"237.157745ms","start":"2026-03-02T22:13:08.204241Z","end":"2026-03-02T22:13:08.441399Z","steps":["trace[1284214062] 'process raft request' (duration: 237.095573ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:08.441846Z","caller":"traceutil/trace.go:171","msg":"trace[1911071148] transaction","detail":"{read_only:false; response_revision:23941; number_of_response:1; }","duration":"237.680284ms","start":"2026-03-02T22:13:08.20416Z","end":"2026-03-02T22:13:08.44184Z","steps":["trace[1911071148] 'process raft request' (duration: 236.986011ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:08.44188Z","caller":"traceutil/trace.go:171","msg":"trace[1323997661] transaction","detail":"{read_only:false; response_revision:23943; number_of_response:1; }","duration":"237.4625ms","start":"2026-03-02T22:13:08.204386Z","end":"2026-03-02T22:13:08.441848Z","steps":["trace[1323997661] 'process raft request' (duration: 236.980991ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:08.442181Z","caller":"traceutil/trace.go:171","msg":"trace[475510559] transaction","detail":"{read_only:false; response_revision:23944; number_of_response:1; }","duration":"237.356498ms","start":"2026-03-02T22:13:08.204814Z","end":"2026-03-02T22:13:08.442171Z","steps":["trace[475510559] 'process raft request' (duration: 236.902589ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:09.199814Z","caller":"traceutil/trace.go:171","msg":"trace[1238639271] transaction","detail":"{read_only:false; response_revision:23947; number_of_response:1; }","duration":"220.745597ms","start":"2026-03-02T22:13:08.979029Z","end":"2026-03-02T22:13:09.199775Z","steps":["trace[1238639271] 'process raft request' (duration: 171.179633ms)","trace[1238639271] 'compare' (duration: 49.141876ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:09.199996Z","caller":"traceutil/trace.go:171","msg":"trace[345393173] transaction","detail":"{read_only:false; response_revision:23949; number_of_response:1; }","duration":"219.578256ms","start":"2026-03-02T22:13:08.980407Z","end":"2026-03-02T22:13:09.199985Z","steps":["trace[345393173] 'process raft request' (duration: 219.340141ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:09.199993Z","caller":"traceutil/trace.go:171","msg":"trace[1392134531] transaction","detail":"{read_only:false; response_revision:23948; number_of_response:1; }","duration":"220.947152ms","start":"2026-03-02T22:13:08.979024Z","end":"2026-03-02T22:13:09.199971Z","steps":["trace[1392134531] 'process raft request' (duration: 220.671436ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:10.446782Z","caller":"traceutil/trace.go:171","msg":"trace[987599613] transaction","detail":"{read_only:false; response_revision:23953; number_of_response:1; }","duration":"148.268275ms","start":"2026-03-02T22:13:10.298462Z","end":"2026-03-02T22:13:10.44673Z","steps":["trace[987599613] 'process raft request' (duration: 119.956986ms)","trace[987599613] 'compare' (duration: 27.920811ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:10.719347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.414107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:10.719498Z","caller":"traceutil/trace.go:171","msg":"trace[1084465323] transaction","detail":"{read_only:false; response_revision:23957; number_of_response:1; }","duration":"271.600567ms","start":"2026-03-02T22:13:10.447883Z","end":"2026-03-02T22:13:10.719483Z","steps":["trace[1084465323] 'process raft request' (duration: 271.570567ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:10.719561Z","caller":"traceutil/trace.go:171","msg":"trace[1798879593] transaction","detail":"{read_only:false; response_revision:23955; number_of_response:1; }","duration":"271.856312ms","start":"2026-03-02T22:13:10.447656Z","end":"2026-03-02T22:13:10.719512Z","steps":["trace[1798879593] 'process raft request' (duration: 129.19983ms)","trace[1798879593] 'compare' (duration: 142.248434ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:10.719633Z","caller":"traceutil/trace.go:171","msg":"trace[1113583623] transaction","detail":"{read_only:false; response_revision:23956; number_of_response:1; }","duration":"271.896452ms","start":"2026-03-02T22:13:10.447731Z","end":"2026-03-02T22:13:10.719627Z","steps":["trace[1113583623] 'process raft request' (duration: 271.695098ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:11.002885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.260756ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-02T22:13:11.003013Z","caller":"traceutil/trace.go:171","msg":"trace[113089483] linearizableReadLoop","detail":"{readStateIndex:24776; appliedIndex:24775; }","duration":"254.645971ms","start":"2026-03-02T22:13:10.748341Z","end":"2026-03-02T22:13:11.002987Z","steps":["trace[113089483] 'read index received' (duration: 47.253882ms)","trace[113089483] 'applied index is now lower than readState.Index' (duration: 207.390429ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:11.003627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.274842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:11.003691Z","caller":"traceutil/trace.go:171","msg":"trace[132022059] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:23957; }","duration":"255.377674ms","start":"2026-03-02T22:13:10.748297Z","end":"2026-03-02T22:13:11.003675Z","steps":["trace[132022059] 'agreement among raft nodes before linearized reading' (duration: 255.264152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:11.003838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.320367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-02T22:13:11.003924Z","caller":"traceutil/trace.go:171","msg":"trace[591487387] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:23957; }","duration":"214.47165ms","start":"2026-03-02T22:13:10.78943Z","end":"2026-03-02T22:13:11.003902Z","steps":["trace[591487387] 'agreement among raft nodes before linearized reading' (duration: 214.312127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:11.004194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.521316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-02T22:13:11.004282Z","caller":"traceutil/trace.go:171","msg":"trace[1727670103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23957; }","duration":"182.640088ms","start":"2026-03-02T22:13:10.821634Z","end":"2026-03-02T22:13:11.004274Z","steps":["trace[1727670103] 'agreement among raft nodes before linearized reading' (duration: 182.455865ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:11.123943Z","caller":"traceutil/trace.go:171","msg":"trace[56958799] transaction","detail":"{read_only:false; response_revision:23958; number_of_response:1; }","duration":"115.617677ms","start":"2026-03-02T22:13:11.008291Z","end":"2026-03-02T22:13:11.123909Z","steps":["trace[56958799] 'process raft request' (duration: 115.388973ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:11.367915Z","caller":"traceutil/trace.go:171","msg":"trace[1176693981] linearizableReadLoop","detail":"{readStateIndex:24778; appliedIndex:24777; }","duration":"237.54655ms","start":"2026-03-02T22:13:11.130339Z","end":"2026-03-02T22:13:11.367886Z","steps":["trace[1176693981] 'read index received' (duration: 170.52933ms)","trace[1176693981] 'applied index is now lower than readState.Index' (duration: 67.01617ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:11.368435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.073711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18509"} {"level":"info","ts":"2026-03-02T22:13:11.368519Z","caller":"traceutil/trace.go:171","msg":"trace[1674636954] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23959; }","duration":"238.181013ms","start":"2026-03-02T22:13:11.13032Z","end":"2026-03-02T22:13:11.368501Z","steps":["trace[1674636954] 'agreement among raft nodes before linearized reading' (duration: 237.965359ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:11.368338Z","caller":"traceutil/trace.go:171","msg":"trace[57633913] transaction","detail":"{read_only:false; response_revision:23959; number_of_response:1; }","duration":"238.796025ms","start":"2026-03-02T22:13:11.129497Z","end":"2026-03-02T22:13:11.368293Z","steps":["trace[57633913] 'process raft request' (duration: 171.369597ms)","trace[57633913] 'compare' (duration: 66.723975ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:11.368946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.824297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:11.368986Z","caller":"traceutil/trace.go:171","msg":"trace[1982965018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23959; }","duration":"110.879929ms","start":"2026-03-02T22:13:11.258095Z","end":"2026-03-02T22:13:11.368975Z","steps":["trace[1982965018] 'agreement among raft nodes before linearized reading' (duration: 110.824758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:11.369012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.59717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-03-02T22:13:11.369037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.408807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-02T22:13:11.369125Z","caller":"traceutil/trace.go:171","msg":"trace[167503513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23959; }","duration":"164.548129ms","start":"2026-03-02T22:13:11.204561Z","end":"2026-03-02T22:13:11.369109Z","steps":["trace[167503513] 'agreement among raft nodes before linearized reading' (duration: 164.286165ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:11.369066Z","caller":"traceutil/trace.go:171","msg":"trace[891232034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23959; }","duration":"164.698392ms","start":"2026-03-02T22:13:11.204353Z","end":"2026-03-02T22:13:11.369052Z","steps":["trace[891232034] 'agreement among raft nodes before linearized reading' (duration: 164.56141ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:11.36995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.751223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-03-02T22:13:11.370094Z","caller":"traceutil/trace.go:171","msg":"trace[1143681788] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:23959; }","duration":"143.887404ms","start":"2026-03-02T22:13:11.226182Z","end":"2026-03-02T22:13:11.37007Z","steps":["trace[1143681788] 'agreement among raft nodes before linearized reading' (duration: 142.658871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:11.37351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.110254ms","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-03-02T22:13:11.373581Z","caller":"traceutil/trace.go:171","msg":"trace[840802001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23959; }","duration":"169.213006ms","start":"2026-03-02T22:13:11.204358Z","end":"2026-03-02T22:13:11.373571Z","steps":["trace[840802001] 'agreement among raft nodes before linearized reading' (duration: 164.424167ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:11.5696Z","caller":"traceutil/trace.go:171","msg":"trace[1665776200] transaction","detail":"{read_only:false; response_revision:23960; number_of_response:1; }","duration":"196.187879ms","start":"2026-03-02T22:13:11.37338Z","end":"2026-03-02T22:13:11.569568Z","steps":["trace[1665776200] 'process raft request' (duration: 149.764124ms)","trace[1665776200] 'compare' (duration: 46.028808ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:11.570221Z","caller":"traceutil/trace.go:171","msg":"trace[26548927] linearizableReadLoop","detail":"{readStateIndex:24781; appliedIndex:24778; }","duration":"130.695268ms","start":"2026-03-02T22:13:11.439507Z","end":"2026-03-02T22:13:11.570202Z","steps":["trace[26548927] 'read index received' (duration: 83.549538ms)","trace[26548927] 'applied index is now lower than readState.Index' (duration: 47.14459ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:11.570337Z","caller":"traceutil/trace.go:171","msg":"trace[1072524499] transaction","detail":"{read_only:false; response_revision:23962; number_of_response:1; }","duration":"193.015991ms","start":"2026-03-02T22:13:11.377288Z","end":"2026-03-02T22:13:11.570304Z","steps":["trace[1072524499] 'process raft request' (duration: 192.836528ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:11.570355Z","caller":"traceutil/trace.go:171","msg":"trace[1504823201] transaction","detail":"{read_only:false; response_revision:23961; number_of_response:1; }","duration":"196.097918ms","start":"2026-03-02T22:13:11.374174Z","end":"2026-03-02T22:13:11.570272Z","steps":["trace[1504823201] 'process raft request' (duration: 195.276592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:11.570593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.057786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:11.570715Z","caller":"traceutil/trace.go:171","msg":"trace[641398211] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23962; }","duration":"131.218108ms","start":"2026-03-02T22:13:11.439475Z","end":"2026-03-02T22:13:11.570693Z","steps":["trace[641398211] 'agreement among raft nodes before linearized reading' (duration: 130.933573ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:11.881886Z","caller":"traceutil/trace.go:171","msg":"trace[1212427147] linearizableReadLoop","detail":"{readStateIndex:24782; appliedIndex:24781; }","duration":"118.169305ms","start":"2026-03-02T22:13:11.763695Z","end":"2026-03-02T22:13:11.881864Z","steps":["trace[1212427147] 'read index received' (duration: 117.985231ms)","trace[1212427147] 'applied index is now lower than readState.Index' (duration: 183.183µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:11.881939Z","caller":"traceutil/trace.go:171","msg":"trace[1036462660] transaction","detail":"{read_only:false; response_revision:23963; number_of_response:1; }","duration":"215.279506ms","start":"2026-03-02T22:13:11.666636Z","end":"2026-03-02T22:13:11.881915Z","steps":["trace[1036462660] 'process raft request' (duration: 215.086883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:11.882035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.321737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:11.882254Z","caller":"traceutil/trace.go:171","msg":"trace[1287848492] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:23963; }","duration":"118.592531ms","start":"2026-03-02T22:13:11.763648Z","end":"2026-03-02T22:13:11.88224Z","steps":["trace[1287848492] 'agreement among raft nodes before linearized reading' (duration: 118.333337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:12.713729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.325793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:12.713958Z","caller":"traceutil/trace.go:171","msg":"trace[1451337742] transaction","detail":"{read_only:false; response_revision:23966; number_of_response:1; }","duration":"194.014889ms","start":"2026-03-02T22:13:12.519926Z","end":"2026-03-02T22:13:12.713941Z","steps":["trace[1451337742] 'process raft request' (duration: 193.905227ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:12.713944Z","caller":"traceutil/trace.go:171","msg":"trace[1329335543] transaction","detail":"{read_only:false; response_revision:23965; number_of_response:1; }","duration":"348.994011ms","start":"2026-03-02T22:13:12.364913Z","end":"2026-03-02T22:13:12.713907Z","steps":["trace[1329335543] 'process raft request' (duration: 63.409663ms)","trace[1329335543] 'compare' (duration: 285.006567ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:12.714109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.364891Z","time spent":"349.130553ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:13.055557Z","caller":"traceutil/trace.go:171","msg":"trace[1133175837] transaction","detail":"{read_only:false; response_revision:23969; number_of_response:1; }","duration":"329.731282ms","start":"2026-03-02T22:13:12.7258Z","end":"2026-03-02T22:13:13.055531Z","steps":["trace[1133175837] 'process raft request' (duration: 329.64159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:13.055699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.72579Z","time spent":"329.836653ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:13.055927Z","caller":"traceutil/trace.go:171","msg":"trace[661378802] transaction","detail":"{read_only:false; response_revision:23968; number_of_response:1; }","duration":"330.375973ms","start":"2026-03-02T22:13:12.725538Z","end":"2026-03-02T22:13:13.055914Z","steps":["trace[661378802] 'process raft request' (duration: 287.459622ms)","trace[661378802] 'compare' (duration: 42.053425ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:13.055992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:12.725513Z","time spent":"330.450234ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:13.287388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.121708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:13.287495Z","caller":"traceutil/trace.go:171","msg":"trace[1091937829] linearizableReadLoop","detail":"{readStateIndex:24789; appliedIndex:24788; }","duration":"139.645956ms","start":"2026-03-02T22:13:13.147834Z","end":"2026-03-02T22:13:13.28748Z","steps":["trace[1091937829] 'read index received' (duration: 24.250122ms)","trace[1091937829] 'applied index is now lower than readState.Index' (duration: 115.394604ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:13.287573Z","caller":"traceutil/trace.go:171","msg":"trace[1767923360] transaction","detail":"{read_only:false; response_revision:23970; number_of_response:1; }","duration":"154.708777ms","start":"2026-03-02T22:13:13.132829Z","end":"2026-03-02T22:13:13.287538Z","steps":["trace[1767923360] 'process raft request' (duration: 39.349374ms)","trace[1767923360] 'compare' (duration: 114.891423ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:13.287628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.784648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:13.287661Z","caller":"traceutil/trace.go:171","msg":"trace[1520692658] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:23970; }","duration":"139.821989ms","start":"2026-03-02T22:13:13.147828Z","end":"2026-03-02T22:13:13.28765Z","steps":["trace[1520692658] 'agreement among raft nodes before linearized reading' (duration: 139.753688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:13.547832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.348182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:13.547944Z","caller":"traceutil/trace.go:171","msg":"trace[1330245019] transaction","detail":"{read_only:false; response_revision:23971; number_of_response:1; }","duration":"171.596782ms","start":"2026-03-02T22:13:13.376329Z","end":"2026-03-02T22:13:13.547925Z","steps":["trace[1330245019] 'process raft request' (duration: 48.033036ms)","trace[1330245019] 'compare' (duration: 123.190368ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:13.805699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.880484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:13.80587Z","caller":"traceutil/trace.go:171","msg":"trace[1882590828] transaction","detail":"{read_only:false; response_revision:23972; number_of_response:1; }","duration":"226.185709ms","start":"2026-03-02T22:13:13.579619Z","end":"2026-03-02T22:13:13.805804Z","steps":["trace[1882590828] 'process raft request' (duration: 97.114702ms)","trace[1882590828] 'compare' (duration: 128.614768ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:13.838978Z","caller":"traceutil/trace.go:171","msg":"trace[1616307038] transaction","detail":"{read_only:false; response_revision:23974; number_of_response:1; }","duration":"258.560233ms","start":"2026-03-02T22:13:13.580377Z","end":"2026-03-02T22:13:13.838937Z","steps":["trace[1616307038] 'process raft request' (duration: 258.42314ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:13.839402Z","caller":"traceutil/trace.go:171","msg":"trace[889938662] transaction","detail":"{read_only:false; response_revision:23973; number_of_response:1; }","duration":"259.685755ms","start":"2026-03-02T22:13:13.579642Z","end":"2026-03-02T22:13:13.839328Z","steps":["trace[889938662] 'process raft request' (duration: 258.886829ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:13.83946Z","caller":"traceutil/trace.go:171","msg":"trace[840760959] transaction","detail":"{read_only:false; response_revision:23975; number_of_response:1; }","duration":"216.882756ms","start":"2026-03-02T22:13:13.622468Z","end":"2026-03-02T22:13:13.839351Z","steps":["trace[840760959] 'process raft request' (duration: 216.398726ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:14.004169Z","caller":"traceutil/trace.go:171","msg":"trace[319726485] transaction","detail":"{read_only:false; response_revision:23976; number_of_response:1; }","duration":"115.851041ms","start":"2026-03-02T22:13:13.888248Z","end":"2026-03-02T22:13:14.004099Z","steps":["trace[319726485] 'process raft request' (duration: 107.504695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:14.369447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.598756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:14.369546Z","caller":"traceutil/trace.go:171","msg":"trace[915280374] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:23976; }","duration":"220.764028ms","start":"2026-03-02T22:13:14.148756Z","end":"2026-03-02T22:13:14.36952Z","steps":["trace[915280374] 'count revisions from in-memory index tree' (duration: 220.390462ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:14.370084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.896308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:14.370169Z","caller":"traceutil/trace.go:171","msg":"trace[1145852591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23976; }","duration":"104.01691ms","start":"2026-03-02T22:13:14.266124Z","end":"2026-03-02T22:13:14.370141Z","steps":["trace[1145852591] 'range keys from in-memory index tree' (duration: 103.639663ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:14.495311Z","caller":"traceutil/trace.go:171","msg":"trace[1900006235] transaction","detail":"{read_only:false; response_revision:23977; number_of_response:1; }","duration":"117.568884ms","start":"2026-03-02T22:13:14.377688Z","end":"2026-03-02T22:13:14.495256Z","steps":["trace[1900006235] 'process raft request' (duration: 117.156496ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:15.39161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.131331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:15.39176Z","caller":"traceutil/trace.go:171","msg":"trace[1686412163] transaction","detail":"{read_only:false; response_revision:23982; number_of_response:1; }","duration":"329.913414ms","start":"2026-03-02T22:13:15.061808Z","end":"2026-03-02T22:13:15.391721Z","steps":["trace[1686412163] 'process raft request' (duration: 83.59795ms)","trace[1686412163] 'compare' (duration: 245.979998ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:15.391925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.061777Z","time spent":"330.062658ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:15.787586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.63891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:15.787728Z","caller":"traceutil/trace.go:171","msg":"trace[1579525012] transaction","detail":"{read_only:false; response_revision:23983; number_of_response:1; }","duration":"391.591985ms","start":"2026-03-02T22:13:15.396111Z","end":"2026-03-02T22:13:15.787703Z","steps":["trace[1579525012] 'process raft request' (duration: 107.71723ms)","trace[1579525012] 'compare' (duration: 283.376036ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:15.787816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.396087Z","time spent":"391.689706ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:15.979684Z","caller":"traceutil/trace.go:171","msg":"trace[1307390991] linearizableReadLoop","detail":"{readStateIndex:24806; appliedIndex:24803; }","duration":"427.056177ms","start":"2026-03-02T22:13:15.552601Z","end":"2026-03-02T22:13:15.979658Z","steps":["trace[1307390991] 'read index received' (duration: 396.08403ms)","trace[1307390991] 'applied index is now lower than readState.Index' (duration: 30.971227ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:15.979808Z","caller":"traceutil/trace.go:171","msg":"trace[1860647051] transaction","detail":"{read_only:false; response_revision:23985; number_of_response:1; }","duration":"547.372442ms","start":"2026-03-02T22:13:15.432425Z","end":"2026-03-02T22:13:15.979797Z","steps":["trace[1860647051] 'process raft request' (duration: 547.139297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:15.979907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.432414Z","time spent":"547.418772ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:15.979944Z","caller":"traceutil/trace.go:171","msg":"trace[1486317806] transaction","detail":"{read_only:false; response_revision:23984; number_of_response:1; }","duration":"566.140951ms","start":"2026-03-02T22:13:15.413737Z","end":"2026-03-02T22:13:15.979878Z","steps":["trace[1486317806] 'process raft request' (duration: 535.040671ms)","trace[1486317806] 'compare' (duration: 30.671542ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:15.980033Z","caller":"traceutil/trace.go:171","msg":"trace[530525108] transaction","detail":"{read_only:false; response_revision:23986; number_of_response:1; }","duration":"528.172083ms","start":"2026-03-02T22:13:15.451852Z","end":"2026-03-02T22:13:15.980024Z","steps":["trace[530525108] 'process raft request' (duration: 527.761305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:15.980083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.451841Z","time spent":"528.213563ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:15.98017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.413725Z","time spent":"566.324954ms","remote":"127.0.0.1:59772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:15.98027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.769827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-02T22:13:15.980251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.931252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:15.980326Z","caller":"traceutil/trace.go:171","msg":"trace[1604181394] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:23986; }","duration":"214.856468ms","start":"2026-03-02T22:13:15.765445Z","end":"2026-03-02T22:13:15.980302Z","steps":["trace[1604181394] 'agreement among raft nodes before linearized reading' (duration: 214.749986ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:15.980333Z","caller":"traceutil/trace.go:171","msg":"trace[1778855116] 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:23986; }","duration":"258.089254ms","start":"2026-03-02T22:13:15.722224Z","end":"2026-03-02T22:13:15.980313Z","steps":["trace[1778855116] 'agreement among raft nodes before linearized reading' (duration: 257.906911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:15.980349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.00344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-03-02T22:13:15.980355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.937284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-02T22:13:15.980412Z","caller":"traceutil/trace.go:171","msg":"trace[2118628423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23986; }","duration":"134.127711ms","start":"2026-03-02T22:13:15.846268Z","end":"2026-03-02T22:13:15.980396Z","steps":["trace[2118628423] 'agreement among raft nodes before linearized reading' (duration: 133.886597ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:15.980455Z","caller":"traceutil/trace.go:171","msg":"trace[1654881891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23986; }","duration":"137.114837ms","start":"2026-03-02T22:13:15.843318Z","end":"2026-03-02T22:13:15.980432Z","steps":["trace[1654881891] 'agreement among raft nodes before linearized reading' (duration: 136.812342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:15.980447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.93062ms","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-03-02T22:13:15.980548Z","caller":"traceutil/trace.go:171","msg":"trace[677583154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23986; }","duration":"170.090213ms","start":"2026-03-02T22:13:15.810431Z","end":"2026-03-02T22:13:15.980521Z","steps":["trace[677583154] 'agreement among raft nodes before linearized reading' (duration: 169.753387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:15.980587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.978593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-03-02T22:13:15.980726Z","caller":"traceutil/trace.go:171","msg":"trace[31266534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23986; }","duration":"428.178397ms","start":"2026-03-02T22:13:15.552528Z","end":"2026-03-02T22:13:15.980706Z","steps":["trace[31266534] 'agreement among raft nodes before linearized reading' (duration: 427.609847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:15.980808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.5525Z","time spent":"428.29711ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T22:13:16.233778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.137354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:16.233884Z","caller":"traceutil/trace.go:171","msg":"trace[1830164289] transaction","detail":"{read_only:false; response_revision:23987; number_of_response:1; }","duration":"250.041754ms","start":"2026-03-02T22:13:15.983823Z","end":"2026-03-02T22:13:16.233865Z","steps":["trace[1830164289] 'process raft request' (duration: 139.740667ms)","trace[1830164289] 'compare' (duration: 109.779258ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:16.332732Z","caller":"traceutil/trace.go:171","msg":"trace[2076206953] transaction","detail":"{read_only:false; response_revision:23989; number_of_response:1; }","duration":"347.437521ms","start":"2026-03-02T22:13:15.985263Z","end":"2026-03-02T22:13:16.332701Z","steps":["trace[2076206953] 'process raft request' (duration: 347.340909ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:16.332838Z","caller":"traceutil/trace.go:171","msg":"trace[1186028117] transaction","detail":"{read_only:false; response_revision:23991; number_of_response:1; }","duration":"334.659613ms","start":"2026-03-02T22:13:15.998162Z","end":"2026-03-02T22:13:16.332822Z","steps":["trace[1186028117] 'process raft request' (duration: 334.518991ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:16.332901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.985255Z","time spent":"347.552173ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:16.332861Z","caller":"traceutil/trace.go:171","msg":"trace[467702194] transaction","detail":"{read_only:false; response_revision:23990; number_of_response:1; }","duration":"347.300789ms","start":"2026-03-02T22:13:15.985504Z","end":"2026-03-02T22:13:16.332804Z","steps":["trace[467702194] 'process raft request' (duration: 347.143416ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:16.332885Z","caller":"traceutil/trace.go:171","msg":"trace[142211166] transaction","detail":"{read_only:false; response_revision:23992; number_of_response:1; }","duration":"323.432795ms","start":"2026-03-02T22:13:16.009408Z","end":"2026-03-02T22:13:16.33284Z","steps":["trace[142211166] 'process raft request' (duration: 323.332303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:16.332988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.998142Z","time spent":"334.750926ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:16.332729Z","caller":"traceutil/trace.go:171","msg":"trace[557633424] transaction","detail":"{read_only:false; response_revision:23988; number_of_response:1; }","duration":"347.517483ms","start":"2026-03-02T22:13:15.985173Z","end":"2026-03-02T22:13:16.332691Z","steps":["trace[557633424] 'process raft request' (duration: 347.235428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:16.333044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.671416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-03-02T22:13:16.333074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.985151Z","time spent":"347.901309ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:16.333086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:16.009397Z","time spent":"323.594697ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T22:13:16.3331Z","caller":"traceutil/trace.go:171","msg":"trace[484568474] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:23992; }","duration":"347.774297ms","start":"2026-03-02T22:13:15.985315Z","end":"2026-03-02T22:13:16.33309Z","steps":["trace[484568474] 'agreement among raft nodes before linearized reading' (duration: 347.564893ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:16.332815Z","caller":"traceutil/trace.go:171","msg":"trace[671869743] linearizableReadLoop","detail":"{readStateIndex:24809; appliedIndex:24806; }","duration":"347.440992ms","start":"2026-03-02T22:13:15.985343Z","end":"2026-03-02T22:13:16.332784Z","steps":["trace[671869743] 'read index received' (duration: 138.220958ms)","trace[671869743] 'applied index is now lower than readState.Index' (duration: 209.218494ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:16.333112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.985484Z","time spent":"347.494762ms","remote":"127.0.0.1:60000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T22:13:16.333142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T22:13:15.985305Z","time spent":"347.829009ms","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-03-02T22:13:16.932335Z","caller":"traceutil/trace.go:171","msg":"trace[1488282228] transaction","detail":"{read_only:false; response_revision:23995; number_of_response:1; }","duration":"108.310711ms","start":"2026-03-02T22:13:16.823985Z","end":"2026-03-02T22:13:16.932296Z","steps":["trace[1488282228] 'process raft request' (duration: 107.995275ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:45.449299Z","caller":"traceutil/trace.go:171","msg":"trace[410176805] linearizableReadLoop","detail":"{readStateIndex:25167; appliedIndex:25166; }","duration":"126.330537ms","start":"2026-03-02T22:13:45.322942Z","end":"2026-03-02T22:13:45.449273Z","steps":["trace[410176805] 'read index received' (duration: 126.087862ms)","trace[410176805] 'applied index is now lower than readState.Index' (duration: 241.545µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T22:13:45.449518Z","caller":"traceutil/trace.go:171","msg":"trace[342747497] transaction","detail":"{read_only:false; response_revision:24340; number_of_response:1; }","duration":"130.683718ms","start":"2026-03-02T22:13:45.318702Z","end":"2026-03-02T22:13:45.449386Z","steps":["trace[342747497] 'process raft request' (duration: 130.396322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:45.449817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.787106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.145\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-03-02T22:13:45.449912Z","caller":"traceutil/trace.go:171","msg":"trace[1015283430] range","detail":"{range_begin:/registry/masterleases/199.19.213.145; range_end:; response_count:1; response_revision:24340; }","duration":"127.00675ms","start":"2026-03-02T22:13:45.322882Z","end":"2026-03-02T22:13:45.449889Z","steps":["trace[1015283430] 'agreement among raft nodes before linearized reading' (duration: 126.5431ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:45.612948Z","caller":"traceutil/trace.go:171","msg":"trace[1638954557] transaction","detail":"{read_only:false; response_revision:24341; number_of_response:1; }","duration":"104.837667ms","start":"2026-03-02T22:13:45.508074Z","end":"2026-03-02T22:13:45.612912Z","steps":["trace[1638954557] 'process raft request' (duration: 104.665444ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:46.220337Z","caller":"traceutil/trace.go:171","msg":"trace[257829067] transaction","detail":"{read_only:false; response_revision:24343; number_of_response:1; }","duration":"243.495943ms","start":"2026-03-02T22:13:45.976818Z","end":"2026-03-02T22:13:46.220314Z","steps":["trace[257829067] 'process raft request' (duration: 243.33177ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:46.240296Z","caller":"traceutil/trace.go:171","msg":"trace[1038539104] transaction","detail":"{read_only:false; response_revision:24345; number_of_response:1; }","duration":"217.100041ms","start":"2026-03-02T22:13:46.023176Z","end":"2026-03-02T22:13:46.240276Z","steps":["trace[1038539104] 'process raft request' (duration: 217.029439ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:46.24066Z","caller":"traceutil/trace.go:171","msg":"trace[593743409] transaction","detail":"{read_only:false; response_revision:24344; number_of_response:1; }","duration":"232.984106ms","start":"2026-03-02T22:13:46.007621Z","end":"2026-03-02T22:13:46.240605Z","steps":["trace[593743409] 'process raft request' (duration: 232.485937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:46.666777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.538658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:46.66688Z","caller":"traceutil/trace.go:171","msg":"trace[958855712] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:24345; }","duration":"224.720962ms","start":"2026-03-02T22:13:46.442137Z","end":"2026-03-02T22:13:46.666858Z","steps":["trace[958855712] 'range keys from in-memory index tree' (duration: 224.391216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:46.666972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.762103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:46.667057Z","caller":"traceutil/trace.go:171","msg":"trace[338217320] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:24345; }","duration":"224.899975ms","start":"2026-03-02T22:13:46.442137Z","end":"2026-03-02T22:13:46.667037Z","steps":["trace[338217320] 'range keys from in-memory index tree' (duration: 224.61789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:46.667191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.041538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:46.667214Z","caller":"traceutil/trace.go:171","msg":"trace[1937781157] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:24345; }","duration":"225.122979ms","start":"2026-03-02T22:13:46.442085Z","end":"2026-03-02T22:13:46.667208Z","steps":["trace[1937781157] 'range keys from in-memory index tree' (duration: 224.884845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:46.667747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.71511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-02T22:13:46.668251Z","caller":"traceutil/trace.go:171","msg":"trace[948083849] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:24345; }","duration":"226.235651ms","start":"2026-03-02T22:13:46.441962Z","end":"2026-03-02T22:13:46.668198Z","steps":["trace[948083849] 'range keys from in-memory index tree' (duration: 225.618028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:46.668545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.314233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-02T22:13:46.668645Z","caller":"traceutil/trace.go:171","msg":"trace[127115315] linearizableReadLoop","detail":"{readStateIndex:25174; appliedIndex:25173; }","duration":"181.599478ms","start":"2026-03-02T22:13:46.487031Z","end":"2026-03-02T22:13:46.66863Z","steps":["trace[127115315] 'read index received' (duration: 7.986019ms)","trace[127115315] 'applied index is now lower than readState.Index' (duration: 173.611919ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:46.667789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.014157ms","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-03-02T22:13:46.66915Z","caller":"traceutil/trace.go:171","msg":"trace[1023472201] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24345; }","duration":"293.23404ms","start":"2026-03-02T22:13:46.375719Z","end":"2026-03-02T22:13:46.668953Z","steps":["trace[1023472201] 'count revisions from in-memory index tree' (duration: 291.917276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:46.669353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.326721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-03-02T22:13:46.66942Z","caller":"traceutil/trace.go:171","msg":"trace[457185958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24346; }","duration":"182.424972ms","start":"2026-03-02T22:13:46.486962Z","end":"2026-03-02T22:13:46.669387Z","steps":["trace[457185958] 'agreement among raft nodes before linearized reading' (duration: 182.256979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T22:13:46.669511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.657754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-03-02T22:13:46.669558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.997914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-02T22:13:46.669619Z","caller":"traceutil/trace.go:171","msg":"trace[1223753731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24346; }","duration":"162.798757ms","start":"2026-03-02T22:13:46.506798Z","end":"2026-03-02T22:13:46.669597Z","steps":["trace[1223753731] 'agreement among raft nodes before linearized reading' (duration: 162.544283ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:46.669664Z","caller":"traceutil/trace.go:171","msg":"trace[1047500540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24346; }","duration":"153.113697ms","start":"2026-03-02T22:13:46.516529Z","end":"2026-03-02T22:13:46.669643Z","steps":["trace[1047500540] 'agreement among raft nodes before linearized reading' (duration: 152.836952ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:46.669195Z","caller":"traceutil/trace.go:171","msg":"trace[848011118] transaction","detail":"{read_only:false; response_revision:24346; number_of_response:1; }","duration":"186.367298ms","start":"2026-03-02T22:13:46.482812Z","end":"2026-03-02T22:13:46.669179Z","steps":["trace[848011118] 'process raft request' (duration: 12.306271ms)","trace[848011118] 'compare' (duration: 172.228332ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T22:13:46.669696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.426003ms","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-03-02T22:13:46.669841Z","caller":"traceutil/trace.go:171","msg":"trace[254683277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24346; }","duration":"152.593766ms","start":"2026-03-02T22:13:46.517219Z","end":"2026-03-02T22:13:46.669812Z","steps":["trace[254683277] 'agreement among raft nodes before linearized reading' (duration: 152.144558ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:46.798833Z","caller":"traceutil/trace.go:171","msg":"trace[81795419] transaction","detail":"{read_only:false; response_revision:24347; number_of_response:1; }","duration":"124.730056ms","start":"2026-03-02T22:13:46.674071Z","end":"2026-03-02T22:13:46.798801Z","steps":["trace[81795419] 'process raft request' (duration: 117.169495ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:46.80119Z","caller":"traceutil/trace.go:171","msg":"trace[521490012] transaction","detail":"{read_only:false; response_revision:24348; number_of_response:1; }","duration":"126.564491ms","start":"2026-03-02T22:13:46.674586Z","end":"2026-03-02T22:13:46.801151Z","steps":["trace[521490012] 'process raft request' (duration: 126.302086ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:46.801424Z","caller":"traceutil/trace.go:171","msg":"trace[425398211] transaction","detail":"{read_only:false; response_revision:24350; number_of_response:1; }","duration":"126.393258ms","start":"2026-03-02T22:13:46.675019Z","end":"2026-03-02T22:13:46.801413Z","steps":["trace[425398211] 'process raft request' (duration: 126.178204ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:46.80168Z","caller":"traceutil/trace.go:171","msg":"trace[1386185330] transaction","detail":"{read_only:false; response_revision:24351; number_of_response:1; }","duration":"110.336617ms","start":"2026-03-02T22:13:46.691313Z","end":"2026-03-02T22:13:46.80165Z","steps":["trace[1386185330] 'process raft request' (duration: 110.053922ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:13:46.801301Z","caller":"traceutil/trace.go:171","msg":"trace[305222467] transaction","detail":"{read_only:false; response_revision:24349; number_of_response:1; }","duration":"126.495089ms","start":"2026-03-02T22:13:46.674769Z","end":"2026-03-02T22:13:46.801264Z","steps":["trace[305222467] 'process raft request' (duration: 126.242775ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T22:14:12.162943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21872} {"level":"info","ts":"2026-03-02T22:14:12.299847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21872,"took":"132.686984ms","hash":1095988640,"current-db-size-bytes":48103424,"current-db-size":"48 MB","current-db-size-in-use-bytes":32428032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-02T22:14:12.299921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1095988640,"revision":21872,"compact-revision":18841} {"level":"info","ts":"2026-03-02T22:19:12.171113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24560} {"level":"info","ts":"2026-03-02T22:19:12.300184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24560,"took":"125.587723ms","hash":2561760454,"current-db-size-bytes":50212864,"current-db-size":"50 MB","current-db-size-in-use-bytes":33824768,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-02T22:19:12.300255Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2561760454,"revision":24560,"compact-revision":21872} {"level":"info","ts":"2026-03-02T22:23:47.878127Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"76a9cbc7029401f7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-02T22:23:47.882763Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-03-02T22:23:47.882837Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-03-02T22:24:12.178499Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27125} {"level":"info","ts":"2026-03-02T22:24:12.313811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27125,"took":"131.219208ms","hash":239786845,"current-db-size-bytes":50212864,"current-db-size":"50 MB","current-db-size-in-use-bytes":27955200,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-02T22:24:12.31389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":239786845,"revision":27125,"compact-revision":24560} {"level":"info","ts":"2026-03-02T22:29:12.184875Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29203} {"level":"info","ts":"2026-03-02T22:29:12.307276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29203,"took":"117.779074ms","hash":3015817499,"current-db-size-bytes":50212864,"current-db-size":"50 MB","current-db-size-in-use-bytes":28786688,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-02T22:29:12.307368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3015817499,"revision":29203,"compact-revision":27125} {"level":"info","ts":"2026-03-02T22:34:12.19165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31319} {"level":"info","ts":"2026-03-02T22:34:12.347532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31319,"took":"151.467038ms","hash":537276751,"current-db-size-bytes":50212864,"current-db-size":"50 MB","current-db-size-in-use-bytes":28971008,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-02T22:34:12.347721Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":537276751,"revision":31319,"compact-revision":29203} {"level":"info","ts":"2026-03-02T22:35:40.672553Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000899c.wal"} {"level":"info","ts":"2026-03-02T22:39:12.197291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33467} {"level":"info","ts":"2026-03-02T22:39:12.34323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33467,"took":"139.206409ms","hash":756067328,"current-db-size-bytes":51056640,"current-db-size":"51 MB","current-db-size-in-use-bytes":29118464,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-02T22:39:12.343285Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":756067328,"revision":33467,"compact-revision":31319} {"level":"info","ts":"2026-03-02T22:44:12.203095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35543} {"level":"info","ts":"2026-03-02T22:44:12.321752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35543,"took":"114.453067ms","hash":2254291244,"current-db-size-bytes":51478528,"current-db-size":"52 MB","current-db-size-in-use-bytes":28778496,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-02T22:44:12.321819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2254291244,"revision":35543,"compact-revision":33467}