{"level":"warn","ts":"2026-05-09T12:53:39.830592Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T12:53:39.830719Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-05-09T12:53:39.830807Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T12:53:39.830831Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-05-09T12:53:39.830856Z","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-05-09T12:53:39.831677Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-05-09T12:53:39.831755Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-05-09T12:53:39.837057Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.564697ms"} {"level":"info","ts":"2026-05-09T12:53:39.845054Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-05-09T12:53:39.845156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T12:53:39.845183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-05-09T12:53:39.845201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T12:53:39.84521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-05-09T12:53:39.845257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-05-09T12:53:39.854433Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T12:53:39.855975Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T12:53:39.857106Z","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-05-09T12:53:39.859393Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T12:53:39.859629Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T12:53:39.859792Z","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-05-09T12:53:39.859941Z","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-05-09T12:53:39.859962Z","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-05-09T12:53:39.862517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-05-09T12:53:39.862668Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-05-09T12:53:39.86461Z","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-05-09T12:53:39.86476Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-05-09T12:53:39.864863Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-05-09T12:53:39.864999Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T12:53:39.864975Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T12:53:40.846511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T12:53:40.846583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T12:53:40.846618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-05-09T12:53:40.846645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-05-09T12:53:40.846657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-05-09T12:53:40.846672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-05-09T12:53:40.846739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-05-09T12:53:40.847815Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:53:40.848632Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T12:53:40.848648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T12:53:40.848708Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T12:53:40.848937Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T12:53:40.849012Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T12:53:40.849272Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:53:40.849503Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:53:40.849577Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T12:53:40.852453Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T12:53:40.855468Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-05-09T12:53:59.549901Z","caller":"traceutil/trace.go:171","msg":"trace[1446166120] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"162.97747ms","start":"2026-05-09T12:53:59.386896Z","end":"2026-05-09T12:53:59.549874Z","steps":["trace[1446166120] 'process raft request' (duration: 162.855496ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:15.939169Z","caller":"traceutil/trace.go:171","msg":"trace[2143205412] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"158.761802ms","start":"2026-05-09T12:54:15.780363Z","end":"2026-05-09T12:54:15.939125Z","steps":["trace[2143205412] 'process raft request' (duration: 158.206487ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:16.159791Z","caller":"traceutil/trace.go:171","msg":"trace[762633366] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"207.328029ms","start":"2026-05-09T12:54:15.952436Z","end":"2026-05-09T12:54:16.159764Z","steps":["trace[762633366] 'process raft request' (duration: 207.158344ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:16.26739Z","caller":"traceutil/trace.go:171","msg":"trace[1786941840] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"130.094516ms","start":"2026-05-09T12:54:16.137224Z","end":"2026-05-09T12:54:16.267319Z","steps":["trace[1786941840] 'read index received' (duration: 22.469673ms)","trace[1786941840] 'applied index is now lower than readState.Index' (duration: 107.623433ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:54:16.267427Z","caller":"traceutil/trace.go:171","msg":"trace[1760019279] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"268.472474ms","start":"2026-05-09T12:54:15.998894Z","end":"2026-05-09T12:54:16.267367Z","steps":["trace[1760019279] 'process raft request' (duration: 260.682788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:54:16.267613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.371194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T12:54:16.267756Z","caller":"traceutil/trace.go:171","msg":"trace[1568142371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"130.588851ms","start":"2026-05-09T12:54:16.137141Z","end":"2026-05-09T12:54:16.267729Z","steps":["trace[1568142371] 'agreement among raft nodes before linearized reading' (duration: 130.330094ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:16.592261Z","caller":"traceutil/trace.go:171","msg":"trace[1667693990] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"182.282884ms","start":"2026-05-09T12:54:16.409955Z","end":"2026-05-09T12:54:16.592238Z","steps":["trace[1667693990] 'process raft request' (duration: 182.248493ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:54:16.592484Z","caller":"traceutil/trace.go:171","msg":"trace[1864214140] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"262.54961ms","start":"2026-05-09T12:54:16.329901Z","end":"2026-05-09T12:54:16.59245Z","steps":["trace[1864214140] 'process raft request' (duration: 218.908071ms)","trace[1864214140] 'compare' (duration: 43.268819ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:54:30.922746Z","caller":"traceutil/trace.go:171","msg":"trace[1429750098] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"111.0039ms","start":"2026-05-09T12:54:30.811719Z","end":"2026-05-09T12:54:30.922723Z","steps":["trace[1429750098] 'process raft request' (duration: 110.856936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:44.194935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.572835ms","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-05-09T12:55:44.195064Z","caller":"traceutil/trace.go:171","msg":"trace[2127123713] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1839; }","duration":"123.78558ms","start":"2026-05-09T12:55:44.071253Z","end":"2026-05-09T12:55:44.195038Z","steps":["trace[2127123713] 'range keys from in-memory index tree' (duration: 123.273747ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:44.321644Z","caller":"traceutil/trace.go:171","msg":"trace[1059410051] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"117.522118ms","start":"2026-05-09T12:55:44.20409Z","end":"2026-05-09T12:55:44.321613Z","steps":["trace[1059410051] 'read index received' (duration: 117.340982ms)","trace[1059410051] 'applied index is now lower than readState.Index' (duration: 180.006µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:55:44.321733Z","caller":"traceutil/trace.go:171","msg":"trace[420799235] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"205.041735ms","start":"2026-05-09T12:55:44.116636Z","end":"2026-05-09T12:55:44.321677Z","steps":["trace[420799235] 'process raft request' (duration: 204.814907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:44.321845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.734013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T12:55:44.321886Z","caller":"traceutil/trace.go:171","msg":"trace[380809962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1840; }","duration":"117.812896ms","start":"2026-05-09T12:55:44.204063Z","end":"2026-05-09T12:55:44.321876Z","steps":["trace[380809962] 'agreement among raft nodes before linearized reading' (duration: 117.668862ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:44.386925Z","caller":"traceutil/trace.go:171","msg":"trace[8065791] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"146.374907ms","start":"2026-05-09T12:55:44.240526Z","end":"2026-05-09T12:55:44.386901Z","steps":["trace[8065791] 'process raft request' (duration: 146.139321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:44.38804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.924888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2585114e-f377-4769-bf26-9909b373d336\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-09T12:55:44.388155Z","caller":"traceutil/trace.go:171","msg":"trace[16518168] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2585114e-f377-4769-bf26-9909b373d336; range_end:; response_count:1; response_revision:1841; }","duration":"127.065492ms","start":"2026-05-09T12:55:44.261054Z","end":"2026-05-09T12:55:44.38812Z","steps":["trace[16518168] 'agreement among raft nodes before linearized reading' (duration: 125.772936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:55:44.693729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.609973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:55:44.694004Z","caller":"traceutil/trace.go:171","msg":"trace[1856926182] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"175.324859ms","start":"2026-05-09T12:55:44.518661Z","end":"2026-05-09T12:55:44.693986Z","steps":["trace[1856926182] 'process raft request' (duration: 175.269278ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:44.693998Z","caller":"traceutil/trace.go:171","msg":"trace[1587268543] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"175.679459ms","start":"2026-05-09T12:55:44.518286Z","end":"2026-05-09T12:55:44.693965Z","steps":["trace[1587268543] 'process raft request' (duration: 54.171671ms)","trace[1587268543] 'compare' (duration: 120.387867ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:55:44.694187Z","caller":"traceutil/trace.go:171","msg":"trace[150868491] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"175.873395ms","start":"2026-05-09T12:55:44.518295Z","end":"2026-05-09T12:55:44.694168Z","steps":["trace[150868491] 'process raft request' (duration: 175.558236ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:55:50.554667Z","caller":"traceutil/trace.go:171","msg":"trace[1316791472] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"135.781633ms","start":"2026-05-09T12:55:50.418857Z","end":"2026-05-09T12:55:50.554639Z","steps":["trace[1316791472] 'process raft request' (duration: 42.900659ms)","trace[1316791472] 'compare' (duration: 92.73292ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:55:55.769454Z","caller":"traceutil/trace.go:171","msg":"trace[1082392927] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"130.561909ms","start":"2026-05-09T12:55:55.638864Z","end":"2026-05-09T12:55:55.769426Z","steps":["trace[1082392927] 'process raft request' (duration: 130.202119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:35.013175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.073934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:56:35.013399Z","caller":"traceutil/trace.go:171","msg":"trace[31479329] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2253; }","duration":"404.438611ms","start":"2026-05-09T12:56:34.608938Z","end":"2026-05-09T12:56:35.013377Z","steps":["trace[31479329] 'read index received' (duration: 136.940526ms)","trace[31479329] 'applied index is now lower than readState.Index' (duration: 267.496565ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:35.013538Z","caller":"traceutil/trace.go:171","msg":"trace[1949161689] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"427.704376ms","start":"2026-05-09T12:56:34.585795Z","end":"2026-05-09T12:56:35.013499Z","steps":["trace[1949161689] 'process raft request' (duration: 160.16483ms)","trace[1949161689] 'compare' (duration: 266.923089ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T12:56:35.013538Z","caller":"traceutil/trace.go:171","msg":"trace[1686838654] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"404.355099ms","start":"2026-05-09T12:56:34.609153Z","end":"2026-05-09T12:56:35.013508Z","steps":["trace[1686838654] 'process raft request' (duration: 404.176344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:35.013776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.834812ms","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-05-09T12:56:35.013831Z","caller":"traceutil/trace.go:171","msg":"trace[526615972] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2173; }","duration":"404.932444ms","start":"2026-05-09T12:56:34.608882Z","end":"2026-05-09T12:56:35.013815Z","steps":["trace[526615972] 'agreement among raft nodes before linearized reading' (duration: 404.623836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:35.013795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.921446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-09T12:56:35.013834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.214027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-09T12:56:35.013891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:34.60886Z","time spent":"405.020947ms","remote":"127.0.0.1:37814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-09T12:56:35.013906Z","caller":"traceutil/trace.go:171","msg":"trace[738812050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2173; }","duration":"141.046369ms","start":"2026-05-09T12:56:34.872839Z","end":"2026-05-09T12:56:35.013886Z","steps":["trace[738812050] 'agreement among raft nodes before linearized reading' (duration: 140.72228ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:35.013931Z","caller":"traceutil/trace.go:171","msg":"trace[476543271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2173; }","duration":"140.376941ms","start":"2026-05-09T12:56:34.873535Z","end":"2026-05-09T12:56:35.013912Z","steps":["trace[476543271] 'agreement among raft nodes before linearized reading' (duration: 140.047742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:56:35.013778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:34.585762Z","time spent":"427.86679ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T12:56:35.014021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T12:56:34.609138Z","time spent":"404.695148ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T12:56:35.161098Z","caller":"traceutil/trace.go:171","msg":"trace[1119658260] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"142.431977ms","start":"2026-05-09T12:56:35.018627Z","end":"2026-05-09T12:56:35.161059Z","steps":["trace[1119658260] 'process raft request' (duration: 142.213341ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:56:35.17337Z","caller":"traceutil/trace.go:171","msg":"trace[1161638871] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"154.583355ms","start":"2026-05-09T12:56:35.018768Z","end":"2026-05-09T12:56:35.173352Z","steps":["trace[1161638871] 'process raft request' (duration: 154.306217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T12:57:57.888982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.542132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T12:57:57.889093Z","caller":"traceutil/trace.go:171","msg":"trace[1236047174] linearizableReadLoop","detail":"{readStateIndex:2922; appliedIndex:2921; }","duration":"193.336822ms","start":"2026-05-09T12:57:57.695739Z","end":"2026-05-09T12:57:57.889076Z","steps":["trace[1236047174] 'read index received' (duration: 87.553313ms)","trace[1236047174] 'applied index is now lower than readState.Index' (duration: 105.781789ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T12:57:57.889262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.522397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T12:57:57.889308Z","caller":"traceutil/trace.go:171","msg":"trace[1119480776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2816; }","duration":"193.594529ms","start":"2026-05-09T12:57:57.6957Z","end":"2026-05-09T12:57:57.889294Z","steps":["trace[1119480776] 'agreement among raft nodes before linearized reading' (duration: 193.427305ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T12:57:57.889456Z","caller":"traceutil/trace.go:171","msg":"trace[1510435245] transaction","detail":"{read_only:false; response_revision:2816; number_of_response:1; }","duration":"270.114966ms","start":"2026-05-09T12:57:57.619253Z","end":"2026-05-09T12:57:57.889368Z","steps":["trace[1510435245] 'process raft request' (duration: 164.098441ms)","trace[1510435245] 'compare' (duration: 105.429288ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:00:24.035383Z","caller":"traceutil/trace.go:171","msg":"trace[914139853] transaction","detail":"{read_only:false; response_revision:3791; number_of_response:1; }","duration":"147.607118ms","start":"2026-05-09T13:00:23.887746Z","end":"2026-05-09T13:00:24.035353Z","steps":["trace[914139853] 'process raft request' (duration: 135.641493ms)","trace[914139853] 'compare' (duration: 11.831641ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:01:48.041349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.620219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e4e02ef6-7141-4662-a985-39f8ae8cc6d9\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-09T13:01:48.041494Z","caller":"traceutil/trace.go:171","msg":"trace[385494814] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e4e02ef6-7141-4662-a985-39f8ae8cc6d9; range_end:; response_count:1; response_revision:4975; }","duration":"106.861706ms","start":"2026-05-09T13:01:47.934607Z","end":"2026-05-09T13:01:48.041469Z","steps":["trace[385494814] 'range keys from in-memory index tree' (duration: 106.350232ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:01:48.36009Z","caller":"traceutil/trace.go:171","msg":"trace[1830454801] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5165; }","duration":"104.677127ms","start":"2026-05-09T13:01:48.255376Z","end":"2026-05-09T13:01:48.360053Z","steps":["trace[1830454801] 'read index received' (duration: 103.915186ms)","trace[1830454801] 'applied index is now lower than readState.Index' (duration: 760.501µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:01:48.360213Z","caller":"traceutil/trace.go:171","msg":"trace[317551876] transaction","detail":"{read_only:false; response_revision:4978; number_of_response:1; }","duration":"124.347722ms","start":"2026-05-09T13:01:48.235819Z","end":"2026-05-09T13:01:48.360166Z","steps":["trace[317551876] 'process raft request' (duration: 123.52261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:01:48.36042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.019466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-d65f58556-px7ff\" ","response":"range_response_count:1 size:11252"} {"level":"info","ts":"2026-05-09T13:01:48.360516Z","caller":"traceutil/trace.go:171","msg":"trace[1886074804] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-d65f58556-px7ff; range_end:; response_count:1; response_revision:4978; }","duration":"105.17034ms","start":"2026-05-09T13:01:48.255324Z","end":"2026-05-09T13:01:48.360495Z","steps":["trace[1886074804] 'agreement among raft nodes before linearized reading' (duration: 104.889813ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:01:48.514581Z","caller":"traceutil/trace.go:171","msg":"trace[2139149405] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"141.327583ms","start":"2026-05-09T13:01:48.37322Z","end":"2026-05-09T13:01:48.514548Z","steps":["trace[2139149405] 'process raft request' (duration: 112.679704ms)","trace[2139149405] 'compare' (duration: 28.393732ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:01:48.514767Z","caller":"traceutil/trace.go:171","msg":"trace[244881226] linearizableReadLoop","detail":"{readStateIndex:5167; appliedIndex:5166; }","duration":"127.288061ms","start":"2026-05-09T13:01:48.387418Z","end":"2026-05-09T13:01:48.514706Z","steps":["trace[244881226] 'read index received' (duration: 98.393926ms)","trace[244881226] 'applied index is now lower than readState.Index' (duration: 28.888875ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:01:48.51509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.64095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18ade743f3a10384\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-05-09T13:01:48.515133Z","caller":"traceutil/trace.go:171","msg":"trace[385550318] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18ade743f3a10384; range_end:; response_count:1; response_revision:4980; }","duration":"127.739443ms","start":"2026-05-09T13:01:48.387378Z","end":"2026-05-09T13:01:48.515117Z","steps":["trace[385550318] 'agreement among raft nodes before linearized reading' (duration: 127.508837ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:01:58.442897Z","caller":"traceutil/trace.go:171","msg":"trace[817303263] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"140.902112ms","start":"2026-05-09T13:01:58.301968Z","end":"2026-05-09T13:01:58.44287Z","steps":["trace[817303263] 'process raft request' (duration: 140.698557ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:05.495756Z","caller":"traceutil/trace.go:171","msg":"trace[972883181] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"112.604537ms","start":"2026-05-09T13:02:05.383129Z","end":"2026-05-09T13:02:05.495734Z","steps":["trace[972883181] 'process raft request' (duration: 112.457213ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:05.524829Z","caller":"traceutil/trace.go:171","msg":"trace[1529933618] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"109.971724ms","start":"2026-05-09T13:02:05.414809Z","end":"2026-05-09T13:02:05.524781Z","steps":["trace[1529933618] 'process raft request' (duration: 109.769199ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:05.778146Z","caller":"traceutil/trace.go:171","msg":"trace[864048146] linearizableReadLoop","detail":"{readStateIndex:5551; appliedIndex:5549; }","duration":"253.453183ms","start":"2026-05-09T13:02:05.52467Z","end":"2026-05-09T13:02:05.778123Z","steps":["trace[864048146] 'read index received' (duration: 253.169535ms)","trace[864048146] 'applied index is now lower than readState.Index' (duration: 283.018µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:02:05.778534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.167924ms","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-05-09T13:02:05.778569Z","caller":"traceutil/trace.go:171","msg":"trace[49815262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5351; }","duration":"210.262676ms","start":"2026-05-09T13:02:05.568297Z","end":"2026-05-09T13:02:05.77856Z","steps":["trace[49815262] 'agreement among raft nodes before linearized reading' (duration: 210.113392ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:05.778595Z","caller":"traceutil/trace.go:171","msg":"trace[1339862664] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"275.839092ms","start":"2026-05-09T13:02:05.502729Z","end":"2026-05-09T13:02:05.778568Z","steps":["trace[1339862664] 'process raft request' (duration: 275.343819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:05.77862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.192404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e88955af-d33f-4530-a8e0-d56aefbfe455\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-09T13:02:05.778699Z","caller":"traceutil/trace.go:171","msg":"trace[611953411] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e88955af-d33f-4530-a8e0-d56aefbfe455; range_end:; response_count:1; response_revision:5351; }","duration":"279.315067ms","start":"2026-05-09T13:02:05.499364Z","end":"2026-05-09T13:02:05.778679Z","steps":["trace[611953411] 'agreement among raft nodes before linearized reading' (duration: 279.03389ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:02:05.778948Z","caller":"traceutil/trace.go:171","msg":"trace[7821411] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"276.756727ms","start":"2026-05-09T13:02:05.502172Z","end":"2026-05-09T13:02:05.778929Z","steps":["trace[7821411] 'process raft request' (duration: 275.738378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:02:16.652633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.381775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-09T13:02:16.65276Z","caller":"traceutil/trace.go:171","msg":"trace[1428593899] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5503; }","duration":"114.549559ms","start":"2026-05-09T13:02:16.538183Z","end":"2026-05-09T13:02:16.652732Z","steps":["trace[1428593899] 'range keys from in-memory index tree' (duration: 114.219ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:03:40.874488Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3055} {"level":"info","ts":"2026-05-09T13:03:40.953713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3055,"took":"77.679506ms","hash":444949295,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":31875072,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T13:03:40.953765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":444949295,"revision":3055,"compact-revision":-1} {"level":"info","ts":"2026-05-09T13:08:13.972334Z","caller":"traceutil/trace.go:171","msg":"trace[1016442660] transaction","detail":"{read_only:false; response_revision:8188; number_of_response:1; }","duration":"119.347732ms","start":"2026-05-09T13:08:13.852921Z","end":"2026-05-09T13:08:13.972269Z","steps":["trace[1016442660] 'process raft request' (duration: 107.455098ms)","trace[1016442660] 'compare' (duration: 11.438872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:08:25.206319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.162457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T13:08:25.206479Z","caller":"traceutil/trace.go:171","msg":"trace[1798242977] transaction","detail":"{read_only:false; response_revision:8250; number_of_response:1; }","duration":"331.053325ms","start":"2026-05-09T13:08:24.875402Z","end":"2026-05-09T13:08:25.206456Z","steps":["trace[1798242977] 'process raft request' (duration: 57.600781ms)","trace[1798242977] 'compare' (duration: 272.518377ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:08:25.206544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:08:24.875367Z","time spent":"331.146717ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:08:25.221269Z","caller":"traceutil/trace.go:171","msg":"trace[1265635551] transaction","detail":"{read_only:false; response_revision:8251; number_of_response:1; }","duration":"344.602993ms","start":"2026-05-09T13:08:24.876646Z","end":"2026-05-09T13:08:25.221249Z","steps":["trace[1265635551] 'process raft request' (duration: 344.424398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:08:25.221375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:08:24.876625Z","time spent":"344.699276ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:08:25.222172Z","caller":"traceutil/trace.go:171","msg":"trace[776309784] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"344.666285ms","start":"2026-05-09T13:08:24.876843Z","end":"2026-05-09T13:08:25.221509Z","steps":["trace[776309784] 'process raft request' (duration: 344.347126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:08:25.222319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:08:24.876832Z","time spent":"345.411115ms","remote":"127.0.0.1:36502","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-05-09T13:08:26.077255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.801291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T13:08:26.077347Z","caller":"traceutil/trace.go:171","msg":"trace[31479016] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:8253; }","duration":"153.962226ms","start":"2026-05-09T13:08:25.92336Z","end":"2026-05-09T13:08:26.077322Z","steps":["trace[31479016] 'count revisions from in-memory index tree' (duration: 153.670328ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:08:26.528592Z","caller":"traceutil/trace.go:171","msg":"trace[1801422340] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"150.664927ms","start":"2026-05-09T13:08:26.377897Z","end":"2026-05-09T13:08:26.528562Z","steps":["trace[1801422340] 'process raft request' (duration: 127.668569ms)","trace[1801422340] 'compare' (duration: 22.863934ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:08:40.881172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6189} {"level":"info","ts":"2026-05-09T13:08:40.97497Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6189,"took":"91.961136ms","hash":294081466,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":22986752,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-09T13:08:40.975026Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":294081466,"revision":6189,"compact-revision":3055} {"level":"warn","ts":"2026-05-09T13:08:54.443845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.441723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:08:54.44394Z","caller":"traceutil/trace.go:171","msg":"trace[1903358826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8461; }","duration":"306.582676ms","start":"2026-05-09T13:08:54.137336Z","end":"2026-05-09T13:08:54.443918Z","steps":["trace[1903358826] 'range keys from in-memory index tree' (duration: 306.292578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:08:54.443981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:08:54.137288Z","time spent":"306.685269ms","remote":"127.0.0.1:36254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T13:08:54.444209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.531407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T13:08:54.444423Z","caller":"traceutil/trace.go:171","msg":"trace[1392319965] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"271.814719ms","start":"2026-05-09T13:08:54.172592Z","end":"2026-05-09T13:08:54.444407Z","steps":["trace[1392319965] 'process raft request' (duration: 271.728257ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:08:54.444549Z","caller":"traceutil/trace.go:171","msg":"trace[599141229] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"274.189764ms","start":"2026-05-09T13:08:54.170348Z","end":"2026-05-09T13:08:54.444538Z","steps":["trace[599141229] 'process raft request' (duration: 150.231854ms)","trace[599141229] 'compare' (duration: 123.110126ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:08:57.505031Z","caller":"traceutil/trace.go:171","msg":"trace[513150594] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"154.783159ms","start":"2026-05-09T13:08:57.350224Z","end":"2026-05-09T13:08:57.505007Z","steps":["trace[513150594] 'process raft request' (duration: 154.531752ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:08:57.505036Z","caller":"traceutil/trace.go:171","msg":"trace[188132671] linearizableReadLoop","detail":"{readStateIndex:8777; appliedIndex:8776; }","duration":"148.490577ms","start":"2026-05-09T13:08:57.356528Z","end":"2026-05-09T13:08:57.505019Z","steps":["trace[188132671] 'read index received' (duration: 148.191769ms)","trace[188132671] 'applied index is now lower than readState.Index' (duration: 297.538µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:08:57.505209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.658552ms","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-05-09T13:08:57.505255Z","caller":"traceutil/trace.go:171","msg":"trace[1403083703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8476; }","duration":"148.751894ms","start":"2026-05-09T13:08:57.356492Z","end":"2026-05-09T13:08:57.505244Z","steps":["trace[1403083703] 'agreement among raft nodes before linearized reading' (duration: 148.58259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:08:57.505377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.569359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-09T13:08:57.505433Z","caller":"traceutil/trace.go:171","msg":"trace[1786255698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8476; }","duration":"144.691033ms","start":"2026-05-09T13:08:57.360727Z","end":"2026-05-09T13:08:57.505418Z","steps":["trace[1786255698] 'agreement among raft nodes before linearized reading' (duration: 144.476297ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:09:06.613394Z","caller":"traceutil/trace.go:171","msg":"trace[380822558] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"121.45656ms","start":"2026-05-09T13:09:06.491913Z","end":"2026-05-09T13:09:06.613369Z","steps":["trace[380822558] 'process raft request' (duration: 121.297326ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:09:06.649916Z","caller":"traceutil/trace.go:171","msg":"trace[373589729] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"148.279942ms","start":"2026-05-09T13:09:06.501609Z","end":"2026-05-09T13:09:06.649889Z","steps":["trace[373589729] 'process raft request' (duration: 148.156708ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:09:06.784139Z","caller":"traceutil/trace.go:171","msg":"trace[29401075] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"170.849677ms","start":"2026-05-09T13:09:06.613263Z","end":"2026-05-09T13:09:06.784112Z","steps":["trace[29401075] 'process raft request' (duration: 143.82421ms)","trace[29401075] 'compare' (duration: 26.905073ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:09:06.800603Z","caller":"traceutil/trace.go:171","msg":"trace[627410749] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"121.044489ms","start":"2026-05-09T13:09:06.679536Z","end":"2026-05-09T13:09:06.800581Z","steps":["trace[627410749] 'process raft request' (duration: 120.924505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:09:07.378956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.682268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T13:09:07.379072Z","caller":"traceutil/trace.go:171","msg":"trace[821054770] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:8574; }","duration":"117.888694ms","start":"2026-05-09T13:09:07.26116Z","end":"2026-05-09T13:09:07.379048Z","steps":["trace[821054770] 'count revisions from in-memory index tree' (duration: 117.587555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:09:07.379308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.141606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-05-09T13:09:07.379413Z","caller":"traceutil/trace.go:171","msg":"trace[208497752] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8574; }","duration":"127.295971ms","start":"2026-05-09T13:09:07.252089Z","end":"2026-05-09T13:09:07.379385Z","steps":["trace[208497752] 'range keys from in-memory index tree' (duration: 126.769786ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:09:58.033399Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a0.wal"} {"level":"info","ts":"2026-05-09T13:11:12.280173Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T13:11:12.28921Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-09T13:11:12.289297Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-09T13:13:40.888253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8337} {"level":"info","ts":"2026-05-09T13:13:40.969865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8337,"took":"78.999083ms","hash":4073051539,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":26525696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-09T13:13:40.969948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4073051539,"revision":8337,"compact-revision":6189} {"level":"info","ts":"2026-05-09T13:18:40.89461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10996} {"level":"info","ts":"2026-05-09T13:18:40.980834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10996,"took":"83.708613ms","hash":1716017262,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":28180480,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T13:18:40.980902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1716017262,"revision":10996,"compact-revision":8337} {"level":"info","ts":"2026-05-09T13:20:45.30872Z","caller":"traceutil/trace.go:171","msg":"trace[376450853] linearizableReadLoop","detail":"{readStateIndex:14303; appliedIndex:14302; }","duration":"101.640064ms","start":"2026-05-09T13:20:45.207061Z","end":"2026-05-09T13:20:45.308701Z","steps":["trace[376450853] 'read index received' (duration: 101.528751ms)","trace[376450853] 'applied index is now lower than readState.Index' (duration: 110.533µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:20:45.308824Z","caller":"traceutil/trace.go:171","msg":"trace[1002196115] transaction","detail":"{read_only:false; response_revision:13826; number_of_response:1; }","duration":"104.855802ms","start":"2026-05-09T13:20:45.203942Z","end":"2026-05-09T13:20:45.308798Z","steps":["trace[1002196115] 'process raft request' (duration: 104.626566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:20:45.308928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.8491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-09T13:20:45.308964Z","caller":"traceutil/trace.go:171","msg":"trace[572766133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13826; }","duration":"101.935032ms","start":"2026-05-09T13:20:45.207016Z","end":"2026-05-09T13:20:45.308951Z","steps":["trace[572766133] 'agreement among raft nodes before linearized reading' (duration: 101.788158ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:20:45.536338Z","caller":"traceutil/trace.go:171","msg":"trace[847357540] transaction","detail":"{read_only:false; response_revision:13827; number_of_response:1; }","duration":"225.126625ms","start":"2026-05-09T13:20:45.311177Z","end":"2026-05-09T13:20:45.536304Z","steps":["trace[847357540] 'process raft request' (duration: 224.96175ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:20:45.54084Z","caller":"traceutil/trace.go:171","msg":"trace[2063275763] transaction","detail":"{read_only:false; response_revision:13831; number_of_response:1; }","duration":"220.929691ms","start":"2026-05-09T13:20:45.319876Z","end":"2026-05-09T13:20:45.540805Z","steps":["trace[2063275763] 'process raft request' (duration: 220.694125ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:20:45.540911Z","caller":"traceutil/trace.go:171","msg":"trace[619346120] transaction","detail":"{read_only:false; response_revision:13832; number_of_response:1; }","duration":"219.751598ms","start":"2026-05-09T13:20:45.32114Z","end":"2026-05-09T13:20:45.540892Z","steps":["trace[619346120] 'process raft request' (duration: 219.481051ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:20:45.541063Z","caller":"traceutil/trace.go:171","msg":"trace[962191783] transaction","detail":"{read_only:false; response_revision:13828; number_of_response:1; }","duration":"227.833239ms","start":"2026-05-09T13:20:45.313213Z","end":"2026-05-09T13:20:45.541047Z","steps":["trace[962191783] 'process raft request' (duration: 227.13985ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:20:45.541274Z","caller":"traceutil/trace.go:171","msg":"trace[462487272] transaction","detail":"{read_only:false; response_revision:13829; number_of_response:1; }","duration":"227.953292ms","start":"2026-05-09T13:20:45.313304Z","end":"2026-05-09T13:20:45.541258Z","steps":["trace[462487272] 'process raft request' (duration: 227.159141ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:20:45.541407Z","caller":"traceutil/trace.go:171","msg":"trace[1921910211] transaction","detail":"{read_only:false; response_revision:13830; number_of_response:1; }","duration":"223.533762ms","start":"2026-05-09T13:20:45.317859Z","end":"2026-05-09T13:20:45.541393Z","steps":["trace[1921910211] 'process raft request' (duration: 222.665859ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:21:59.967253Z","caller":"traceutil/trace.go:171","msg":"trace[2106959847] transaction","detail":"{read_only:false; response_revision:14900; number_of_response:1; }","duration":"131.188881ms","start":"2026-05-09T13:21:59.83603Z","end":"2026-05-09T13:21:59.967219Z","steps":["trace[2106959847] 'process raft request' (duration: 130.525862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:22:33.384538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.604228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T13:22:33.384642Z","caller":"traceutil/trace.go:171","msg":"trace[53793932] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15074; }","duration":"232.745932ms","start":"2026-05-09T13:22:33.151876Z","end":"2026-05-09T13:22:33.384622Z","steps":["trace[53793932] 'range keys from in-memory index tree' (duration: 232.533805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:22:33.384633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.975779ms","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-05-09T13:22:33.384744Z","caller":"traceutil/trace.go:171","msg":"trace[1162712844] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15074; }","duration":"229.129062ms","start":"2026-05-09T13:22:33.155587Z","end":"2026-05-09T13:22:33.384716Z","steps":["trace[1162712844] 'range keys from in-memory index tree' (duration: 228.926227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:22:33.384926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.142103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-09T13:22:33.385Z","caller":"traceutil/trace.go:171","msg":"trace[1107567265] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15074; }","duration":"233.253696ms","start":"2026-05-09T13:22:33.15173Z","end":"2026-05-09T13:22:33.384984Z","steps":["trace[1107567265] 'range keys from in-memory index tree' (duration: 233.015159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:22:33.385022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.168393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-05-09T13:22:33.385065Z","caller":"traceutil/trace.go:171","msg":"trace[221526679] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15074; }","duration":"233.221824ms","start":"2026-05-09T13:22:33.151828Z","end":"2026-05-09T13:22:33.38505Z","steps":["trace[221526679] 'range keys from in-memory index tree' (duration: 233.091171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:22:33.384566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.695987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-vtphz\" ","response":"range_response_count:1 size:20747"} {"level":"warn","ts":"2026-05-09T13:22:33.384932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.017318ms","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-05-09T13:22:33.385217Z","caller":"traceutil/trace.go:171","msg":"trace[1673594362] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-vtphz; range_end:; response_count:1; response_revision:15074; }","duration":"190.372725ms","start":"2026-05-09T13:22:33.194825Z","end":"2026-05-09T13:22:33.385198Z","steps":["trace[1673594362] 'range keys from in-memory index tree' (duration: 189.45444ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:22:33.385226Z","caller":"traceutil/trace.go:171","msg":"trace[1804175784] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15074; }","duration":"233.318678ms","start":"2026-05-09T13:22:33.151893Z","end":"2026-05-09T13:22:33.385212Z","steps":["trace[1804175784] 'range keys from in-memory index tree' (duration: 232.930615ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:23:40.900713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13041} {"level":"info","ts":"2026-05-09T13:23:40.976007Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13041,"took":"71.953783ms","hash":2990362152,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":31072256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T13:23:40.976069Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2990362152,"revision":13041,"compact-revision":10996} {"level":"info","ts":"2026-05-09T13:28:12.930225Z","caller":"traceutil/trace.go:171","msg":"trace[879037244] transaction","detail":"{read_only:false; response_revision:17684; number_of_response:1; }","duration":"122.944812ms","start":"2026-05-09T13:28:12.807257Z","end":"2026-05-09T13:28:12.930202Z","steps":["trace[879037244] 'process raft request' (duration: 122.781157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:28:19.116422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.155036ms","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-05-09T13:28:19.116522Z","caller":"traceutil/trace.go:171","msg":"trace[1096613957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17718; }","duration":"160.326551ms","start":"2026-05-09T13:28:18.956173Z","end":"2026-05-09T13:28:19.1165Z","steps":["trace[1096613957] 'range keys from in-memory index tree' (duration: 159.960211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:28:19.116683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.413323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:24 size:220658"} {"level":"info","ts":"2026-05-09T13:28:19.116999Z","caller":"traceutil/trace.go:171","msg":"trace[1998810816] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:24; response_revision:17718; }","duration":"215.604829ms","start":"2026-05-09T13:28:18.901183Z","end":"2026-05-09T13:28:19.116788Z","steps":["trace[1998810816] 'range keys from in-memory index tree' (duration: 214.91974ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:28:19.595Z","caller":"traceutil/trace.go:171","msg":"trace[916414129] linearizableReadLoop","detail":"{readStateIndex:18322; appliedIndex:18321; }","duration":"248.749451ms","start":"2026-05-09T13:28:19.346222Z","end":"2026-05-09T13:28:19.594972Z","steps":["trace[916414129] 'read index received' (duration: 248.632448ms)","trace[916414129] 'applied index is now lower than readState.Index' (duration: 115.793µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:28:19.595095Z","caller":"traceutil/trace.go:171","msg":"trace[1068249382] transaction","detail":"{read_only:false; response_revision:17723; number_of_response:1; }","duration":"249.378149ms","start":"2026-05-09T13:28:19.345651Z","end":"2026-05-09T13:28:19.595029Z","steps":["trace[1068249382] 'process raft request' (duration: 249.164083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:28:19.595319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.071761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-vtphz\" ","response":"range_response_count:1 size:20850"} {"level":"info","ts":"2026-05-09T13:28:19.595405Z","caller":"traceutil/trace.go:171","msg":"trace[1051734260] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-vtphz; range_end:; response_count:1; response_revision:17723; }","duration":"249.198164ms","start":"2026-05-09T13:28:19.346186Z","end":"2026-05-09T13:28:19.595384Z","steps":["trace[1051734260] 'agreement among raft nodes before linearized reading' (duration: 248.955917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:28:19.631374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.761804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T13:28:19.631438Z","caller":"traceutil/trace.go:171","msg":"trace[960194150] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17723; }","duration":"280.874117ms","start":"2026-05-09T13:28:19.350548Z","end":"2026-05-09T13:28:19.631422Z","steps":["trace[960194150] 'agreement among raft nodes before linearized reading' (duration: 280.768294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:28:19.632073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.998229ms","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-05-09T13:28:19.632173Z","caller":"traceutil/trace.go:171","msg":"trace[790217589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17723; }","duration":"223.110642ms","start":"2026-05-09T13:28:19.409046Z","end":"2026-05-09T13:28:19.632157Z","steps":["trace[790217589] 'agreement among raft nodes before linearized reading' (duration: 222.892176ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:28:19.898415Z","caller":"traceutil/trace.go:171","msg":"trace[211668654] linearizableReadLoop","detail":"{readStateIndex:18325; appliedIndex:18324; }","duration":"150.712879ms","start":"2026-05-09T13:28:19.747669Z","end":"2026-05-09T13:28:19.898382Z","steps":["trace[211668654] 'read index received' (duration: 140.670786ms)","trace[211668654] 'applied index is now lower than readState.Index' (duration: 10.040963ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:28:19.898506Z","caller":"traceutil/trace.go:171","msg":"trace[683829884] transaction","detail":"{read_only:false; response_revision:17725; number_of_response:1; }","duration":"254.58354ms","start":"2026-05-09T13:28:19.643866Z","end":"2026-05-09T13:28:19.898449Z","steps":["trace[683829884] 'process raft request' (duration: 244.568448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:28:19.898577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.891633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:28:19.898626Z","caller":"traceutil/trace.go:171","msg":"trace[1053155570] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:17725; }","duration":"150.984326ms","start":"2026-05-09T13:28:19.747628Z","end":"2026-05-09T13:28:19.898612Z","steps":["trace[1053155570] 'agreement among raft nodes before linearized reading' (duration: 150.890153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:28:19.898918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.132779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-vws2c\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-05-09T13:28:19.89906Z","caller":"traceutil/trace.go:171","msg":"trace[1763169173] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-vws2c; range_end:; response_count:1; response_revision:17725; }","duration":"100.320085ms","start":"2026-05-09T13:28:19.79872Z","end":"2026-05-09T13:28:19.89904Z","steps":["trace[1763169173] 'agreement among raft nodes before linearized reading' (duration: 100.069098ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:28:20.112973Z","caller":"traceutil/trace.go:171","msg":"trace[1173630133] linearizableReadLoop","detail":"{readStateIndex:18327; appliedIndex:18325; }","duration":"207.322842ms","start":"2026-05-09T13:28:19.905622Z","end":"2026-05-09T13:28:20.112944Z","steps":["trace[1173630133] 'read index received' (duration: 174.391894ms)","trace[1173630133] 'applied index is now lower than readState.Index' (duration: 32.929408ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:28:20.113336Z","caller":"traceutil/trace.go:171","msg":"trace[50543415] transaction","detail":"{read_only:false; response_revision:17726; number_of_response:1; }","duration":"209.668027ms","start":"2026-05-09T13:28:19.903647Z","end":"2026-05-09T13:28:20.113315Z","steps":["trace[50543415] 'process raft request' (duration: 176.348318ms)","trace[50543415] 'compare' (duration: 32.694952ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:28:20.113573Z","caller":"traceutil/trace.go:171","msg":"trace[1312458597] transaction","detail":"{read_only:false; response_revision:17727; number_of_response:1; }","duration":"208.870274ms","start":"2026-05-09T13:28:19.904688Z","end":"2026-05-09T13:28:20.113558Z","steps":["trace[1312458597] 'process raft request' (duration: 208.187846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:28:20.114866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.224315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:953100"} {"level":"info","ts":"2026-05-09T13:28:20.114966Z","caller":"traceutil/trace.go:171","msg":"trace[1415307587] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:17727; }","duration":"209.333637ms","start":"2026-05-09T13:28:19.905582Z","end":"2026-05-09T13:28:20.114916Z","steps":["trace[1415307587] 'agreement among raft nodes before linearized reading' (duration: 208.165985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:28:20.115463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.693258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T13:28:20.115501Z","caller":"traceutil/trace.go:171","msg":"trace[1583565447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17727; }","duration":"194.802821ms","start":"2026-05-09T13:28:19.920691Z","end":"2026-05-09T13:28:20.115494Z","steps":["trace[1583565447] 'agreement among raft nodes before linearized reading' (duration: 194.694499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:28:20.11582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.053221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-wl4bw\" ","response":"range_response_count:1 size:9789"} {"level":"info","ts":"2026-05-09T13:28:20.115857Z","caller":"traceutil/trace.go:171","msg":"trace[1138873053] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-wl4bw; range_end:; response_count:1; response_revision:17727; }","duration":"128.091082ms","start":"2026-05-09T13:28:19.987758Z","end":"2026-05-09T13:28:20.115849Z","steps":["trace[1138873053] 'agreement among raft nodes before linearized reading' (duration: 127.994559ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:28:40.906603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15522} {"level":"info","ts":"2026-05-09T13:28:41.007202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15522,"took":"95.426142ms","hash":2432117297,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":33398784,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-09T13:28:41.007293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2432117297,"revision":15522,"compact-revision":13041} {"level":"info","ts":"2026-05-09T13:31:37.90542Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T13:31:37.910602Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-09T13:31:37.910713Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-09T13:33:40.91512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18101} {"level":"info","ts":"2026-05-09T13:33:41.028728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18101,"took":"109.939068ms","hash":856689115,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":30298112,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T13:33:41.028802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":856689115,"revision":18101,"compact-revision":15522} {"level":"info","ts":"2026-05-09T13:33:56.116816Z","caller":"traceutil/trace.go:171","msg":"trace[543756278] linearizableReadLoop","detail":"{readStateIndex:20949; appliedIndex:20947; }","duration":"159.684714ms","start":"2026-05-09T13:33:55.957104Z","end":"2026-05-09T13:33:56.116788Z","steps":["trace[543756278] 'read index received' (duration: 2.52961ms)","trace[543756278] 'applied index is now lower than readState.Index' (duration: 157.154214ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:33:56.116965Z","caller":"traceutil/trace.go:171","msg":"trace[104777992] transaction","detail":"{read_only:false; response_revision:20263; number_of_response:1; }","duration":"192.445167ms","start":"2026-05-09T13:33:55.924453Z","end":"2026-05-09T13:33:56.116898Z","steps":["trace[104777992] 'process raft request' (duration: 192.18393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:33:56.117083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.824078ms","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-05-09T13:33:56.11713Z","caller":"traceutil/trace.go:171","msg":"trace[703345164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20263; }","duration":"159.91977ms","start":"2026-05-09T13:33:55.957199Z","end":"2026-05-09T13:33:56.117119Z","steps":["trace[703345164] 'agreement among raft nodes before linearized reading' (duration: 159.756156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:33:56.11726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.098895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T13:33:56.117393Z","caller":"traceutil/trace.go:171","msg":"trace[904572209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20263; }","duration":"160.306481ms","start":"2026-05-09T13:33:55.957056Z","end":"2026-05-09T13:33:56.117362Z","steps":["trace[904572209] 'agreement among raft nodes before linearized reading' (duration: 159.864159ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:33:56.275766Z","caller":"traceutil/trace.go:171","msg":"trace[963293495] linearizableReadLoop","detail":"{readStateIndex:20951; appliedIndex:20950; }","duration":"140.971554ms","start":"2026-05-09T13:33:56.134747Z","end":"2026-05-09T13:33:56.275719Z","steps":["trace[963293495] 'read index received' (duration: 132.842452ms)","trace[963293495] 'applied index is now lower than readState.Index' (duration: 8.127412ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:33:56.275788Z","caller":"traceutil/trace.go:171","msg":"trace[679881396] transaction","detail":"{read_only:false; response_revision:20265; number_of_response:1; }","duration":"152.575821ms","start":"2026-05-09T13:33:56.123173Z","end":"2026-05-09T13:33:56.275749Z","steps":["trace[679881396] 'process raft request' (duration: 144.517031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:33:56.275937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.17532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:33:56.27597Z","caller":"traceutil/trace.go:171","msg":"trace[1416298174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20265; }","duration":"141.278713ms","start":"2026-05-09T13:33:56.134682Z","end":"2026-05-09T13:33:56.27596Z","steps":["trace[1416298174] 'agreement among raft nodes before linearized reading' (duration: 141.17829ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:33:56.494554Z","caller":"traceutil/trace.go:171","msg":"trace[709430974] transaction","detail":"{read_only:false; response_revision:20268; number_of_response:1; }","duration":"114.755709ms","start":"2026-05-09T13:33:56.379764Z","end":"2026-05-09T13:33:56.49452Z","steps":["trace[709430974] 'process raft request' (duration: 114.698747ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:33:56.494624Z","caller":"traceutil/trace.go:171","msg":"trace[1307820010] transaction","detail":"{read_only:false; response_revision:20266; number_of_response:1; }","duration":"121.36392ms","start":"2026-05-09T13:33:56.373231Z","end":"2026-05-09T13:33:56.494595Z","steps":["trace[1307820010] 'process raft request' (duration: 53.219131ms)","trace[1307820010] 'compare' (duration: 67.716387ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:33:56.494752Z","caller":"traceutil/trace.go:171","msg":"trace[415556985] transaction","detail":"{read_only:false; response_revision:20267; number_of_response:1; }","duration":"119.681464ms","start":"2026-05-09T13:33:56.375027Z","end":"2026-05-09T13:33:56.494709Z","steps":["trace[415556985] 'process raft request' (duration: 119.363555ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:33:56.943654Z","caller":"traceutil/trace.go:171","msg":"trace[1777332893] linearizableReadLoop","detail":"{readStateIndex:20955; appliedIndex:20954; }","duration":"137.175921ms","start":"2026-05-09T13:33:56.806455Z","end":"2026-05-09T13:33:56.943631Z","steps":["trace[1777332893] 'read index received' (duration: 137.114179ms)","trace[1777332893] 'applied index is now lower than readState.Index' (duration: 60.172µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:33:56.943764Z","caller":"traceutil/trace.go:171","msg":"trace[840617659] transaction","detail":"{read_only:false; response_revision:20269; number_of_response:1; }","duration":"197.056403ms","start":"2026-05-09T13:33:56.746696Z","end":"2026-05-09T13:33:56.943752Z","steps":["trace[840617659] 'process raft request' (duration: 196.780065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:33:56.943959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.494049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T13:33:56.944047Z","caller":"traceutil/trace.go:171","msg":"trace[2051433514] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:20269; }","duration":"137.634803ms","start":"2026-05-09T13:33:56.80639Z","end":"2026-05-09T13:33:56.944025Z","steps":["trace[2051433514] 'agreement among raft nodes before linearized reading' (duration: 137.486329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:33:57.134735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.789481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T13:33:57.13484Z","caller":"traceutil/trace.go:171","msg":"trace[169035426] linearizableReadLoop","detail":"{readStateIndex:20956; appliedIndex:20955; }","duration":"191.10048ms","start":"2026-05-09T13:33:56.943723Z","end":"2026-05-09T13:33:57.134824Z","steps":["trace[169035426] 'read index received' (duration: 40.114094ms)","trace[169035426] 'applied index is now lower than readState.Index' (duration: 150.985316ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:33:57.134868Z","caller":"traceutil/trace.go:171","msg":"trace[159561012] transaction","detail":"{read_only:false; response_revision:20270; number_of_response:1; }","duration":"231.894672ms","start":"2026-05-09T13:33:56.902941Z","end":"2026-05-09T13:33:57.134835Z","steps":["trace[159561012] 'process raft request' (duration: 80.921235ms)","trace[159561012] 'compare' (duration: 150.666498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:33:57.13503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.021701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-09T13:33:57.135145Z","caller":"traceutil/trace.go:171","msg":"trace[1212531676] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20270; }","duration":"241.205577ms","start":"2026-05-09T13:33:56.893907Z","end":"2026-05-09T13:33:57.135112Z","steps":["trace[1212531676] 'agreement among raft nodes before linearized reading' (duration: 240.96131ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:37:45.07343Z","caller":"traceutil/trace.go:171","msg":"trace[890879532] transaction","detail":"{read_only:false; response_revision:22653; number_of_response:1; }","duration":"265.835854ms","start":"2026-05-09T13:37:44.807562Z","end":"2026-05-09T13:37:45.073397Z","steps":["trace[890879532] 'process raft request' (duration: 221.114733ms)","trace[890879532] 'compare' (duration: 44.496025ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:38:40.92324Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20186} {"level":"info","ts":"2026-05-09T13:38:41.039686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20186,"took":"112.478039ms","hash":3379340202,"current-db-size-bytes":58200064,"current-db-size":"58 MB","current-db-size-in-use-bytes":40710144,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-09T13:38:41.039809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3379340202,"revision":20186,"compact-revision":18101} {"level":"info","ts":"2026-05-09T13:41:15.849016Z","caller":"traceutil/trace.go:171","msg":"trace[501163689] transaction","detail":"{read_only:false; response_revision:24794; number_of_response:1; }","duration":"105.988021ms","start":"2026-05-09T13:41:15.742991Z","end":"2026-05-09T13:41:15.848979Z","steps":["trace[501163689] 'process raft request' (duration: 103.451613ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:41:21.309435Z","caller":"traceutil/trace.go:171","msg":"trace[1583375075] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"122.724359ms","start":"2026-05-09T13:41:21.186689Z","end":"2026-05-09T13:41:21.309414Z","steps":["trace[1583375075] 'process raft request' (duration: 122.562045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:41:21.708385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.402478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-09T13:41:21.708481Z","caller":"traceutil/trace.go:171","msg":"trace[11185797] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24840; }","duration":"244.549963ms","start":"2026-05-09T13:41:21.463908Z","end":"2026-05-09T13:41:21.708458Z","steps":["trace[11185797] 'range keys from in-memory index tree' (duration: 244.219034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:41:21.708623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.350088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-09T13:41:21.708711Z","caller":"traceutil/trace.go:171","msg":"trace[2046295712] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24840; }","duration":"133.490723ms","start":"2026-05-09T13:41:21.5752Z","end":"2026-05-09T13:41:21.708691Z","steps":["trace[2046295712] 'range keys from in-memory index tree' (duration: 133.166223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:41:21.708816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.324207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:41:21.708863Z","caller":"traceutil/trace.go:171","msg":"trace[1668529958] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24840; }","duration":"237.388048ms","start":"2026-05-09T13:41:21.471463Z","end":"2026-05-09T13:41:21.708851Z","steps":["trace[1668529958] 'count revisions from in-memory index tree' (duration: 237.290625ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:41:30.997767Z","caller":"traceutil/trace.go:171","msg":"trace[976949415] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"153.406876ms","start":"2026-05-09T13:41:30.844325Z","end":"2026-05-09T13:41:30.997732Z","steps":["trace[976949415] 'process raft request' (duration: 153.190061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:41:31.29299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.385163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T13:41:31.293107Z","caller":"traceutil/trace.go:171","msg":"trace[1282386220] linearizableReadLoop","detail":"{readStateIndex:25728; appliedIndex:25727; }","duration":"184.924416ms","start":"2026-05-09T13:41:31.108163Z","end":"2026-05-09T13:41:31.293087Z","steps":["trace[1282386220] 'read index received' (duration: 71.380678ms)","trace[1282386220] 'applied index is now lower than readState.Index' (duration: 113.542238ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:41:31.293122Z","caller":"traceutil/trace.go:171","msg":"trace[1184441585] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"265.480214ms","start":"2026-05-09T13:41:31.027609Z","end":"2026-05-09T13:41:31.293089Z","steps":["trace[1184441585] 'process raft request' (duration: 151.903815ms)","trace[1184441585] 'compare' (duration: 113.208789ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:41:31.293285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.127373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-09T13:41:31.293345Z","caller":"traceutil/trace.go:171","msg":"trace[1965974423] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24916; }","duration":"185.230895ms","start":"2026-05-09T13:41:31.1081Z","end":"2026-05-09T13:41:31.293331Z","steps":["trace[1965974423] 'agreement among raft nodes before linearized reading' (duration: 185.042089ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:41:31.569479Z","caller":"traceutil/trace.go:171","msg":"trace[461332487] linearizableReadLoop","detail":"{readStateIndex:25730; appliedIndex:25729; }","duration":"191.774353ms","start":"2026-05-09T13:41:31.377669Z","end":"2026-05-09T13:41:31.569444Z","steps":["trace[461332487] 'read index received' (duration: 108.037738ms)","trace[461332487] 'applied index is now lower than readState.Index' (duration: 83.735235ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:41:31.569511Z","caller":"traceutil/trace.go:171","msg":"trace[1271500300] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"218.000908ms","start":"2026-05-09T13:41:31.35147Z","end":"2026-05-09T13:41:31.569471Z","steps":["trace[1271500300] 'process raft request' (duration: 134.216822ms)","trace[1271500300] 'compare' (duration: 83.526929ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:41:31.569608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.915346ms","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-05-09T13:41:31.569687Z","caller":"traceutil/trace.go:171","msg":"trace[1569447443] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24918; }","duration":"192.05145ms","start":"2026-05-09T13:41:31.377615Z","end":"2026-05-09T13:41:31.569666Z","steps":["trace[1569447443] 'agreement among raft nodes before linearized reading' (duration: 191.915256ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:41:45.629357Z","caller":"traceutil/trace.go:171","msg":"trace[2132536076] linearizableReadLoop","detail":"{readStateIndex:25920; appliedIndex:25919; }","duration":"182.056757ms","start":"2026-05-09T13:41:45.447275Z","end":"2026-05-09T13:41:45.629332Z","steps":["trace[2132536076] 'read index received' (duration: 129.262067ms)","trace[2132536076] 'applied index is now lower than readState.Index' (duration: 52.79319ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:41:45.62948Z","caller":"traceutil/trace.go:171","msg":"trace[150496612] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"185.280616ms","start":"2026-05-09T13:41:45.444177Z","end":"2026-05-09T13:41:45.629457Z","steps":["trace[150496612] 'process raft request' (duration: 132.440544ms)","trace[150496612] 'compare' (duration: 52.204355ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:41:45.629587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.293834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:41:45.629633Z","caller":"traceutil/trace.go:171","msg":"trace[969026872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25103; }","duration":"182.349755ms","start":"2026-05-09T13:41:45.44727Z","end":"2026-05-09T13:41:45.629619Z","steps":["trace[969026872] 'agreement among raft nodes before linearized reading' (duration: 182.194501ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:16.841855Z","caller":"traceutil/trace.go:171","msg":"trace[358746681] linearizableReadLoop","detail":"{readStateIndex:26175; appliedIndex:26174; }","duration":"189.453989ms","start":"2026-05-09T13:42:16.652373Z","end":"2026-05-09T13:42:16.841827Z","steps":["trace[358746681] 'read index received' (duration: 189.239294ms)","trace[358746681] 'applied index is now lower than readState.Index' (duration: 213.775µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:42:16.841988Z","caller":"traceutil/trace.go:171","msg":"trace[664399736] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"253.779095ms","start":"2026-05-09T13:42:16.588196Z","end":"2026-05-09T13:42:16.841975Z","steps":["trace[664399736] 'process raft request' (duration: 253.484477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:16.842178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.313338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T13:42:16.842254Z","caller":"traceutil/trace.go:171","msg":"trace[291762007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25352; }","duration":"188.435621ms","start":"2026-05-09T13:42:16.6538Z","end":"2026-05-09T13:42:16.842236Z","steps":["trace[291762007] 'agreement among raft nodes before linearized reading' (duration: 188.251216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:16.842292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.899691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:42:16.842344Z","caller":"traceutil/trace.go:171","msg":"trace[1046204324] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25352; }","duration":"189.986624ms","start":"2026-05-09T13:42:16.652343Z","end":"2026-05-09T13:42:16.84233Z","steps":["trace[1046204324] 'agreement among raft nodes before linearized reading' (duration: 189.895071ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:17.054313Z","caller":"traceutil/trace.go:171","msg":"trace[166284131] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"208.666755ms","start":"2026-05-09T13:42:16.845605Z","end":"2026-05-09T13:42:17.054272Z","steps":["trace[166284131] 'process raft request' (duration: 122.715379ms)","trace[166284131] 'compare' (duration: 85.817912ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:42:17.399494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.004964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T13:42:17.399586Z","caller":"traceutil/trace.go:171","msg":"trace[1489487902] linearizableReadLoop","detail":"{readStateIndex:26177; appliedIndex:26176; }","duration":"110.368282ms","start":"2026-05-09T13:42:17.289201Z","end":"2026-05-09T13:42:17.399569Z","steps":["trace[1489487902] 'read index received' (duration: 41.631µs)","trace[1489487902] 'applied index is now lower than readState.Index' (duration: 110.325161ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:42:17.399701Z","caller":"traceutil/trace.go:171","msg":"trace[1462412968] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"244.511072ms","start":"2026-05-09T13:42:17.155157Z","end":"2026-05-09T13:42:17.399668Z","steps":["trace[1462412968] 'process raft request' (duration: 109.248792ms)","trace[1462412968] 'compare' (duration: 134.559981ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:42:17.399728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.538747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-09T13:42:17.399794Z","caller":"traceutil/trace.go:171","msg":"trace[1174142704] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25354; }","duration":"110.63707ms","start":"2026-05-09T13:42:17.289137Z","end":"2026-05-09T13:42:17.399774Z","steps":["trace[1174142704] 'agreement among raft nodes before linearized reading' (duration: 110.477376ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:24.313954Z","caller":"traceutil/trace.go:171","msg":"trace[1963939699] linearizableReadLoop","detail":"{readStateIndex:26224; appliedIndex:26223; }","duration":"344.565502ms","start":"2026-05-09T13:42:23.969357Z","end":"2026-05-09T13:42:24.313922Z","steps":["trace[1963939699] 'read index received' (duration: 314.813ms)","trace[1963939699] 'applied index is now lower than readState.Index' (duration: 29.750962ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:42:24.314031Z","caller":"traceutil/trace.go:171","msg":"trace[34383641] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"492.375495ms","start":"2026-05-09T13:42:23.821622Z","end":"2026-05-09T13:42:24.313997Z","steps":["trace[34383641] 'process raft request' (duration: 462.533231ms)","trace[34383641] 'compare' (duration: 29.578707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:42:24.31411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.724217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:42:24.314197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:42:23.821599Z","time spent":"492.509779ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:42:24.314217Z","caller":"traceutil/trace.go:171","msg":"trace[894587316] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25400; }","duration":"344.885551ms","start":"2026-05-09T13:42:23.969311Z","end":"2026-05-09T13:42:24.314196Z","steps":["trace[894587316] 'agreement among raft nodes before linearized reading' (duration: 344.702236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:24.314267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:42:23.969289Z","time spent":"344.966372ms","remote":"127.0.0.1:34574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-05-09T13:42:24.317048Z","caller":"traceutil/trace.go:171","msg":"trace[1011365173] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"203.381869ms","start":"2026-05-09T13:42:24.113651Z","end":"2026-05-09T13:42:24.317033Z","steps":["trace[1011365173] 'process raft request' (duration: 203.342988ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:24.31708Z","caller":"traceutil/trace.go:171","msg":"trace[1058486514] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"234.388005ms","start":"2026-05-09T13:42:24.08266Z","end":"2026-05-09T13:42:24.317048Z","steps":["trace[1058486514] 'process raft request' (duration: 234.166839ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:24.317164Z","caller":"traceutil/trace.go:171","msg":"trace[74966701] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"228.939656ms","start":"2026-05-09T13:42:24.088183Z","end":"2026-05-09T13:42:24.317123Z","steps":["trace[74966701] 'process raft request' (duration: 228.773531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:24.31885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.666764ms","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-05-09T13:42:24.318929Z","caller":"traceutil/trace.go:171","msg":"trace[1782706801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25404; }","duration":"274.792558ms","start":"2026-05-09T13:42:24.044119Z","end":"2026-05-09T13:42:24.318912Z","steps":["trace[1782706801] 'agreement among raft nodes before linearized reading' (duration: 274.577972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:24.319394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.073913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-09T13:42:24.319458Z","caller":"traceutil/trace.go:171","msg":"trace[1610803729] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:25404; }","duration":"217.195946ms","start":"2026-05-09T13:42:24.102246Z","end":"2026-05-09T13:42:24.319442Z","steps":["trace[1610803729] 'agreement among raft nodes before linearized reading' (duration: 217.039702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:24.319002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.344066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:42:24.320274Z","caller":"traceutil/trace.go:171","msg":"trace[807141552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25404; }","duration":"183.750614ms","start":"2026-05-09T13:42:24.136497Z","end":"2026-05-09T13:42:24.320247Z","steps":["trace[807141552] 'agreement among raft nodes before linearized reading' (duration: 182.290204ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:24.532231Z","caller":"traceutil/trace.go:171","msg":"trace[1670121225] linearizableReadLoop","detail":"{readStateIndex:26229; appliedIndex:26228; }","duration":"210.904885ms","start":"2026-05-09T13:42:24.321251Z","end":"2026-05-09T13:42:24.532156Z","steps":["trace[1670121225] 'read index received' (duration: 192.863603ms)","trace[1670121225] 'applied index is now lower than readState.Index' (duration: 18.039912ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:42:24.532647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.387998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:42:24.532694Z","caller":"traceutil/trace.go:171","msg":"trace[1197205838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25404; }","duration":"211.48122ms","start":"2026-05-09T13:42:24.321202Z","end":"2026-05-09T13:42:24.532683Z","steps":["trace[1197205838] 'agreement among raft nodes before linearized reading' (duration: 211.389338ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:24.623469Z","caller":"traceutil/trace.go:171","msg":"trace[1087393161] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"299.990307ms","start":"2026-05-09T13:42:24.323453Z","end":"2026-05-09T13:42:24.623444Z","steps":["trace[1087393161] 'process raft request' (duration: 299.799832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:24.623697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:42:24.323434Z","time spent":"300.174202ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:42:24.623829Z","caller":"traceutil/trace.go:171","msg":"trace[377699131] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"243.542846ms","start":"2026-05-09T13:42:24.380248Z","end":"2026-05-09T13:42:24.623791Z","steps":["trace[377699131] 'process raft request' (duration: 243.153545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:24.624195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.535683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T13:42:24.62428Z","caller":"traceutil/trace.go:171","msg":"trace[1473539306] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"243.112844ms","start":"2026-05-09T13:42:24.38115Z","end":"2026-05-09T13:42:24.624263Z","steps":["trace[1473539306] 'process raft request' (duration: 242.649092ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:24.624537Z","caller":"traceutil/trace.go:171","msg":"trace[1323300053] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25407; }","duration":"196.673166ms","start":"2026-05-09T13:42:24.427586Z","end":"2026-05-09T13:42:24.624259Z","steps":["trace[1323300053] 'agreement among raft nodes before linearized reading' (duration: 196.513132ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:24.717853Z","caller":"traceutil/trace.go:171","msg":"trace[536692220] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"184.130973ms","start":"2026-05-09T13:42:24.533703Z","end":"2026-05-09T13:42:24.717834Z","steps":["trace[536692220] 'process raft request' (duration: 183.593538ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:38.496547Z","caller":"traceutil/trace.go:171","msg":"trace[254689015] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"132.694471ms","start":"2026-05-09T13:42:38.363821Z","end":"2026-05-09T13:42:38.496516Z","steps":["trace[254689015] 'process raft request' (duration: 132.346062ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:38.668447Z","caller":"traceutil/trace.go:171","msg":"trace[331757435] linearizableReadLoop","detail":"{readStateIndex:26332; appliedIndex:26328; }","duration":"259.763999ms","start":"2026-05-09T13:42:38.408618Z","end":"2026-05-09T13:42:38.668382Z","steps":["trace[331757435] 'read index received' (duration: 87.763326ms)","trace[331757435] 'applied index is now lower than readState.Index' (duration: 171.999523ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:42:38.668664Z","caller":"traceutil/trace.go:171","msg":"trace[1628901014] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"295.722919ms","start":"2026-05-09T13:42:38.37292Z","end":"2026-05-09T13:42:38.668642Z","steps":["trace[1628901014] 'process raft request' (duration: 295.41606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:38.668797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.382969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-05-09T13:42:38.668803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.173819ms","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-05-09T13:42:38.66884Z","caller":"traceutil/trace.go:171","msg":"trace[1625367628] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25505; }","duration":"104.491982ms","start":"2026-05-09T13:42:38.564337Z","end":"2026-05-09T13:42:38.668829Z","steps":["trace[1625367628] 'agreement among raft nodes before linearized reading' (duration: 104.307177ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:38.668413Z","caller":"traceutil/trace.go:171","msg":"trace[1727600304] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"298.72782ms","start":"2026-05-09T13:42:38.369652Z","end":"2026-05-09T13:42:38.668379Z","steps":["trace[1727600304] 'process raft request' (duration: 272.937127ms)","trace[1727600304] 'compare' (duration: 25.477245ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:42:38.668872Z","caller":"traceutil/trace.go:171","msg":"trace[66553661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25505; }","duration":"260.308943ms","start":"2026-05-09T13:42:38.408546Z","end":"2026-05-09T13:42:38.668855Z","steps":["trace[66553661] 'agreement among raft nodes before linearized reading' (duration: 260.089457ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:38.668896Z","caller":"traceutil/trace.go:171","msg":"trace[1532640966] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"298.541625ms","start":"2026-05-09T13:42:38.370238Z","end":"2026-05-09T13:42:38.66878Z","steps":["trace[1532640966] 'process raft request' (duration: 298.034882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:38.893924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.199694ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:42:38.89403Z","caller":"traceutil/trace.go:171","msg":"trace[928402883] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25505; }","duration":"122.328248ms","start":"2026-05-09T13:42:38.771687Z","end":"2026-05-09T13:42:38.894015Z","steps":["trace[928402883] 'range keys from in-memory index tree' (duration: 122.183754ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:38.894194Z","caller":"traceutil/trace.go:171","msg":"trace[1845798231] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"222.027659ms","start":"2026-05-09T13:42:38.672129Z","end":"2026-05-09T13:42:38.894156Z","steps":["trace[1845798231] 'process raft request' (duration: 136.467934ms)","trace[1845798231] 'compare' (duration: 85.39806ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:42:38.910059Z","caller":"traceutil/trace.go:171","msg":"trace[152201002] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"237.286644ms","start":"2026-05-09T13:42:38.672735Z","end":"2026-05-09T13:42:38.910021Z","steps":["trace[152201002] 'process raft request' (duration: 237.095249ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:38.910054Z","caller":"traceutil/trace.go:171","msg":"trace[1481897629] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"236.579625ms","start":"2026-05-09T13:42:38.673445Z","end":"2026-05-09T13:42:38.910025Z","steps":["trace[1481897629] 'process raft request' (duration: 236.515933ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:38.910297Z","caller":"traceutil/trace.go:171","msg":"trace[1348098543] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"224.525396ms","start":"2026-05-09T13:42:38.685749Z","end":"2026-05-09T13:42:38.910274Z","steps":["trace[1348098543] 'process raft request' (duration: 224.239619ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:38.910308Z","caller":"traceutil/trace.go:171","msg":"trace[394736224] linearizableReadLoop","detail":"{readStateIndex:26336; appliedIndex:26332; }","duration":"213.926837ms","start":"2026-05-09T13:42:38.696347Z","end":"2026-05-09T13:42:38.910274Z","steps":["trace[394736224] 'read index received' (duration: 112.197262ms)","trace[394736224] 'applied index is now lower than readState.Index' (duration: 101.726765ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:42:38.910489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.122464ms","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-05-09T13:42:38.910545Z","caller":"traceutil/trace.go:171","msg":"trace[234694454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25509; }","duration":"214.226116ms","start":"2026-05-09T13:42:38.696306Z","end":"2026-05-09T13:42:38.910532Z","steps":["trace[234694454] 'agreement among raft nodes before linearized reading' (duration: 214.073272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:38.911586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.414318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T13:42:38.911719Z","caller":"traceutil/trace.go:171","msg":"trace[820373109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25509; }","duration":"119.613724ms","start":"2026-05-09T13:42:38.792072Z","end":"2026-05-09T13:42:38.911686Z","steps":["trace[820373109] 'agreement among raft nodes before linearized reading' (duration: 118.460793ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:48.173856Z","caller":"traceutil/trace.go:171","msg":"trace[24644782] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"271.019265ms","start":"2026-05-09T13:42:47.902815Z","end":"2026-05-09T13:42:48.173835Z","steps":["trace[24644782] 'process raft request' (duration: 270.884381ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:42:48.194892Z","caller":"traceutil/trace.go:171","msg":"trace[1852507380] linearizableReadLoop","detail":"{readStateIndex:26547; appliedIndex:26546; }","duration":"247.01281ms","start":"2026-05-09T13:42:47.947859Z","end":"2026-05-09T13:42:48.194872Z","steps":["trace[1852507380] 'read index received' (duration: 226.001037ms)","trace[1852507380] 'applied index is now lower than readState.Index' (duration: 21.011033ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:42:48.195025Z","caller":"traceutil/trace.go:171","msg":"trace[1496601111] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"267.080798ms","start":"2026-05-09T13:42:47.927907Z","end":"2026-05-09T13:42:48.194988Z","steps":["trace[1496601111] 'process raft request' (duration: 266.814051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:48.195094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.30528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T13:42:48.195131Z","caller":"traceutil/trace.go:171","msg":"trace[1230040714] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25716; }","duration":"217.385492ms","start":"2026-05-09T13:42:47.977736Z","end":"2026-05-09T13:42:48.195121Z","steps":["trace[1230040714] 'agreement among raft nodes before linearized reading' (duration: 217.255868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:42:48.195186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.300508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-09T13:42:48.195271Z","caller":"traceutil/trace.go:171","msg":"trace[355470780] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25716; }","duration":"247.431171ms","start":"2026-05-09T13:42:47.947822Z","end":"2026-05-09T13:42:48.195253Z","steps":["trace[355470780] 'agreement among raft nodes before linearized reading' (duration: 247.163644ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:28.771689Z","caller":"traceutil/trace.go:171","msg":"trace[304024180] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"103.663879ms","start":"2026-05-09T13:43:28.667805Z","end":"2026-05-09T13:43:28.771469Z","steps":["trace[304024180] 'process raft request' (duration: 103.534715ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:29.167161Z","caller":"traceutil/trace.go:171","msg":"trace[1074147281] transaction","detail":"{read_only:false; response_revision:26041; number_of_response:1; }","duration":"332.02327ms","start":"2026-05-09T13:43:28.835102Z","end":"2026-05-09T13:43:29.167125Z","steps":["trace[1074147281] 'process raft request' (duration: 331.835625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:43:29.167374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:43:28.835026Z","time spent":"332.254607ms","remote":"127.0.0.1:36502","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-05-09T13:43:29.209442Z","caller":"traceutil/trace.go:171","msg":"trace[1227740435] transaction","detail":"{read_only:false; response_revision:26043; number_of_response:1; }","duration":"355.539582ms","start":"2026-05-09T13:43:28.853874Z","end":"2026-05-09T13:43:29.209414Z","steps":["trace[1227740435] 'process raft request' (duration: 355.458879ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:29.209464Z","caller":"traceutil/trace.go:171","msg":"trace[264034451] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"369.754579ms","start":"2026-05-09T13:43:28.839682Z","end":"2026-05-09T13:43:29.209437Z","steps":["trace[264034451] 'process raft request' (duration: 369.492742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:43:29.209612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:43:28.853853Z","time spent":"355.671186ms","remote":"127.0.0.1:36502","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-05-09T13:43:29.209689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:43:28.83966Z","time spent":"369.888243ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:43:29.209838Z","caller":"traceutil/trace.go:171","msg":"trace[1372603769] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"112.272734ms","start":"2026-05-09T13:43:29.097525Z","end":"2026-05-09T13:43:29.209798Z","steps":["trace[1372603769] 'process raft request' (duration: 111.849713ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:29.210034Z","caller":"traceutil/trace.go:171","msg":"trace[1773389758] linearizableReadLoop","detail":"{readStateIndex:26882; appliedIndex:26879; }","duration":"343.949605ms","start":"2026-05-09T13:43:28.866066Z","end":"2026-05-09T13:43:29.210016Z","steps":["trace[1773389758] 'read index received' (duration: 300.893679ms)","trace[1773389758] 'applied index is now lower than readState.Index' (duration: 43.052876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:43:29.210167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.421798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:43:29.210214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.931206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:43:29.210231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.125861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T13:43:29.210276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.028188ms","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-05-09T13:43:29.210284Z","caller":"traceutil/trace.go:171","msg":"trace[326982212] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26045; }","duration":"167.010338ms","start":"2026-05-09T13:43:29.043254Z","end":"2026-05-09T13:43:29.210265Z","steps":["trace[326982212] 'agreement among raft nodes before linearized reading' (duration: 166.886184ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:29.210289Z","caller":"traceutil/trace.go:171","msg":"trace[1772298968] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:26045; }","duration":"344.229953ms","start":"2026-05-09T13:43:28.866038Z","end":"2026-05-09T13:43:29.210268Z","steps":["trace[1772298968] 'agreement among raft nodes before linearized reading' (duration: 344.024567ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:29.210307Z","caller":"traceutil/trace.go:171","msg":"trace[626471732] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26045; }","duration":"167.071699ms","start":"2026-05-09T13:43:29.043226Z","end":"2026-05-09T13:43:29.210298Z","steps":["trace[626471732] 'agreement among raft nodes before linearized reading' (duration: 167.026758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:43:29.210351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:43:28.866027Z","time spent":"344.297185ms","remote":"127.0.0.1:36598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":99,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-09T13:43:29.210235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.874841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-05-09T13:43:29.210474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.273294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-05-09T13:43:29.210483Z","caller":"traceutil/trace.go:171","msg":"trace[2094490233] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26045; }","duration":"301.152088ms","start":"2026-05-09T13:43:28.909319Z","end":"2026-05-09T13:43:29.210471Z","steps":["trace[2094490233] 'agreement among raft nodes before linearized reading' (duration: 300.795589ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:29.210499Z","caller":"traceutil/trace.go:171","msg":"trace[1190399365] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26045; }","duration":"167.361507ms","start":"2026-05-09T13:43:29.043132Z","end":"2026-05-09T13:43:29.210493Z","steps":["trace[1190399365] 'agreement among raft nodes before linearized reading' (duration: 167.290445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:43:29.21052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:43:28.909303Z","time spent":"301.205609ms","remote":"127.0.0.1:36438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-09T13:43:29.21058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.137051ms","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-05-09T13:43:29.210597Z","caller":"traceutil/trace.go:171","msg":"trace[205284196] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26045; }","duration":"167.168401ms","start":"2026-05-09T13:43:29.043423Z","end":"2026-05-09T13:43:29.210592Z","steps":["trace[205284196] 'agreement among raft nodes before linearized reading' (duration: 167.137531ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:29.210232Z","caller":"traceutil/trace.go:171","msg":"trace[1077307096] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26045; }","duration":"149.52411ms","start":"2026-05-09T13:43:29.060691Z","end":"2026-05-09T13:43:29.210215Z","steps":["trace[1077307096] 'agreement among raft nodes before linearized reading' (duration: 149.435528ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:29.347885Z","caller":"traceutil/trace.go:171","msg":"trace[1932925118] transaction","detail":"{read_only:false; response_revision:26046; number_of_response:1; }","duration":"131.389045ms","start":"2026-05-09T13:43:29.216464Z","end":"2026-05-09T13:43:29.347853Z","steps":["trace[1932925118] 'process raft request' (duration: 129.207535ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:29.349299Z","caller":"traceutil/trace.go:171","msg":"trace[1933708668] linearizableReadLoop","detail":"{readStateIndex:26886; appliedIndex:26884; }","duration":"128.130776ms","start":"2026-05-09T13:43:29.221138Z","end":"2026-05-09T13:43:29.349269Z","steps":["trace[1933708668] 'read index received' (duration: 124.504936ms)","trace[1933708668] 'applied index is now lower than readState.Index' (duration: 3.62478ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:43:29.349352Z","caller":"traceutil/trace.go:171","msg":"trace[1442763786] transaction","detail":"{read_only:false; response_revision:26047; number_of_response:1; }","duration":"131.534959ms","start":"2026-05-09T13:43:29.217784Z","end":"2026-05-09T13:43:29.349319Z","steps":["trace[1442763786] 'process raft request' (duration: 131.334693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:43:29.349602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.404964ms","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-05-09T13:43:29.349693Z","caller":"traceutil/trace.go:171","msg":"trace[884616059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26047; }","duration":"128.557488ms","start":"2026-05-09T13:43:29.221118Z","end":"2026-05-09T13:43:29.349676Z","steps":["trace[884616059] 'agreement among raft nodes before linearized reading' (duration: 128.300511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:43:29.349663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.449798ms","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-05-09T13:43:29.349812Z","caller":"traceutil/trace.go:171","msg":"trace[2038099365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26047; }","duration":"127.633213ms","start":"2026-05-09T13:43:29.222162Z","end":"2026-05-09T13:43:29.349795Z","steps":["trace[2038099365] 'agreement among raft nodes before linearized reading' (duration: 127.327965ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:43:40.938326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23190} {"level":"info","ts":"2026-05-09T13:43:41.077814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23190,"took":"135.25435ms","hash":68382365,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":35717120,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-09T13:43:41.077886Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":68382365,"revision":23190,"compact-revision":20186} {"level":"info","ts":"2026-05-09T13:44:00.873814Z","caller":"traceutil/trace.go:171","msg":"trace[1537038330] linearizableReadLoop","detail":"{readStateIndex:27125; appliedIndex:27124; }","duration":"172.818976ms","start":"2026-05-09T13:44:00.700972Z","end":"2026-05-09T13:44:00.873791Z","steps":["trace[1537038330] 'read index received' (duration: 172.58021ms)","trace[1537038330] 'applied index is now lower than readState.Index' (duration: 237.756µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:44:00.873937Z","caller":"traceutil/trace.go:171","msg":"trace[816541626] transaction","detail":"{read_only:false; response_revision:26277; number_of_response:1; }","duration":"265.730471ms","start":"2026-05-09T13:44:00.608173Z","end":"2026-05-09T13:44:00.873904Z","steps":["trace[816541626] 'process raft request' (duration: 265.448734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:44:00.874004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.016001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T13:44:00.874042Z","caller":"traceutil/trace.go:171","msg":"trace[1365695091] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:26277; }","duration":"173.107334ms","start":"2026-05-09T13:44:00.700925Z","end":"2026-05-09T13:44:00.874032Z","steps":["trace[1365695091] 'agreement among raft nodes before linearized reading' (duration: 173.006901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:44:00.874084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.137971ms","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-05-09T13:44:00.87418Z","caller":"traceutil/trace.go:171","msg":"trace[1111943067] 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:26277; }","duration":"123.303725ms","start":"2026-05-09T13:44:00.750856Z","end":"2026-05-09T13:44:00.87416Z","steps":["trace[1111943067] 'agreement among raft nodes before linearized reading' (duration: 123.12488ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:44:01.080146Z","caller":"traceutil/trace.go:171","msg":"trace[17708666] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"188.528054ms","start":"2026-05-09T13:44:00.891574Z","end":"2026-05-09T13:44:01.080102Z","steps":["trace[17708666] 'process raft request' (duration: 92.264327ms)","trace[17708666] 'compare' (duration: 95.777534ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:44:01.402993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.35585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-05-09T13:44:01.403075Z","caller":"traceutil/trace.go:171","msg":"trace[142153828] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26278; }","duration":"118.475673ms","start":"2026-05-09T13:44:01.28458Z","end":"2026-05-09T13:44:01.403056Z","steps":["trace[142153828] 'range keys from in-memory index tree' (duration: 118.162995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:44:01.403073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.454063ms","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-05-09T13:44:01.40314Z","caller":"traceutil/trace.go:171","msg":"trace[1013745293] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26278; }","duration":"118.573236ms","start":"2026-05-09T13:44:01.284551Z","end":"2026-05-09T13:44:01.403124Z","steps":["trace[1013745293] 'range keys from in-memory index tree' (duration: 118.341639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:44:01.403262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.163703ms","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-05-09T13:44:01.403279Z","caller":"traceutil/trace.go:171","msg":"trace[2114714239] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26278; }","duration":"118.180265ms","start":"2026-05-09T13:44:01.285094Z","end":"2026-05-09T13:44:01.403274Z","steps":["trace[2114714239] 'range keys from in-memory index tree' (duration: 118.099592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:44:01.40333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.134753ms","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-05-09T13:44:01.403346Z","caller":"traceutil/trace.go:171","msg":"trace[1308076386] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26278; }","duration":"118.148694ms","start":"2026-05-09T13:44:01.285191Z","end":"2026-05-09T13:44:01.40334Z","steps":["trace[1308076386] 'range keys from in-memory index tree' (duration: 118.066851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:44:01.403331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.799098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:44:01.403388Z","caller":"traceutil/trace.go:171","msg":"trace[815422668] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26278; }","duration":"134.907302ms","start":"2026-05-09T13:44:01.268464Z","end":"2026-05-09T13:44:01.403372Z","steps":["trace[815422668] 'count revisions from in-memory index tree' (duration: 134.664934ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:44:01.403456Z","caller":"traceutil/trace.go:171","msg":"trace[782603607] transaction","detail":"{read_only:false; response_revision:26279; number_of_response:1; }","duration":"117.302601ms","start":"2026-05-09T13:44:01.286132Z","end":"2026-05-09T13:44:01.403435Z","steps":["trace[782603607] 'process raft request' (duration: 71.09947ms)","trace[782603607] 'compare' (duration: 45.611735ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:44:01.427376Z","caller":"traceutil/trace.go:171","msg":"trace[295302984] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"121.658679ms","start":"2026-05-09T13:44:01.305692Z","end":"2026-05-09T13:44:01.427351Z","steps":["trace[295302984] 'process raft request' (duration: 121.030692ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:44:33.15633Z","caller":"traceutil/trace.go:171","msg":"trace[798704329] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"168.236571ms","start":"2026-05-09T13:44:32.988076Z","end":"2026-05-09T13:44:33.156312Z","steps":["trace[798704329] 'process raft request' (duration: 167.759128ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:44:33.373393Z","caller":"traceutil/trace.go:171","msg":"trace[1602619926] transaction","detail":"{read_only:false; response_revision:26510; number_of_response:1; }","duration":"180.398132ms","start":"2026-05-09T13:44:33.192942Z","end":"2026-05-09T13:44:33.37334Z","steps":["trace[1602619926] 'process raft request' (duration: 114.3799ms)","trace[1602619926] 'compare' (duration: 65.827177ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:44:33.471148Z","caller":"traceutil/trace.go:171","msg":"trace[1111320591] transaction","detail":"{read_only:false; response_revision:26511; number_of_response:1; }","duration":"182.041738ms","start":"2026-05-09T13:44:33.289085Z","end":"2026-05-09T13:44:33.471127Z","steps":["trace[1111320591] 'process raft request' (duration: 181.877673ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:00.927158Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006bc4.wal"} {"level":"info","ts":"2026-05-09T13:45:03.486428Z","caller":"traceutil/trace.go:171","msg":"trace[1909915720] transaction","detail":"{read_only:false; response_revision:26744; number_of_response:1; }","duration":"224.484676ms","start":"2026-05-09T13:45:03.261914Z","end":"2026-05-09T13:45:03.486399Z","steps":["trace[1909915720] 'process raft request' (duration: 224.199558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:03.847489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.213798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T13:45:03.847604Z","caller":"traceutil/trace.go:171","msg":"trace[690333690] transaction","detail":"{read_only:false; response_revision:26745; number_of_response:1; }","duration":"369.77862ms","start":"2026-05-09T13:45:03.477803Z","end":"2026-05-09T13:45:03.847582Z","steps":["trace[690333690] 'process raft request' (duration: 208.385537ms)","trace[690333690] 'compare' (duration: 160.957992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:45:03.847671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.477779Z","time spent":"369.863433ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T13:45:04.403499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.695793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T13:45:04.403712Z","caller":"traceutil/trace.go:171","msg":"trace[1457994894] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"750.052177ms","start":"2026-05-09T13:45:03.653646Z","end":"2026-05-09T13:45:04.403698Z","steps":["trace[1457994894] 'process raft request' (duration: 749.943514ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:04.40372Z","caller":"traceutil/trace.go:171","msg":"trace[405760746] transaction","detail":"{read_only:false; response_revision:26746; number_of_response:1; }","duration":"906.914297ms","start":"2026-05-09T13:45:03.496765Z","end":"2026-05-09T13:45:04.403679Z","steps":["trace[405760746] 'process raft request' (duration: 663.955757ms)","trace[405760746] 'compare' (duration: 242.5671ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:45:04.403769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.653634Z","time spent":"750.109068ms","remote":"127.0.0.1:36502","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-05-09T13:45:04.403859Z","caller":"traceutil/trace.go:171","msg":"trace[1626891815] transaction","detail":"{read_only:false; response_revision:26748; number_of_response:1; }","duration":"735.219623ms","start":"2026-05-09T13:45:03.668627Z","end":"2026-05-09T13:45:04.403847Z","steps":["trace[1626891815] 'process raft request' (duration: 735.036968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.403881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.49674Z","time spent":"907.060441ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T13:45:04.403924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.668597Z","time spent":"735.295435ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:45:04.404927Z","caller":"traceutil/trace.go:171","msg":"trace[1875133540] transaction","detail":"{read_only:false; response_revision:26749; number_of_response:1; }","duration":"736.024064ms","start":"2026-05-09T13:45:03.66887Z","end":"2026-05-09T13:45:04.404894Z","steps":["trace[1875133540] 'process raft request' (duration: 734.921774ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:04.404988Z","caller":"traceutil/trace.go:171","msg":"trace[1112924953] transaction","detail":"{read_only:false; response_revision:26752; number_of_response:1; }","duration":"394.541866ms","start":"2026-05-09T13:45:04.010438Z","end":"2026-05-09T13:45:04.40498Z","steps":["trace[1112924953] 'process raft request' (duration: 394.517065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:04.010415Z","time spent":"394.597637ms","remote":"127.0.0.1:36502","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-05-09T13:45:04.405002Z","caller":"traceutil/trace.go:171","msg":"trace[854413467] transaction","detail":"{read_only:false; response_revision:26751; number_of_response:1; }","duration":"735.264863ms","start":"2026-05-09T13:45:03.669707Z","end":"2026-05-09T13:45:04.404972Z","steps":["trace[854413467] 'process raft request' (duration: 735.202571ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:04.405079Z","caller":"traceutil/trace.go:171","msg":"trace[1260148081] linearizableReadLoop","detail":"{readStateIndex:27607; appliedIndex:27605; }","duration":"772.640723ms","start":"2026-05-09T13:45:03.632382Z","end":"2026-05-09T13:45:04.405022Z","steps":["trace[1260148081] 'read index received' (duration: 53.722377ms)","trace[1260148081] 'applied index is now lower than readState.Index' (duration: 718.914626ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:45:04.405228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.834758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:04.405274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.931413ms","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-05-09T13:45:04.405285Z","caller":"traceutil/trace.go:171","msg":"trace[1223926594] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26752; }","duration":"772.936331ms","start":"2026-05-09T13:45:03.632332Z","end":"2026-05-09T13:45:04.405268Z","steps":["trace[1223926594] 'agreement among raft nodes before linearized reading' (duration: 772.811118ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:04.405316Z","caller":"traceutil/trace.go:171","msg":"trace[749512839] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26752; }","duration":"772.014526ms","start":"2026-05-09T13:45:03.633289Z","end":"2026-05-09T13:45:04.405303Z","steps":["trace[749512839] 'agreement among raft nodes before linearized reading' (duration: 771.919653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.632313Z","time spent":"773.002343ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"warn","ts":"2026-05-09T13:45:04.405333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.297474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T13:45:04.405362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.633256Z","time spent":"772.097847ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"info","ts":"2026-05-09T13:45:04.405372Z","caller":"traceutil/trace.go:171","msg":"trace[739320461] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26752; }","duration":"370.367126ms","start":"2026-05-09T13:45:04.034994Z","end":"2026-05-09T13:45:04.405361Z","steps":["trace[739320461] 'agreement among raft nodes before linearized reading' (duration: 370.290544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.511191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:04.405393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.168447ms","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-05-09T13:45:04.405457Z","caller":"traceutil/trace.go:171","msg":"trace[1333133563] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26752; }","duration":"392.638924ms","start":"2026-05-09T13:45:04.0128Z","end":"2026-05-09T13:45:04.405439Z","steps":["trace[1333133563] 'agreement among raft nodes before linearized reading' (duration: 392.467139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.601582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:04.405451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.638669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:04.405508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.937396ms","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-05-09T13:45:04.405513Z","caller":"traceutil/trace.go:171","msg":"trace[924725179] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26752; }","duration":"392.664454ms","start":"2026-05-09T13:45:04.012834Z","end":"2026-05-09T13:45:04.405498Z","steps":["trace[924725179] 'agreement among raft nodes before linearized reading' (duration: 392.615883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.40553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:04.012792Z","time spent":"392.727966ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"info","ts":"2026-05-09T13:45:04.405545Z","caller":"traceutil/trace.go:171","msg":"trace[130279959] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26752; }","duration":"770.993068ms","start":"2026-05-09T13:45:03.634543Z","end":"2026-05-09T13:45:04.405536Z","steps":["trace[130279959] 'agreement among raft nodes before linearized reading' (duration: 770.942197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:04.012816Z","time spent":"392.731176ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-05-09T13:45:04.405563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.353118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:04.405578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.457163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:04.40559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.28164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"warn","ts":"2026-05-09T13:45:04.405594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.634486Z","time spent":"771.08617ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"info","ts":"2026-05-09T13:45:04.405609Z","caller":"traceutil/trace.go:171","msg":"trace[1770756919] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26752; }","duration":"771.42261ms","start":"2026-05-09T13:45:03.634173Z","end":"2026-05-09T13:45:04.405596Z","steps":["trace[1770756919] 'agreement among raft nodes before linearized reading' (duration: 771.346258ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:04.405608Z","caller":"traceutil/trace.go:171","msg":"trace[1383936488] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26752; }","duration":"770.518025ms","start":"2026-05-09T13:45:03.635082Z","end":"2026-05-09T13:45:04.4056Z","steps":["trace[1383936488] 'agreement among raft nodes before linearized reading' (duration: 770.474534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.40564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.634161Z","time spent":"771.473741ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"info","ts":"2026-05-09T13:45:04.405503Z","caller":"traceutil/trace.go:171","msg":"trace[592325982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26752; }","duration":"596.313791ms","start":"2026-05-09T13:45:03.809171Z","end":"2026-05-09T13:45:04.405485Z","steps":["trace[592325982] 'agreement among raft nodes before linearized reading' (duration: 596.070794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.498384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"warn","ts":"2026-05-09T13:45:04.405652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.635073Z","time spent":"770.571646ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-05-09T13:45:04.405388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.998523ms","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-05-09T13:45:04.405613Z","caller":"traceutil/trace.go:171","msg":"trace[1702290178] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26752; }","duration":"762.316831ms","start":"2026-05-09T13:45:03.643291Z","end":"2026-05-09T13:45:04.405607Z","steps":["trace[1702290178] 'agreement among raft nodes before linearized reading' (duration: 761.928521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.911146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-09T13:45:04.405709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.643284Z","time spent":"762.420304ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5103,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-05-09T13:45:04.405535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.723454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-09T13:45:04.405713Z","caller":"traceutil/trace.go:171","msg":"trace[808970550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26752; }","duration":"596.325942ms","start":"2026-05-09T13:45:03.809375Z","end":"2026-05-09T13:45:04.4057Z","steps":["trace[808970550] 'agreement among raft nodes before linearized reading' (duration: 595.89933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.194208ms","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-05-09T13:45:04.405753Z","caller":"traceutil/trace.go:171","msg":"trace[1846441611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26752; }","duration":"601.091741ms","start":"2026-05-09T13:45:03.80463Z","end":"2026-05-09T13:45:04.405721Z","steps":["trace[1846441611] 'agreement among raft nodes before linearized reading' (duration: 600.799163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.809343Z","time spent":"596.424433ms","remote":"127.0.0.1:36502","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-05-09T13:45:04.405798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.683392ms","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-05-09T13:45:04.405785Z","caller":"traceutil/trace.go:171","msg":"trace[723854013] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26752; }","duration":"500.283631ms","start":"2026-05-09T13:45:03.905492Z","end":"2026-05-09T13:45:04.405775Z","steps":["trace[723854013] 'agreement among raft nodes before linearized reading' (duration: 500.210489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.379336ms","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-05-09T13:45:04.405808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.210071ms","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-05-09T13:45:04.405819Z","caller":"traceutil/trace.go:171","msg":"trace[908413430] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26752; }","duration":"393.773885ms","start":"2026-05-09T13:45:04.012039Z","end":"2026-05-09T13:45:04.405813Z","steps":["trace[908413430] 'agreement among raft nodes before linearized reading' (duration: 393.730253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.905458Z","time spent":"500.362753ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"info","ts":"2026-05-09T13:45:04.405835Z","caller":"traceutil/trace.go:171","msg":"trace[592813920] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26752; }","duration":"499.271593ms","start":"2026-05-09T13:45:03.906558Z","end":"2026-05-09T13:45:04.405829Z","steps":["trace[592813920] 'agreement among raft nodes before linearized reading' (duration: 499.193071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:04.012029Z","time spent":"393.807435ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"info","ts":"2026-05-09T13:45:04.405842Z","caller":"traceutil/trace.go:171","msg":"trace[1568091030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26752; }","duration":"499.482619ms","start":"2026-05-09T13:45:03.906345Z","end":"2026-05-09T13:45:04.405827Z","steps":["trace[1568091030] 'agreement among raft nodes before linearized reading' (duration: 499.078448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.405861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.906546Z","time spent":"499.311386ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"warn","ts":"2026-05-09T13:45:04.405882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.906331Z","time spent":"499.537771ms","remote":"127.0.0.1:36502","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-05-09T13:45:04.405647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.700183ms","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-05-09T13:45:04.405993Z","caller":"traceutil/trace.go:171","msg":"trace[95654949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26752; }","duration":"608.084924ms","start":"2026-05-09T13:45:03.7979Z","end":"2026-05-09T13:45:04.405985Z","steps":["trace[95654949] 'agreement among raft nodes before linearized reading' (duration: 607.633001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.406016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.797876Z","time spent":"608.133845ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T13:45:04.40547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.924791ms","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-05-09T13:45:04.406071Z","caller":"traceutil/trace.go:171","msg":"trace[867039929] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26752; }","duration":"500.573089ms","start":"2026-05-09T13:45:03.905486Z","end":"2026-05-09T13:45:04.406059Z","steps":["trace[867039929] 'agreement among raft nodes before linearized reading' (duration: 499.720955ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:04.405303Z","caller":"traceutil/trace.go:171","msg":"trace[597338873] transaction","detail":"{read_only:false; response_revision:26750; number_of_response:1; }","duration":"736.23955ms","start":"2026-05-09T13:45:03.669005Z","end":"2026-05-09T13:45:04.405245Z","steps":["trace[597338873] 'process raft request' (duration: 735.196112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.406119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.905409Z","time spent":"500.699313ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-05-09T13:45:04.406216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.668849Z","time spent":"736.144728ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T13:45:04.405717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.408628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"warn","ts":"2026-05-09T13:45:04.406246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.668976Z","time spent":"737.182127ms","remote":"127.0.0.1:36502","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-05-09T13:45:04.405728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.729556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"warn","ts":"2026-05-09T13:45:04.406602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.669693Z","time spent":"736.24653ms","remote":"127.0.0.1:36502","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-05-09T13:45:04.406612Z","caller":"traceutil/trace.go:171","msg":"trace[53892080] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26752; }","duration":"500.636981ms","start":"2026-05-09T13:45:03.905936Z","end":"2026-05-09T13:45:04.406573Z","steps":["trace[53892080] 'agreement among raft nodes before linearized reading' (duration: 499.756016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.406722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.905925Z","time spent":"500.791715ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5103,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"info","ts":"2026-05-09T13:45:04.405676Z","caller":"traceutil/trace.go:171","msg":"trace[1149685455] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26752; }","duration":"770.543306ms","start":"2026-05-09T13:45:03.635128Z","end":"2026-05-09T13:45:04.405671Z","steps":["trace[1149685455] 'agreement among raft nodes before linearized reading' (duration: 770.402022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.406839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.635123Z","time spent":"771.676667ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5103,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-05-09T13:45:04.405407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:04.034984Z","time spent":"370.417418ms","remote":"127.0.0.1:37790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-09T13:45:04.405517Z","caller":"traceutil/trace.go:171","msg":"trace[1648640804] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26752; }","duration":"770.749971ms","start":"2026-05-09T13:45:03.634761Z","end":"2026-05-09T13:45:04.405511Z","steps":["trace[1648640804] 'agreement among raft nodes before linearized reading' (duration: 770.669729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.406953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.634754Z","time spent":"772.191241ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-05-09T13:45:04.405679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.80916Z","time spent":"596.510596ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T13:45:04.406289Z","caller":"traceutil/trace.go:171","msg":"trace[1960419559] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26752; }","duration":"392.983944ms","start":"2026-05-09T13:45:04.013291Z","end":"2026-05-09T13:45:04.406275Z","steps":["trace[1960419559] 'agreement among raft nodes before linearized reading' (duration: 392.031087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.407062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:04.013284Z","time spent":"393.769435ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5103,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"info","ts":"2026-05-09T13:45:04.405748Z","caller":"traceutil/trace.go:171","msg":"trace[809045869] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:26752; }","duration":"293.991302ms","start":"2026-05-09T13:45:04.111746Z","end":"2026-05-09T13:45:04.405738Z","steps":["trace[809045869] 'agreement among raft nodes before linearized reading' (duration: 293.626672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.408476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:03.804609Z","time spent":"601.193174ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T13:45:04.681907Z","caller":"traceutil/trace.go:171","msg":"trace[2069593696] transaction","detail":"{read_only:false; response_revision:26753; number_of_response:1; }","duration":"269.826533ms","start":"2026-05-09T13:45:04.412038Z","end":"2026-05-09T13:45:04.681864Z","steps":["trace[2069593696] 'process raft request' (duration: 269.521865ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:04.68204Z","caller":"traceutil/trace.go:171","msg":"trace[1021521354] transaction","detail":"{read_only:false; response_revision:26754; number_of_response:1; }","duration":"268.388264ms","start":"2026-05-09T13:45:04.413617Z","end":"2026-05-09T13:45:04.682005Z","steps":["trace[1021521354] 'process raft request' (duration: 268.152837ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:04.682735Z","caller":"traceutil/trace.go:171","msg":"trace[489448915] transaction","detail":"{read_only:false; response_revision:26755; number_of_response:1; }","duration":"269.091652ms","start":"2026-05-09T13:45:04.413611Z","end":"2026-05-09T13:45:04.682703Z","steps":["trace[489448915] 'process raft request' (duration: 268.804365ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:04.682927Z","caller":"traceutil/trace.go:171","msg":"trace[433318149] transaction","detail":"{read_only:false; response_revision:26756; number_of_response:1; }","duration":"269.278668ms","start":"2026-05-09T13:45:04.41363Z","end":"2026-05-09T13:45:04.682909Z","steps":["trace[433318149] 'process raft request' (duration: 269.012961ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:04.683156Z","caller":"traceutil/trace.go:171","msg":"trace[456189548] linearizableReadLoop","detail":"{readStateIndex:27619; appliedIndex:27613; }","duration":"181.301467ms","start":"2026-05-09T13:45:04.501832Z","end":"2026-05-09T13:45:04.683133Z","steps":["trace[456189548] 'read index received' (duration: 145.942442ms)","trace[456189548] 'applied index is now lower than readState.Index' (duration: 35.355435ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:04.683273Z","caller":"traceutil/trace.go:171","msg":"trace[484028864] transaction","detail":"{read_only:false; response_revision:26757; number_of_response:1; }","duration":"269.497054ms","start":"2026-05-09T13:45:04.413725Z","end":"2026-05-09T13:45:04.683222Z","steps":["trace[484028864] 'process raft request' (duration: 269.117523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:04.68334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.492812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:45:04.683382Z","caller":"traceutil/trace.go:171","msg":"trace[234629424] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26757; }","duration":"181.563784ms","start":"2026-05-09T13:45:04.501804Z","end":"2026-05-09T13:45:04.683368Z","steps":["trace[234629424] 'agreement among raft nodes before linearized reading' (duration: 181.39672ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:09.554468Z","caller":"traceutil/trace.go:171","msg":"trace[1581866473] transaction","detail":"{read_only:false; response_revision:26795; number_of_response:1; }","duration":"159.919033ms","start":"2026-05-09T13:45:09.394512Z","end":"2026-05-09T13:45:09.554431Z","steps":["trace[1581866473] 'process raft request' (duration: 159.666927ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:10.898669Z","caller":"traceutil/trace.go:171","msg":"trace[894991947] transaction","detail":"{read_only:false; response_revision:26808; number_of_response:1; }","duration":"188.00471ms","start":"2026-05-09T13:45:10.710628Z","end":"2026-05-09T13:45:10.898633Z","steps":["trace[894991947] 'process raft request' (duration: 186.5155ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:10.898748Z","caller":"traceutil/trace.go:171","msg":"trace[200364287] linearizableReadLoop","detail":"{readStateIndex:27672; appliedIndex:27670; }","duration":"180.659069ms","start":"2026-05-09T13:45:10.718062Z","end":"2026-05-09T13:45:10.898721Z","steps":["trace[200364287] 'read index received' (duration: 179.087057ms)","trace[200364287] 'applied index is now lower than readState.Index' (duration: 1.568522ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:10.898867Z","caller":"traceutil/trace.go:171","msg":"trace[1142740991] transaction","detail":"{read_only:false; response_revision:26809; number_of_response:1; }","duration":"185.647945ms","start":"2026-05-09T13:45:10.713197Z","end":"2026-05-09T13:45:10.898845Z","steps":["trace[1142740991] 'process raft request' (duration: 185.383458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:10.899095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.817219ms","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-05-09T13:45:10.899094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.010748ms","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-05-09T13:45:10.899126Z","caller":"traceutil/trace.go:171","msg":"trace[455490894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26809; }","duration":"178.889281ms","start":"2026-05-09T13:45:10.720229Z","end":"2026-05-09T13:45:10.899118Z","steps":["trace[455490894] 'agreement among raft nodes before linearized reading' (duration: 178.771078ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:10.899135Z","caller":"traceutil/trace.go:171","msg":"trace[514332158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26809; }","duration":"181.094111ms","start":"2026-05-09T13:45:10.718029Z","end":"2026-05-09T13:45:10.899123Z","steps":["trace[514332158] 'agreement among raft nodes before linearized reading' (duration: 180.832594ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:31.182403Z","caller":"traceutil/trace.go:171","msg":"trace[861651486] transaction","detail":"{read_only:false; response_revision:26966; number_of_response:1; }","duration":"171.883311ms","start":"2026-05-09T13:45:31.010466Z","end":"2026-05-09T13:45:31.182349Z","steps":["trace[861651486] 'process raft request' (duration: 157.741275ms)","trace[861651486] 'compare' (duration: 13.962761ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:31.201971Z","caller":"traceutil/trace.go:171","msg":"trace[1733970016] transaction","detail":"{read_only:false; response_revision:26967; number_of_response:1; }","duration":"191.194386ms","start":"2026-05-09T13:45:31.010747Z","end":"2026-05-09T13:45:31.201941Z","steps":["trace[1733970016] 'process raft request' (duration: 190.875978ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:31.202061Z","caller":"traceutil/trace.go:171","msg":"trace[1658961143] transaction","detail":"{read_only:false; response_revision:26968; number_of_response:1; }","duration":"190.607101ms","start":"2026-05-09T13:45:31.011438Z","end":"2026-05-09T13:45:31.202045Z","steps":["trace[1658961143] 'process raft request' (duration: 190.310873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:31.202389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.423932ms","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-05-09T13:45:31.202436Z","caller":"traceutil/trace.go:171","msg":"trace[1555982156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26968; }","duration":"188.513544ms","start":"2026-05-09T13:45:31.013911Z","end":"2026-05-09T13:45:31.202424Z","steps":["trace[1555982156] 'agreement among raft nodes before linearized reading' (duration: 188.331479ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:31.20217Z","caller":"traceutil/trace.go:171","msg":"trace[452374265] linearizableReadLoop","detail":"{readStateIndex:27835; appliedIndex:27832; }","duration":"188.074822ms","start":"2026-05-09T13:45:31.013924Z","end":"2026-05-09T13:45:31.201998Z","steps":["trace[452374265] 'read index received' (duration: 154.183767ms)","trace[452374265] 'applied index is now lower than readState.Index' (duration: 33.890155ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:31.772465Z","caller":"traceutil/trace.go:171","msg":"trace[1818815838] transaction","detail":"{read_only:false; response_revision:26970; number_of_response:1; }","duration":"107.452931ms","start":"2026-05-09T13:45:31.664977Z","end":"2026-05-09T13:45:31.77243Z","steps":["trace[1818815838] 'process raft request' (duration: 107.253816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:32.323873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.272362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:45:32.323968Z","caller":"traceutil/trace.go:171","msg":"trace[2025112831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26971; }","duration":"186.445657ms","start":"2026-05-09T13:45:32.137501Z","end":"2026-05-09T13:45:32.323947Z","steps":["trace[2025112831] 'range keys from in-memory index tree' (duration: 186.080637ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:32.777043Z","caller":"traceutil/trace.go:171","msg":"trace[1860937198] transaction","detail":"{read_only:false; response_revision:26973; number_of_response:1; }","duration":"238.770775ms","start":"2026-05-09T13:45:32.538243Z","end":"2026-05-09T13:45:32.777014Z","steps":["trace[1860937198] 'process raft request' (duration: 238.481027ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:32.777045Z","caller":"traceutil/trace.go:171","msg":"trace[106893864] transaction","detail":"{read_only:false; response_revision:26974; number_of_response:1; }","duration":"217.516186ms","start":"2026-05-09T13:45:32.559497Z","end":"2026-05-09T13:45:32.777013Z","steps":["trace[106893864] 'process raft request' (duration: 217.424033ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:32.777443Z","caller":"traceutil/trace.go:171","msg":"trace[378804626] linearizableReadLoop","detail":"{readStateIndex:27844; appliedIndex:27839; }","duration":"164.399795ms","start":"2026-05-09T13:45:32.613025Z","end":"2026-05-09T13:45:32.777425Z","steps":["trace[378804626] 'read index received' (duration: 163.594803ms)","trace[378804626] 'applied index is now lower than readState.Index' (duration: 804.162µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:32.777495Z","caller":"traceutil/trace.go:171","msg":"trace[1122188688] transaction","detail":"{read_only:false; response_revision:26977; number_of_response:1; }","duration":"214.017889ms","start":"2026-05-09T13:45:32.56346Z","end":"2026-05-09T13:45:32.777478Z","steps":["trace[1122188688] 'process raft request' (duration: 213.914216ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:32.777471Z","caller":"traceutil/trace.go:171","msg":"trace[658245238] transaction","detail":"{read_only:false; response_revision:26976; number_of_response:1; }","duration":"213.985788ms","start":"2026-05-09T13:45:32.563451Z","end":"2026-05-09T13:45:32.777437Z","steps":["trace[658245238] 'process raft request' (duration: 213.764152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:32.777634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.627577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-05-09T13:45:32.777673Z","caller":"traceutil/trace.go:171","msg":"trace[1783245038] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26977; }","duration":"240.706028ms","start":"2026-05-09T13:45:32.536953Z","end":"2026-05-09T13:45:32.777659Z","steps":["trace[1783245038] 'agreement among raft nodes before linearized reading' (duration: 240.542734ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:32.777283Z","caller":"traceutil/trace.go:171","msg":"trace[72963702] transaction","detail":"{read_only:false; response_revision:26975; number_of_response:1; }","duration":"215.421798ms","start":"2026-05-09T13:45:32.561837Z","end":"2026-05-09T13:45:32.777259Z","steps":["trace[72963702] 'process raft request' (duration: 215.12795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:32.77774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.35423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-05-09T13:45:32.777835Z","caller":"traceutil/trace.go:171","msg":"trace[2016394823] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26977; }","duration":"217.523465ms","start":"2026-05-09T13:45:32.560302Z","end":"2026-05-09T13:45:32.777826Z","steps":["trace[2016394823] 'agreement among raft nodes before linearized reading' (duration: 217.278878ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:33.33778Z","caller":"traceutil/trace.go:171","msg":"trace[1252437056] linearizableReadLoop","detail":"{readStateIndex:27845; appliedIndex:27844; }","duration":"151.166105ms","start":"2026-05-09T13:45:33.186579Z","end":"2026-05-09T13:45:33.337745Z","steps":["trace[1252437056] 'read index received' (duration: 150.822346ms)","trace[1252437056] 'applied index is now lower than readState.Index' (duration: 342.609µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:33.338145Z","caller":"traceutil/trace.go:171","msg":"trace[199153644] transaction","detail":"{read_only:false; response_revision:26978; number_of_response:1; }","duration":"322.907661ms","start":"2026-05-09T13:45:33.015219Z","end":"2026-05-09T13:45:33.338127Z","steps":["trace[199153644] 'process raft request' (duration: 322.280724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:33.338291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:33.015195Z","time spent":"322.995753ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T13:45:33.33856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.978417ms","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-05-09T13:45:33.338609Z","caller":"traceutil/trace.go:171","msg":"trace[1010584432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26978; }","duration":"152.07258ms","start":"2026-05-09T13:45:33.186522Z","end":"2026-05-09T13:45:33.338594Z","steps":["trace[1010584432] 'agreement among raft nodes before linearized reading' (duration: 151.917425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:33.339029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.313108ms","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-05-09T13:45:33.339131Z","caller":"traceutil/trace.go:171","msg":"trace[1052933838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26978; }","duration":"122.460482ms","start":"2026-05-09T13:45:33.216645Z","end":"2026-05-09T13:45:33.339105Z","steps":["trace[1052933838] 'agreement among raft nodes before linearized reading' (duration: 122.168524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:33.339507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.708948ms","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-05-09T13:45:33.339565Z","caller":"traceutil/trace.go:171","msg":"trace[860648786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26978; }","duration":"122.813512ms","start":"2026-05-09T13:45:33.216739Z","end":"2026-05-09T13:45:33.339553Z","steps":["trace[860648786] 'agreement among raft nodes before linearized reading' (duration: 122.678188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:33.583474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.728754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T13:45:33.583722Z","caller":"traceutil/trace.go:171","msg":"trace[1238143463] transaction","detail":"{read_only:false; response_revision:26979; number_of_response:1; }","duration":"240.839012ms","start":"2026-05-09T13:45:33.342852Z","end":"2026-05-09T13:45:33.583691Z","steps":["trace[1238143463] 'process raft request' (duration: 82.754969ms)","trace[1238143463] 'compare' (duration: 157.309032ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:33.583901Z","caller":"traceutil/trace.go:171","msg":"trace[1785920500] transaction","detail":"{read_only:false; response_revision:26981; number_of_response:1; }","duration":"239.392152ms","start":"2026-05-09T13:45:33.344469Z","end":"2026-05-09T13:45:33.583862Z","steps":["trace[1785920500] 'process raft request' (duration: 239.292959ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:33.584057Z","caller":"traceutil/trace.go:171","msg":"trace[2107455111] linearizableReadLoop","detail":"{readStateIndex:27849; appliedIndex:27845; }","duration":"144.066641ms","start":"2026-05-09T13:45:33.439978Z","end":"2026-05-09T13:45:33.584045Z","steps":["trace[2107455111] 'read index received' (duration: 111.351598ms)","trace[2107455111] 'applied index is now lower than readState.Index' (duration: 32.713863ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:33.584086Z","caller":"traceutil/trace.go:171","msg":"trace[2071556009] transaction","detail":"{read_only:false; response_revision:26980; number_of_response:1; }","duration":"239.67051ms","start":"2026-05-09T13:45:33.344405Z","end":"2026-05-09T13:45:33.584076Z","steps":["trace[2071556009] 'process raft request' (duration: 239.230958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:33.584173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.165814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:45:33.584221Z","caller":"traceutil/trace.go:171","msg":"trace[504981818] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26982; }","duration":"144.259666ms","start":"2026-05-09T13:45:33.439946Z","end":"2026-05-09T13:45:33.584206Z","steps":["trace[504981818] 'agreement among raft nodes before linearized reading' (duration: 144.150513ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:33.584335Z","caller":"traceutil/trace.go:171","msg":"trace[1208712340] transaction","detail":"{read_only:false; response_revision:26982; number_of_response:1; }","duration":"238.992251ms","start":"2026-05-09T13:45:33.345311Z","end":"2026-05-09T13:45:33.584304Z","steps":["trace[1208712340] 'process raft request' (duration: 238.496678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:33.941185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.275557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T13:45:33.941268Z","caller":"traceutil/trace.go:171","msg":"trace[777999] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:26982; }","duration":"251.420451ms","start":"2026-05-09T13:45:33.689829Z","end":"2026-05-09T13:45:33.941249Z","steps":["trace[777999] 'count revisions from in-memory index tree' (duration: 251.157593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:33.941632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.224787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:45:33.941669Z","caller":"traceutil/trace.go:171","msg":"trace[1100271901] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26982; }","duration":"236.260757ms","start":"2026-05-09T13:45:33.705396Z","end":"2026-05-09T13:45:33.941656Z","steps":["trace[1100271901] 'count revisions from in-memory index tree' (duration: 236.175315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:33.942345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.245437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T13:45:33.942635Z","caller":"traceutil/trace.go:171","msg":"trace[1668007830] transaction","detail":"{read_only:false; response_revision:26983; number_of_response:1; }","duration":"165.421363ms","start":"2026-05-09T13:45:33.777196Z","end":"2026-05-09T13:45:33.942618Z","steps":["trace[1668007830] 'process raft request' (duration: 20.780146ms)","trace[1668007830] 'compare' (duration: 143.771834ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:34.161614Z","caller":"traceutil/trace.go:171","msg":"trace[1385463016] transaction","detail":"{read_only:false; response_revision:26984; number_of_response:1; }","duration":"120.707484ms","start":"2026-05-09T13:45:34.040866Z","end":"2026-05-09T13:45:34.161573Z","steps":["trace[1385463016] 'process raft request' (duration: 120.18155ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:38.33352Z","caller":"traceutil/trace.go:171","msg":"trace[929151276] transaction","detail":"{read_only:false; response_revision:27015; number_of_response:1; }","duration":"156.82626ms","start":"2026-05-09T13:45:38.176675Z","end":"2026-05-09T13:45:38.333501Z","steps":["trace[929151276] 'process raft request' (duration: 156.711815ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:38.884026Z","caller":"traceutil/trace.go:171","msg":"trace[22233501] linearizableReadLoop","detail":"{readStateIndex:27884; appliedIndex:27883; }","duration":"244.243054ms","start":"2026-05-09T13:45:38.639752Z","end":"2026-05-09T13:45:38.883995Z","steps":["trace[22233501] 'read index received' (duration: 244.111651ms)","trace[22233501] 'applied index is now lower than readState.Index' (duration: 130.083µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:38.884151Z","caller":"traceutil/trace.go:171","msg":"trace[321421076] transaction","detail":"{read_only:false; response_revision:27016; number_of_response:1; }","duration":"245.206231ms","start":"2026-05-09T13:45:38.638912Z","end":"2026-05-09T13:45:38.884119Z","steps":["trace[321421076] 'process raft request' (duration: 244.894072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:38.884281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.486882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:45:38.884382Z","caller":"traceutil/trace.go:171","msg":"trace[799067209] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:27016; }","duration":"244.624875ms","start":"2026-05-09T13:45:38.639734Z","end":"2026-05-09T13:45:38.884359Z","steps":["trace[799067209] 'agreement among raft nodes before linearized reading' (duration: 244.409929ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:39.174755Z","caller":"traceutil/trace.go:171","msg":"trace[1686848650] linearizableReadLoop","detail":"{readStateIndex:27889; appliedIndex:27884; }","duration":"290.610449ms","start":"2026-05-09T13:45:38.884112Z","end":"2026-05-09T13:45:39.174722Z","steps":["trace[1686848650] 'read index received' (duration: 290.325552ms)","trace[1686848650] 'applied index is now lower than readState.Index' (duration: 283.577µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:39.174905Z","caller":"traceutil/trace.go:171","msg":"trace[2066261292] transaction","detail":"{read_only:false; response_revision:27017; number_of_response:1; }","duration":"378.659492ms","start":"2026-05-09T13:45:38.796183Z","end":"2026-05-09T13:45:39.174842Z","steps":["trace[2066261292] 'process raft request' (duration: 378.149318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:39.175051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.271861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-05-09T13:45:39.175087Z","caller":"traceutil/trace.go:171","msg":"trace[1026574246] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27021; }","duration":"382.351343ms","start":"2026-05-09T13:45:38.792726Z","end":"2026-05-09T13:45:39.175077Z","steps":["trace[1026574246] 'agreement among raft nodes before linearized reading' (duration: 382.151478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:39.175122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:38.792722Z","time spent":"382.392305ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5103,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"warn","ts":"2026-05-09T13:45:39.175148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:38.79617Z","time spent":"378.854478ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:45:39.175068Z","caller":"traceutil/trace.go:171","msg":"trace[1513109885] transaction","detail":"{read_only:false; response_revision:27018; number_of_response:1; }","duration":"376.513883ms","start":"2026-05-09T13:45:38.798537Z","end":"2026-05-09T13:45:39.175051Z","steps":["trace[1513109885] 'process raft request' (duration: 376.03573ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:39.174965Z","caller":"traceutil/trace.go:171","msg":"trace[1931474526] transaction","detail":"{read_only:false; response_revision:27019; number_of_response:1; }","duration":"373.270645ms","start":"2026-05-09T13:45:38.801551Z","end":"2026-05-09T13:45:39.174821Z","steps":["trace[1931474526] 'process raft request' (duration: 373.06326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:39.175415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:38.798516Z","time spent":"376.77675ms","remote":"127.0.0.1:36502","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-05-09T13:45:39.17562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:38.801535Z","time spent":"373.83887ms","remote":"127.0.0.1:36502","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-05-09T13:45:39.175646Z","caller":"traceutil/trace.go:171","msg":"trace[324155877] transaction","detail":"{read_only:false; response_revision:27020; number_of_response:1; }","duration":"372.233677ms","start":"2026-05-09T13:45:38.802879Z","end":"2026-05-09T13:45:39.175113Z","steps":["trace[324155877] 'process raft request' (duration: 371.761494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:39.175721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:38.802866Z","time spent":"372.810543ms","remote":"127.0.0.1:36502","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-05-09T13:45:39.175634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.823546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-05-09T13:45:39.175837Z","caller":"traceutil/trace.go:171","msg":"trace[154050868] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27021; }","duration":"383.089684ms","start":"2026-05-09T13:45:38.79268Z","end":"2026-05-09T13:45:39.17577Z","steps":["trace[154050868] 'agreement among raft nodes before linearized reading' (duration: 382.402215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:39.175969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:38.79266Z","time spent":"383.248137ms","remote":"127.0.0.1:36472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5103,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "} {"level":"info","ts":"2026-05-09T13:45:39.17508Z","caller":"traceutil/trace.go:171","msg":"trace[2083035939] transaction","detail":"{read_only:false; response_revision:27021; number_of_response:1; }","duration":"371.879198ms","start":"2026-05-09T13:45:38.803013Z","end":"2026-05-09T13:45:39.174892Z","steps":["trace[2083035939] 'process raft request' (duration: 371.661142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:39.176383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:38.802976Z","time spent":"373.354878ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:45:39.314624Z","caller":"traceutil/trace.go:171","msg":"trace[1765842814] linearizableReadLoop","detail":"{readStateIndex:27890; appliedIndex:27889; }","duration":"139.749863ms","start":"2026-05-09T13:45:39.174841Z","end":"2026-05-09T13:45:39.31459Z","steps":["trace[1765842814] 'read index received' (duration: 80.63223ms)","trace[1765842814] 'applied index is now lower than readState.Index' (duration: 59.115473ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:45:39.314732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.135543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:45:39.314804Z","caller":"traceutil/trace.go:171","msg":"trace[593345610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27021; }","duration":"177.253146ms","start":"2026-05-09T13:45:39.137532Z","end":"2026-05-09T13:45:39.314786Z","steps":["trace[593345610] 'agreement among raft nodes before linearized reading' (duration: 177.089202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:39.314813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.622317ms","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-05-09T13:45:39.314857Z","caller":"traceutil/trace.go:171","msg":"trace[1919964604] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:27021; }","duration":"424.708429ms","start":"2026-05-09T13:45:38.890136Z","end":"2026-05-09T13:45:39.314844Z","steps":["trace[1919964604] 'agreement among raft nodes before linearized reading' (duration: 424.616507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:39.314836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.96345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-09T13:45:39.314885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:38.890119Z","time spent":"424.759141ms","remote":"127.0.0.1:36446","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":"info","ts":"2026-05-09T13:45:39.314913Z","caller":"traceutil/trace.go:171","msg":"trace[1022841437] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:27021; }","duration":"254.089173ms","start":"2026-05-09T13:45:39.060805Z","end":"2026-05-09T13:45:39.314894Z","steps":["trace[1022841437] 'agreement among raft nodes before linearized reading' (duration: 253.808526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:39.314922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.18285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T13:45:39.314988Z","caller":"traceutil/trace.go:171","msg":"trace[1666912916] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:27021; }","duration":"138.309063ms","start":"2026-05-09T13:45:39.176659Z","end":"2026-05-09T13:45:39.314968Z","steps":["trace[1666912916] 'agreement among raft nodes before linearized reading' (duration: 138.143189ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:39.52729Z","caller":"traceutil/trace.go:171","msg":"trace[2062356993] transaction","detail":"{read_only:false; response_revision:27023; number_of_response:1; }","duration":"121.494575ms","start":"2026-05-09T13:45:39.405768Z","end":"2026-05-09T13:45:39.527262Z","steps":["trace[2062356993] 'process raft request' (duration: 53.268923ms)","trace[2062356993] 'compare' (duration: 67.693037ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:39.740983Z","caller":"traceutil/trace.go:171","msg":"trace[1432221566] linearizableReadLoop","detail":"{readStateIndex:27893; appliedIndex:27892; }","duration":"129.088732ms","start":"2026-05-09T13:45:39.611829Z","end":"2026-05-09T13:45:39.740918Z","steps":["trace[1432221566] 'read index received' (duration: 57.335224ms)","trace[1432221566] 'applied index is now lower than readState.Index' (duration: 71.751308ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:39.741017Z","caller":"traceutil/trace.go:171","msg":"trace[399242239] transaction","detail":"{read_only:false; response_revision:27024; number_of_response:1; }","duration":"129.7589ms","start":"2026-05-09T13:45:39.611206Z","end":"2026-05-09T13:45:39.740965Z","steps":["trace[399242239] 'process raft request' (duration: 58.031463ms)","trace[399242239] 'compare' (duration: 71.43707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:45:39.74127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.424542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T13:45:39.741329Z","caller":"traceutil/trace.go:171","msg":"trace[1359405215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27024; }","duration":"129.501914ms","start":"2026-05-09T13:45:39.611812Z","end":"2026-05-09T13:45:39.741314Z","steps":["trace[1359405215] 'agreement among raft nodes before linearized reading' (duration: 129.287938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:39.741403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.116214ms","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-05-09T13:45:39.741412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.366083ms","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-05-09T13:45:39.74148Z","caller":"traceutil/trace.go:171","msg":"trace[973898522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27024; }","duration":"129.240987ms","start":"2026-05-09T13:45:39.612217Z","end":"2026-05-09T13:45:39.741458Z","steps":["trace[973898522] 'agreement among raft nodes before linearized reading' (duration: 128.968539ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:39.741515Z","caller":"traceutil/trace.go:171","msg":"trace[1591149816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27024; }","duration":"128.484596ms","start":"2026-05-09T13:45:39.613002Z","end":"2026-05-09T13:45:39.741487Z","steps":["trace[1591149816] 'agreement among raft nodes before linearized reading' (duration: 128.204099ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:40.087246Z","caller":"traceutil/trace.go:171","msg":"trace[1338410272] transaction","detail":"{read_only:false; response_revision:27025; number_of_response:1; }","duration":"341.015906ms","start":"2026-05-09T13:45:39.746196Z","end":"2026-05-09T13:45:40.087212Z","steps":["trace[1338410272] 'process raft request' (duration: 321.911924ms)","trace[1338410272] 'compare' (duration: 18.193576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:45:40.08741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:39.746176Z","time spent":"341.145319ms","remote":"127.0.0.1:36502","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-05-09T13:45:40.125018Z","caller":"traceutil/trace.go:171","msg":"trace[712797576] transaction","detail":"{read_only:false; response_revision:27028; number_of_response:1; }","duration":"159.03965ms","start":"2026-05-09T13:45:39.96595Z","end":"2026-05-09T13:45:40.124989Z","steps":["trace[712797576] 'process raft request' (duration: 158.963958ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:40.125051Z","caller":"traceutil/trace.go:171","msg":"trace[1820721275] transaction","detail":"{read_only:false; response_revision:27026; number_of_response:1; }","duration":"378.516659ms","start":"2026-05-09T13:45:39.746489Z","end":"2026-05-09T13:45:40.125006Z","steps":["trace[1820721275] 'process raft request' (duration: 378.262282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:40.125262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:39.746465Z","time spent":"378.679773ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:45:40.125275Z","caller":"traceutil/trace.go:171","msg":"trace[1931140861] transaction","detail":"{read_only:false; response_revision:27027; number_of_response:1; }","duration":"378.712084ms","start":"2026-05-09T13:45:39.74655Z","end":"2026-05-09T13:45:40.125263Z","steps":["trace[1931140861] 'process raft request' (duration: 378.321793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:40.125466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T13:45:39.746546Z","time spent":"378.874008ms","remote":"127.0.0.1:36502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T13:45:40.484681Z","caller":"traceutil/trace.go:171","msg":"trace[1480254154] transaction","detail":"{read_only:false; response_revision:27031; number_of_response:1; }","duration":"106.342862ms","start":"2026-05-09T13:45:40.37831Z","end":"2026-05-09T13:45:40.484653Z","steps":["trace[1480254154] 'process raft request' (duration: 106.136466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:40.673809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.94693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:40.67396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.236619ms","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-05-09T13:45:40.673974Z","caller":"traceutil/trace.go:171","msg":"trace[504029091] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27031; }","duration":"158.174016ms","start":"2026-05-09T13:45:40.51578Z","end":"2026-05-09T13:45:40.673954Z","steps":["trace[504029091] 'range keys from in-memory index tree' (duration: 157.826967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:40.673985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.608694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:40.674114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.175459ms","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-05-09T13:45:40.67416Z","caller":"traceutil/trace.go:171","msg":"trace[1625356842] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27031; }","duration":"175.239391ms","start":"2026-05-09T13:45:40.498907Z","end":"2026-05-09T13:45:40.674147Z","steps":["trace[1625356842] 'range keys from in-memory index tree' (duration: 175.058436ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:40.674123Z","caller":"traceutil/trace.go:171","msg":"trace[232028505] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27031; }","duration":"174.766368ms","start":"2026-05-09T13:45:40.499339Z","end":"2026-05-09T13:45:40.674105Z","steps":["trace[232028505] 'range keys from in-memory index tree' (duration: 174.483411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:40.674075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.064697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:40.674127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.634805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-05-09T13:45:40.674247Z","caller":"traceutil/trace.go:171","msg":"trace[673107521] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27031; }","duration":"175.240171ms","start":"2026-05-09T13:45:40.498983Z","end":"2026-05-09T13:45:40.674224Z","steps":["trace[673107521] 'range keys from in-memory index tree' (duration: 175.026616ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:40.674271Z","caller":"traceutil/trace.go:171","msg":"trace[2115870708] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27031; }","duration":"174.840961ms","start":"2026-05-09T13:45:40.4994Z","end":"2026-05-09T13:45:40.674241Z","steps":["trace[2115870708] 'range keys from in-memory index tree' (duration: 174.382258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:40.674314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.132138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:40.674422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.334954ms","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-05-09T13:45:40.674424Z","caller":"traceutil/trace.go:171","msg":"trace[703491872] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27031; }","duration":"175.248401ms","start":"2026-05-09T13:45:40.499158Z","end":"2026-05-09T13:45:40.674406Z","steps":["trace[703491872] 'range keys from in-memory index tree' (duration: 175.022626ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:40.674448Z","caller":"traceutil/trace.go:171","msg":"trace[1387641702] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27031; }","duration":"175.393625ms","start":"2026-05-09T13:45:40.499047Z","end":"2026-05-09T13:45:40.67444Z","steps":["trace[1387641702] 'range keys from in-memory index tree' (duration: 175.278102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:40.673809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.542852ms","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-05-09T13:45:40.674507Z","caller":"traceutil/trace.go:171","msg":"trace[1560116180] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27031; }","duration":"175.341734ms","start":"2026-05-09T13:45:40.49916Z","end":"2026-05-09T13:45:40.674502Z","steps":["trace[1560116180] 'range keys from in-memory index tree' (duration: 174.369908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:40.673921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.297881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T13:45:40.673879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.399181ms","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-05-09T13:45:40.674546Z","caller":"traceutil/trace.go:171","msg":"trace[1661072813] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27031; }","duration":"176.961049ms","start":"2026-05-09T13:45:40.497574Z","end":"2026-05-09T13:45:40.674535Z","steps":["trace[1661072813] 'range keys from in-memory index tree' (duration: 176.225719ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:40.674556Z","caller":"traceutil/trace.go:171","msg":"trace[2013792999] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27031; }","duration":"178.150101ms","start":"2026-05-09T13:45:40.4964Z","end":"2026-05-09T13:45:40.674551Z","steps":["trace[2013792999] 'range keys from in-memory index tree' (duration: 177.262177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:40.674591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.404476ms","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-05-09T13:45:40.674642Z","caller":"traceutil/trace.go:171","msg":"trace[1266720438] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27031; }","duration":"175.464087ms","start":"2026-05-09T13:45:40.499163Z","end":"2026-05-09T13:45:40.674628Z","steps":["trace[1266720438] 'range keys from in-memory index tree' (duration: 175.303173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:40.674546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.372654ms","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-05-09T13:45:40.674698Z","caller":"traceutil/trace.go:171","msg":"trace[1419912037] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27031; }","duration":"175.562ms","start":"2026-05-09T13:45:40.499127Z","end":"2026-05-09T13:45:40.674689Z","steps":["trace[1419912037] 'range keys from in-memory index tree' (duration: 175.255841ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:40.674022Z","caller":"traceutil/trace.go:171","msg":"trace[1429796891] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27031; }","duration":"176.318931ms","start":"2026-05-09T13:45:40.497684Z","end":"2026-05-09T13:45:40.674003Z","steps":["trace[1429796891] 'range keys from in-memory index tree' (duration: 176.173728ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:48.264971Z","caller":"traceutil/trace.go:171","msg":"trace[119089414] linearizableReadLoop","detail":"{readStateIndex:27954; appliedIndex:27953; }","duration":"106.805734ms","start":"2026-05-09T13:45:48.158116Z","end":"2026-05-09T13:45:48.264922Z","steps":["trace[119089414] 'read index received' (duration: 31.204691ms)","trace[119089414] 'applied index is now lower than readState.Index' (duration: 75.599013ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:48.266037Z","caller":"traceutil/trace.go:171","msg":"trace[1346609562] transaction","detail":"{read_only:false; response_revision:27084; number_of_response:1; }","duration":"110.148175ms","start":"2026-05-09T13:45:48.15587Z","end":"2026-05-09T13:45:48.266018Z","steps":["trace[1346609562] 'process raft request' (duration: 33.512035ms)","trace[1346609562] 'compare' (duration: 74.713478ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:45:48.266509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.356446ms","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-05-09T13:45:48.266553Z","caller":"traceutil/trace.go:171","msg":"trace[1322222714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27084; }","duration":"108.451478ms","start":"2026-05-09T13:45:48.158087Z","end":"2026-05-09T13:45:48.266538Z","steps":["trace[1322222714] 'agreement among raft nodes before linearized reading' (duration: 108.206682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:48.266946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.76359ms","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-05-09T13:45:48.266986Z","caller":"traceutil/trace.go:171","msg":"trace[1072025281] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27084; }","duration":"100.835782ms","start":"2026-05-09T13:45:48.166134Z","end":"2026-05-09T13:45:48.26697Z","steps":["trace[1072025281] 'agreement among raft nodes before linearized reading' (duration: 100.726969ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:48.533518Z","caller":"traceutil/trace.go:171","msg":"trace[280024056] transaction","detail":"{read_only:false; response_revision:27087; number_of_response:1; }","duration":"109.697313ms","start":"2026-05-09T13:45:48.423793Z","end":"2026-05-09T13:45:48.53349Z","steps":["trace[280024056] 'process raft request' (duration: 109.502858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:55.867813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.487561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:45:55.867921Z","caller":"traceutil/trace.go:171","msg":"trace[2002133014] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:27140; }","duration":"119.648295ms","start":"2026-05-09T13:45:55.748248Z","end":"2026-05-09T13:45:55.867896Z","steps":["trace[2002133014] 'count revisions from in-memory index tree' (duration: 119.351897ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:56.767821Z","caller":"traceutil/trace.go:171","msg":"trace[1890168005] transaction","detail":"{read_only:false; response_revision:27146; number_of_response:1; }","duration":"126.145662ms","start":"2026-05-09T13:45:56.641655Z","end":"2026-05-09T13:45:56.7678Z","steps":["trace[1890168005] 'process raft request' (duration: 125.977617ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:45:58.507748Z","caller":"traceutil/trace.go:171","msg":"trace[1137625678] linearizableReadLoop","detail":"{readStateIndex:28032; appliedIndex:28031; }","duration":"127.709735ms","start":"2026-05-09T13:45:58.380016Z","end":"2026-05-09T13:45:58.507725Z","steps":["trace[1137625678] 'read index received' (duration: 35.866408ms)","trace[1137625678] 'applied index is now lower than readState.Index' (duration: 91.842547ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:45:58.507855Z","caller":"traceutil/trace.go:171","msg":"trace[220699035] transaction","detail":"{read_only:false; response_revision:27160; number_of_response:1; }","duration":"132.056633ms","start":"2026-05-09T13:45:58.375768Z","end":"2026-05-09T13:45:58.507825Z","steps":["trace[220699035] 'process raft request' (duration: 40.103804ms)","trace[220699035] 'compare' (duration: 91.747444ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T13:45:58.507907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.87212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:45:58.507943Z","caller":"traceutil/trace.go:171","msg":"trace[692285672] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:27160; }","duration":"127.959932ms","start":"2026-05-09T13:45:58.379971Z","end":"2026-05-09T13:45:58.507931Z","steps":["trace[692285672] 'agreement among raft nodes before linearized reading' (duration: 127.86922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:45:58.5082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.393071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T13:45:58.508237Z","caller":"traceutil/trace.go:171","msg":"trace[488431517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27160; }","duration":"110.462633ms","start":"2026-05-09T13:45:58.397766Z","end":"2026-05-09T13:45:58.508229Z","steps":["trace[488431517] 'agreement among raft nodes before linearized reading' (duration: 110.3507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:46:05.459552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.743234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-05-09T13:46:05.459624Z","caller":"traceutil/trace.go:171","msg":"trace[709684873] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27222; }","duration":"201.857457ms","start":"2026-05-09T13:46:05.257748Z","end":"2026-05-09T13:46:05.459606Z","steps":["trace[709684873] 'range keys from in-memory index tree' (duration: 201.599511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:46:05.459719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.523835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5080"} {"level":"info","ts":"2026-05-09T13:46:05.459815Z","caller":"traceutil/trace.go:171","msg":"trace[2018057872] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27222; }","duration":"199.634578ms","start":"2026-05-09T13:46:05.260156Z","end":"2026-05-09T13:46:05.459791Z","steps":["trace[2018057872] 'range keys from in-memory index tree' (duration: 199.414833ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:46:05.460023Z","caller":"traceutil/trace.go:171","msg":"trace[1564004624] transaction","detail":"{read_only:false; response_revision:27223; number_of_response:1; }","duration":"176.797144ms","start":"2026-05-09T13:46:05.283211Z","end":"2026-05-09T13:46:05.460008Z","steps":["trace[1564004624] 'process raft request' (duration: 101.491889ms)","trace[1564004624] 'compare' (duration: 74.647608ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:46:05.493771Z","caller":"traceutil/trace.go:171","msg":"trace[536782964] transaction","detail":"{read_only:false; response_revision:27224; number_of_response:1; }","duration":"206.574257ms","start":"2026-05-09T13:46:05.287156Z","end":"2026-05-09T13:46:05.49373Z","steps":["trace[536782964] 'process raft request' (duration: 206.29819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:46:05.493908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.278841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T13:46:05.493947Z","caller":"traceutil/trace.go:171","msg":"trace[1731894709] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:27227; }","duration":"128.397604ms","start":"2026-05-09T13:46:05.365537Z","end":"2026-05-09T13:46:05.493935Z","steps":["trace[1731894709] 'agreement among raft nodes before linearized reading' (duration: 128.284921ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:46:05.493761Z","caller":"traceutil/trace.go:171","msg":"trace[223859773] linearizableReadLoop","detail":"{readStateIndex:28102; appliedIndex:28097; }","duration":"128.152867ms","start":"2026-05-09T13:46:05.365584Z","end":"2026-05-09T13:46:05.493737Z","steps":["trace[223859773] 'read index received' (duration: 19.145213ms)","trace[223859773] 'applied index is now lower than readState.Index' (duration: 109.006784ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T13:46:05.494161Z","caller":"traceutil/trace.go:171","msg":"trace[817277977] transaction","detail":"{read_only:false; response_revision:27225; number_of_response:1; }","duration":"206.69632ms","start":"2026-05-09T13:46:05.287456Z","end":"2026-05-09T13:46:05.494152Z","steps":["trace[817277977] 'process raft request' (duration: 206.177366ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:46:05.494303Z","caller":"traceutil/trace.go:171","msg":"trace[2059068441] transaction","detail":"{read_only:false; response_revision:27226; number_of_response:1; }","duration":"206.604977ms","start":"2026-05-09T13:46:05.287689Z","end":"2026-05-09T13:46:05.494294Z","steps":["trace[2059068441] 'process raft request' (duration: 205.978061ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:46:05.494476Z","caller":"traceutil/trace.go:171","msg":"trace[222113389] transaction","detail":"{read_only:false; response_revision:27227; number_of_response:1; }","duration":"206.478834ms","start":"2026-05-09T13:46:05.287842Z","end":"2026-05-09T13:46:05.494321Z","steps":["trace[222113389] 'process raft request' (duration: 205.858447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T13:48:40.607189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.326134ms","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-05-09T13:48:40.607322Z","caller":"traceutil/trace.go:171","msg":"trace[819311314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28693; }","duration":"105.521739ms","start":"2026-05-09T13:48:40.501777Z","end":"2026-05-09T13:48:40.607299Z","steps":["trace[819311314] 'range keys from in-memory index tree' (duration: 105.126748ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T13:48:40.991065Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26131} {"level":"info","ts":"2026-05-09T13:48:41.353627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26131,"took":"357.293938ms","hash":2485270684,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":33390592,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-09T13:48:41.353719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2485270684,"revision":26131,"compact-revision":23190} {"level":"info","ts":"2026-05-09T13:49:17.236704Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T13:49:17.241651Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-09T13:49:17.241747Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-09T13:53:40.99727Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28697} {"level":"info","ts":"2026-05-09T13:53:41.125716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28697,"took":"125.509734ms","hash":3519510377,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":31408128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T13:53:41.125815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3519510377,"revision":28697,"compact-revision":26131} {"level":"info","ts":"2026-05-09T13:58:41.004548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31055} {"level":"info","ts":"2026-05-09T13:58:41.135491Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31055,"took":"127.023528ms","hash":2532608259,"current-db-size-bytes":59043840,"current-db-size":"59 MB","current-db-size-in-use-bytes":30355456,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T13:58:41.135559Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2532608259,"revision":31055,"compact-revision":28697} {"level":"info","ts":"2026-05-09T14:03:41.009974Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33318} {"level":"info","ts":"2026-05-09T14:03:41.168611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33318,"took":"154.197435ms","hash":1961252494,"current-db-size-bytes":60084224,"current-db-size":"60 MB","current-db-size-in-use-bytes":31629312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T14:03:41.168683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1961252494,"revision":33318,"compact-revision":31055} {"level":"info","ts":"2026-05-09T14:08:41.019422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35675} {"level":"info","ts":"2026-05-09T14:08:41.187507Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35675,"took":"163.86439ms","hash":1775199631,"current-db-size-bytes":60542976,"current-db-size":"60 MB","current-db-size-in-use-bytes":32124928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T14:08:41.187577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1775199631,"revision":35675,"compact-revision":33318} {"level":"info","ts":"2026-05-09T14:10:28.566738Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T14:10:28.572731Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-09T14:10:28.572837Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-09T14:13:41.0254Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37937} {"level":"info","ts":"2026-05-09T14:13:41.145748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37937,"took":"115.238534ms","hash":1848119561,"current-db-size-bytes":62259200,"current-db-size":"62 MB","current-db-size-in-use-bytes":32346112,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T14:13:41.145834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1848119561,"revision":37937,"compact-revision":35675}