{"level":"warn","ts":"2026-05-19T17:03:26.588746Z","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-19T17:03:26.588908Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-19T17:03:26.589018Z","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-19T17:03:26.589056Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-19T17:03:26.589079Z","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-19T17:03:26.589938Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-05-19T17:03:26.590053Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-19T17:03:26.595231Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.894294ms"} {"level":"info","ts":"2026-05-19T17:03:26.603974Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-05-19T17:03:26.604086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T17:03:26.604118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-05-19T17:03:26.604136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T17:03:26.60415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-05-19T17:03:26.604195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-05-19T17:03:26.611006Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T17:03:26.614099Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T17:03:26.615467Z","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-19T17:03:26.618172Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T17:03:26.618536Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T17:03:26.618618Z","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-19T17:03:26.618759Z","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-19T17:03:26.618795Z","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-19T17:03:26.619505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-05-19T17:03:26.619653Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-19T17:03:26.621683Z","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-19T17:03:26.621796Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-19T17:03:26.621818Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-19T17:03:26.621996Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T17:03:26.622056Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T17:03:26.904806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T17:03:26.904893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T17:03:26.904919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-05-19T17:03:26.904934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-05-19T17:03:26.90494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-19T17:03:26.90495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-05-19T17:03:26.904959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-19T17:03:26.906257Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:03:26.90698Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T17:03:26.907009Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:03:26.907293Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T17:03:26.907666Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T17:03:26.907735Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T17:03:26.907672Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:03:26.907846Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:03:26.907885Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T17:03:26.911855Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-05-19T17:03:26.912688Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T17:03:46.823353Z","caller":"traceutil/trace.go:171","msg":"trace[1413317644] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"140.024794ms","start":"2026-05-19T17:03:46.68329Z","end":"2026-05-19T17:03:46.823314Z","steps":["trace[1413317644] 'process raft request' (duration: 139.754797ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:03:46.893831Z","caller":"traceutil/trace.go:171","msg":"trace[1438869049] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:424; }","duration":"146.506887ms","start":"2026-05-19T17:03:46.747288Z","end":"2026-05-19T17:03:46.893795Z","steps":["trace[1438869049] 'read index received' (duration: 75.737766ms)","trace[1438869049] 'applied index is now lower than readState.Index' (duration: 70.767621ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:03:46.893894Z","caller":"traceutil/trace.go:171","msg":"trace[1156527904] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"207.967436ms","start":"2026-05-19T17:03:46.685886Z","end":"2026-05-19T17:03:46.893853Z","steps":["trace[1156527904] 'process raft request' (duration: 202.831556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:03:46.894023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.719834ms","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-19T17:03:46.89412Z","caller":"traceutil/trace.go:171","msg":"trace[15217531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"146.872938ms","start":"2026-05-19T17:03:46.747223Z","end":"2026-05-19T17:03:46.894096Z","steps":["trace[15217531] 'agreement among raft nodes before linearized reading' (duration: 146.690443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:03:46.894107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.44867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T17:03:46.894166Z","caller":"traceutil/trace.go:171","msg":"trace[100028304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"104.582223ms","start":"2026-05-19T17:03:46.789568Z","end":"2026-05-19T17:03:46.89415Z","steps":["trace[100028304] 'agreement among raft nodes before linearized reading' (duration: 104.438639ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:04:06.119978Z","caller":"traceutil/trace.go:171","msg":"trace[305247085] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"152.619617ms","start":"2026-05-19T17:04:05.967318Z","end":"2026-05-19T17:04:06.119938Z","steps":["trace[305247085] 'process raft request' (duration: 152.339099ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:04:06.307567Z","caller":"traceutil/trace.go:171","msg":"trace[202736914] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"230.626531ms","start":"2026-05-19T17:04:06.076899Z","end":"2026-05-19T17:04:06.307526Z","steps":["trace[202736914] 'process raft request' (duration: 218.904262ms)","trace[202736914] 'compare' (duration: 11.280237ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:04:06.79482Z","caller":"traceutil/trace.go:171","msg":"trace[598634664] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"429.672966ms","start":"2026-05-19T17:04:06.365113Z","end":"2026-05-19T17:04:06.794786Z","steps":["trace[598634664] 'process raft request' (duration: 426.58416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:04:06.795459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:04:06.365083Z","time spent":"429.82411ms","remote":"127.0.0.1:44970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T17:04:08.18836Z","caller":"traceutil/trace.go:171","msg":"trace[2087147764] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"105.074279ms","start":"2026-05-19T17:04:08.083255Z","end":"2026-05-19T17:04:08.188329Z","steps":["trace[2087147764] 'process raft request' (duration: 80.380573ms)","trace[2087147764] 'compare' (duration: 24.539642ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:04:08.195028Z","caller":"traceutil/trace.go:171","msg":"trace[519731395] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"109.747123ms","start":"2026-05-19T17:04:08.085248Z","end":"2026-05-19T17:04:08.194995Z","steps":["trace[519731395] 'process raft request' (duration: 109.459846ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:04:08.195452Z","caller":"traceutil/trace.go:171","msg":"trace[518637608] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"109.751464ms","start":"2026-05-19T17:04:08.085676Z","end":"2026-05-19T17:04:08.195428Z","steps":["trace[518637608] 'process raft request' (duration: 109.153769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:04:22.233805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.868129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gzs2f\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-19T17:04:22.233952Z","caller":"traceutil/trace.go:171","msg":"trace[1185312929] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gzs2f; range_end:; response_count:1; response_revision:749; }","duration":"172.085124ms","start":"2026-05-19T17:04:22.061837Z","end":"2026-05-19T17:04:22.233922Z","steps":["trace[1185312929] 'range keys from in-memory index tree' (duration: 171.593502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:05:34.454573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.170099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9147a796-b4fb-4ca1-a110-f9bc15dac6a8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T17:05:34.454714Z","caller":"traceutil/trace.go:171","msg":"trace[1913156535] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9147a796-b4fb-4ca1-a110-f9bc15dac6a8; range_end:; response_count:1; response_revision:1808; }","duration":"199.346664ms","start":"2026-05-19T17:05:34.255346Z","end":"2026-05-19T17:05:34.454693Z","steps":["trace[1913156535] 'range keys from in-memory index tree' (duration: 199.033846ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:05:34.611918Z","caller":"traceutil/trace.go:171","msg":"trace[1004673194] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"283.401828ms","start":"2026-05-19T17:05:34.328488Z","end":"2026-05-19T17:05:34.61189Z","steps":["trace[1004673194] 'process raft request' (duration: 283.177543ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:05:34.647028Z","caller":"traceutil/trace.go:171","msg":"trace[2065602854] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1878; }","duration":"210.823729ms","start":"2026-05-19T17:05:34.436175Z","end":"2026-05-19T17:05:34.646999Z","steps":["trace[2065602854] 'read index received' (duration: 175.611405ms)","trace[2065602854] 'applied index is now lower than readState.Index' (duration: 35.211224ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:05:34.647472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.48949ms","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-19T17:05:34.647523Z","caller":"traceutil/trace.go:171","msg":"trace[1129869428] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1811; }","duration":"137.593792ms","start":"2026-05-19T17:05:34.509912Z","end":"2026-05-19T17:05:34.647506Z","steps":["trace[1129869428] 'agreement among raft nodes before linearized reading' (duration: 137.436598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:05:34.647508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.29011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9147a796-b4fb-4ca1-a110-f9bc15dac6a8\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T17:05:34.647522Z","caller":"traceutil/trace.go:171","msg":"trace[1550018615] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"294.082894ms","start":"2026-05-19T17:05:34.353399Z","end":"2026-05-19T17:05:34.647482Z","steps":["trace[1550018615] 'process raft request' (duration: 293.418267ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:05:34.64761Z","caller":"traceutil/trace.go:171","msg":"trace[603010573] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9147a796-b4fb-4ca1-a110-f9bc15dac6a8; range_end:; response_count:1; response_revision:1811; }","duration":"211.443073ms","start":"2026-05-19T17:05:34.436141Z","end":"2026-05-19T17:05:34.647584Z","steps":["trace[603010573] 'agreement among raft nodes before linearized reading' (duration: 211.122775ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:05:40.849607Z","caller":"traceutil/trace.go:171","msg":"trace[1033421638] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"100.956214ms","start":"2026-05-19T17:05:40.74863Z","end":"2026-05-19T17:05:40.849586Z","steps":["trace[1033421638] 'process raft request' (duration: 100.81386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:06:24.652695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.578555ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:06:24.652973Z","caller":"traceutil/trace.go:171","msg":"trace[373921708] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2131; }","duration":"209.863622ms","start":"2026-05-19T17:06:24.443081Z","end":"2026-05-19T17:06:24.652945Z","steps":["trace[373921708] 'range keys from in-memory index tree' (duration: 209.542634ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:11:25.76353Z","caller":"traceutil/trace.go:171","msg":"trace[104676937] transaction","detail":"{read_only:false; response_revision:4552; number_of_response:1; }","duration":"110.449783ms","start":"2026-05-19T17:11:25.653057Z","end":"2026-05-19T17:11:25.763507Z","steps":["trace[104676937] 'process raft request' (duration: 110.364761ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:11:25.763647Z","caller":"traceutil/trace.go:171","msg":"trace[1956864569] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"114.007968ms","start":"2026-05-19T17:11:25.649611Z","end":"2026-05-19T17:11:25.763619Z","steps":["trace[1956864569] 'process raft request' (duration: 73.979124ms)","trace[1956864569] 'compare' (duration: 39.369269ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:11:37.044191Z","caller":"traceutil/trace.go:171","msg":"trace[1242152894] linearizableReadLoop","detail":"{readStateIndex:5092; appliedIndex:5091; }","duration":"165.494387ms","start":"2026-05-19T17:11:36.87867Z","end":"2026-05-19T17:11:37.044164Z","steps":["trace[1242152894] 'read index received' (duration: 102.207152ms)","trace[1242152894] 'applied index is now lower than readState.Index' (duration: 63.280925ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:11:37.044217Z","caller":"traceutil/trace.go:171","msg":"trace[988732821] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4906; }","duration":"172.106626ms","start":"2026-05-19T17:11:36.872073Z","end":"2026-05-19T17:11:37.044179Z","steps":["trace[988732821] 'process raft request' (duration: 108.74944ms)","trace[988732821] 'compare' (duration: 63.239043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:11:37.044382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.697721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18b106b386888a3a\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-05-19T17:11:37.044438Z","caller":"traceutil/trace.go:171","msg":"trace[1910644514] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18b106b386888a3a; range_end:; response_count:1; response_revision:4906; }","duration":"165.805364ms","start":"2026-05-19T17:11:36.878621Z","end":"2026-05-19T17:11:37.044426Z","steps":["trace[1910644514] 'agreement among raft nodes before linearized reading' (duration: 165.616019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:37.044531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.870609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c6e36d62-dbf3-403a-a7ec-de9885d57f7f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-19T17:11:37.044592Z","caller":"traceutil/trace.go:171","msg":"trace[557299946] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c6e36d62-dbf3-403a-a7ec-de9885d57f7f; range_end:; response_count:1; response_revision:4906; }","duration":"113.002383ms","start":"2026-05-19T17:11:36.931574Z","end":"2026-05-19T17:11:37.044577Z","steps":["trace[557299946] 'agreement among raft nodes before linearized reading' (duration: 112.775907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:39.495448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.400382ms","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-19T17:11:39.495497Z","caller":"traceutil/trace.go:171","msg":"trace[562527515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4966; }","duration":"113.469044ms","start":"2026-05-19T17:11:39.382016Z","end":"2026-05-19T17:11:39.495485Z","steps":["trace[562527515] 'range keys from in-memory index tree' (duration: 113.30893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:39.495419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.989343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-19T17:11:39.495497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.026585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:1 size:799"} {"level":"info","ts":"2026-05-19T17:11:39.495599Z","caller":"traceutil/trace.go:171","msg":"trace[388989770] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:1; response_revision:4966; }","duration":"165.183569ms","start":"2026-05-19T17:11:39.330392Z","end":"2026-05-19T17:11:39.495575Z","steps":["trace[388989770] 'range keys from in-memory index tree' (duration: 164.83888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:39.495593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.517577ms","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-19T17:11:39.495646Z","caller":"traceutil/trace.go:171","msg":"trace[213870160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4966; }","duration":"145.205618ms","start":"2026-05-19T17:11:39.350355Z","end":"2026-05-19T17:11:39.49556Z","steps":["trace[213870160] 'range keys from in-memory index tree' (duration: 144.809409ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:11:39.495689Z","caller":"traceutil/trace.go:171","msg":"trace[1330996258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4966; }","duration":"116.65655ms","start":"2026-05-19T17:11:39.379009Z","end":"2026-05-19T17:11:39.495666Z","steps":["trace[1330996258] 'range keys from in-memory index tree' (duration: 116.314202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:11:39.496075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.726308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:26 size:789657"} {"level":"info","ts":"2026-05-19T17:11:39.496176Z","caller":"traceutil/trace.go:171","msg":"trace[874167958] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:26; response_revision:4966; }","duration":"137.888722ms","start":"2026-05-19T17:11:39.358263Z","end":"2026-05-19T17:11:39.496152Z","steps":["trace[874167958] 'range keys from in-memory index tree' (duration: 137.136114ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:11:54.866084Z","caller":"traceutil/trace.go:171","msg":"trace[1647538295] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"125.630445ms","start":"2026-05-19T17:11:54.74043Z","end":"2026-05-19T17:11:54.866061Z","steps":["trace[1647538295] 'process raft request' (duration: 125.359809ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:12:07.841291Z","caller":"traceutil/trace.go:171","msg":"trace[1925280243] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"102.962711ms","start":"2026-05-19T17:12:07.738289Z","end":"2026-05-19T17:12:07.841252Z","steps":["trace[1925280243] 'process raft request' (duration: 68.006449ms)","trace[1925280243] 'compare' (duration: 34.852319ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:13:27.085959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-05-19T17:13:27.156222Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"68.377725ms","hash":157766423,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":34107392,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T17:13:27.156295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":157766423,"revision":2991,"compact-revision":-1} {"level":"warn","ts":"2026-05-19T17:17:53.978349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.18467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T17:17:53.978555Z","caller":"traceutil/trace.go:171","msg":"trace[1075048367] linearizableReadLoop","detail":"{readStateIndex:8372; appliedIndex:8370; }","duration":"104.786786ms","start":"2026-05-19T17:17:53.873751Z","end":"2026-05-19T17:17:53.978537Z","steps":["trace[1075048367] 'read index received' (duration: 99.762715ms)","trace[1075048367] 'applied index is now lower than readState.Index' (duration: 5.022931ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:17:53.978622Z","caller":"traceutil/trace.go:171","msg":"trace[1095870652] transaction","detail":"{read_only:false; response_revision:8090; number_of_response:1; }","duration":"450.118057ms","start":"2026-05-19T17:17:53.528461Z","end":"2026-05-19T17:17:53.978579Z","steps":["trace[1095870652] 'process raft request' (duration: 273.359033ms)","trace[1095870652] 'compare' (duration: 175.891433ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:17:53.978694Z","caller":"traceutil/trace.go:171","msg":"trace[1751721226] transaction","detail":"{read_only:false; response_revision:8091; number_of_response:1; }","duration":"256.764674ms","start":"2026-05-19T17:17:53.721839Z","end":"2026-05-19T17:17:53.978603Z","steps":["trace[1751721226] 'process raft request' (duration: 256.631271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:17:53.978717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.929749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:17:53.978809Z","caller":"traceutil/trace.go:171","msg":"trace[550667418] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:8091; }","duration":"105.085073ms","start":"2026-05-19T17:17:53.873701Z","end":"2026-05-19T17:17:53.978786Z","steps":["trace[550667418] 'agreement among raft nodes before linearized reading' (duration: 104.903909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:17:53.978813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:17:53.528441Z","time spent":"450.28641ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:18:05.60039Z","caller":"traceutil/trace.go:171","msg":"trace[1988839864] transaction","detail":"{read_only:false; response_revision:8149; number_of_response:1; }","duration":"183.016258ms","start":"2026-05-19T17:18:05.417349Z","end":"2026-05-19T17:18:05.600366Z","steps":["trace[1988839864] 'process raft request' (duration: 182.864304ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:05.65493Z","caller":"traceutil/trace.go:171","msg":"trace[180469164] linearizableReadLoop","detail":"{readStateIndex:8434; appliedIndex:8432; }","duration":"108.132853ms","start":"2026-05-19T17:18:05.546769Z","end":"2026-05-19T17:18:05.654902Z","steps":["trace[180469164] 'read index received' (duration: 53.499603ms)","trace[180469164] 'applied index is now lower than readState.Index' (duration: 54.63176ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:18:05.655067Z","caller":"traceutil/trace.go:171","msg":"trace[721865811] transaction","detail":"{read_only:false; response_revision:8150; number_of_response:1; }","duration":"209.151605ms","start":"2026-05-19T17:18:05.44588Z","end":"2026-05-19T17:18:05.655031Z","steps":["trace[721865811] 'process raft request' (duration: 199.318339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:18:05.655119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.329388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:18:05.655164Z","caller":"traceutil/trace.go:171","msg":"trace[719546236] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:8150; }","duration":"108.43261ms","start":"2026-05-19T17:18:05.546721Z","end":"2026-05-19T17:18:05.655154Z","steps":["trace[719546236] 'agreement among raft nodes before linearized reading' (duration: 108.333948ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:06.360105Z","caller":"traceutil/trace.go:171","msg":"trace[1540642085] transaction","detail":"{read_only:false; response_revision:8154; number_of_response:1; }","duration":"100.588882ms","start":"2026-05-19T17:18:06.259488Z","end":"2026-05-19T17:18:06.360077Z","steps":["trace[1540642085] 'process raft request' (duration: 100.453629ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:07.763318Z","caller":"traceutil/trace.go:171","msg":"trace[1712919342] transaction","detail":"{read_only:false; response_revision:8159; number_of_response:1; }","duration":"124.269419ms","start":"2026-05-19T17:18:07.639028Z","end":"2026-05-19T17:18:07.763297Z","steps":["trace[1712919342] 'process raft request' (duration: 122.128548ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:07.763623Z","caller":"traceutil/trace.go:171","msg":"trace[107375537] transaction","detail":"{read_only:false; response_revision:8160; number_of_response:1; }","duration":"103.4539ms","start":"2026-05-19T17:18:07.660137Z","end":"2026-05-19T17:18:07.763591Z","steps":["trace[107375537] 'process raft request' (duration: 103.117972ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:27.093967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-05-19T17:18:27.186969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"91.36647ms","hash":2135992925,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":22990848,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-19T17:18:27.187051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2135992925,"revision":6103,"compact-revision":2991} {"level":"info","ts":"2026-05-19T17:18:35.229098Z","caller":"traceutil/trace.go:171","msg":"trace[388919088] linearizableReadLoop","detail":"{readStateIndex:8657; appliedIndex:8656; }","duration":"142.367533ms","start":"2026-05-19T17:18:35.086704Z","end":"2026-05-19T17:18:35.229071Z","steps":["trace[388919088] 'read index received' (duration: 142.22444ms)","trace[388919088] 'applied index is now lower than readState.Index' (duration: 141.513µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:18:35.229189Z","caller":"traceutil/trace.go:171","msg":"trace[1872546736] transaction","detail":"{read_only:false; response_revision:8364; number_of_response:1; }","duration":"349.465009ms","start":"2026-05-19T17:18:34.879661Z","end":"2026-05-19T17:18:35.229126Z","steps":["trace[1872546736] 'process raft request' (duration: 349.177312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:18:35.229383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:18:34.879633Z","time spent":"349.637282ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:18:35.229462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.729682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nttnt\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-05-19T17:18:35.229564Z","caller":"traceutil/trace.go:171","msg":"trace[319745236] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nttnt; range_end:; response_count:1; response_revision:8364; }","duration":"142.877626ms","start":"2026-05-19T17:18:35.086665Z","end":"2026-05-19T17:18:35.229543Z","steps":["trace[319745236] 'agreement among raft nodes before linearized reading' (duration: 142.567498ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:18:38.535864Z","caller":"traceutil/trace.go:171","msg":"trace[1856807103] transaction","detail":"{read_only:false; response_revision:8380; number_of_response:1; }","duration":"189.712038ms","start":"2026-05-19T17:18:38.346125Z","end":"2026-05-19T17:18:38.535837Z","steps":["trace[1856807103] 'process raft request' (duration: 113.04886ms)","trace[1856807103] 'compare' (duration: 76.280029ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:18:38.621072Z","caller":"traceutil/trace.go:171","msg":"trace[1596664649] transaction","detail":"{read_only:false; response_revision:8381; number_of_response:1; }","duration":"158.793967ms","start":"2026-05-19T17:18:38.462242Z","end":"2026-05-19T17:18:38.621036Z","steps":["trace[1596664649] 'process raft request' (duration: 158.579012ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:19:14.680066Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002379.wal"} {"level":"info","ts":"2026-05-19T17:21:06.450258Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T17:21:06.455854Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T17:21:06.45595Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-19T17:23:27.100075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8271} {"level":"info","ts":"2026-05-19T17:23:27.179928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8271,"took":"76.946289ms","hash":3301768522,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":26734592,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-19T17:23:27.180098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3301768522,"revision":8271,"compact-revision":6103} {"level":"info","ts":"2026-05-19T17:23:27.986354Z","caller":"traceutil/trace.go:171","msg":"trace[1718528187] transaction","detail":"{read_only:false; response_revision:10962; number_of_response:1; }","duration":"153.307875ms","start":"2026-05-19T17:23:27.833011Z","end":"2026-05-19T17:23:27.986319Z","steps":["trace[1718528187] 'process raft request' (duration: 152.970537ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:23:50.524432Z","caller":"traceutil/trace.go:171","msg":"trace[1555638957] linearizableReadLoop","detail":"{readStateIndex:11460; appliedIndex:11459; }","duration":"213.537702ms","start":"2026-05-19T17:23:50.310872Z","end":"2026-05-19T17:23:50.52441Z","steps":["trace[1555638957] 'read index received' (duration: 213.331567ms)","trace[1555638957] 'applied index is now lower than readState.Index' (duration: 205.235µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:23:50.524475Z","caller":"traceutil/trace.go:171","msg":"trace[808303475] transaction","detail":"{read_only:false; response_revision:11086; number_of_response:1; }","duration":"223.897601ms","start":"2026-05-19T17:23:50.300554Z","end":"2026-05-19T17:23:50.524452Z","steps":["trace[808303475] 'process raft request' (duration: 223.711396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:23:50.524696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.795868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-19T17:23:50.524832Z","caller":"traceutil/trace.go:171","msg":"trace[1854116981] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:11086; }","duration":"213.949371ms","start":"2026-05-19T17:23:50.310858Z","end":"2026-05-19T17:23:50.524807Z","steps":["trace[1854116981] 'agreement among raft nodes before linearized reading' (duration: 213.668095ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:28:27.106966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10956} {"level":"info","ts":"2026-05-19T17:28:27.185806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10956,"took":"76.350128ms","hash":2678283006,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":28438528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-19T17:28:27.185869Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2678283006,"revision":10956,"compact-revision":8271} {"level":"info","ts":"2026-05-19T17:29:42.337022Z","caller":"traceutil/trace.go:171","msg":"trace[1433756631] transaction","detail":"{read_only:false; response_revision:13484; number_of_response:1; }","duration":"100.843191ms","start":"2026-05-19T17:29:42.236138Z","end":"2026-05-19T17:29:42.336981Z","steps":["trace[1433756631] 'process raft request' (duration: 100.604775ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:30:01.679574Z","caller":"traceutil/trace.go:171","msg":"trace[1802411321] transaction","detail":"{read_only:false; response_revision:13591; number_of_response:1; }","duration":"133.875206ms","start":"2026-05-19T17:30:01.545671Z","end":"2026-05-19T17:30:01.679546Z","steps":["trace[1802411321] 'process raft request' (duration: 133.707392ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:30:18.41799Z","caller":"traceutil/trace.go:171","msg":"trace[1362794488] linearizableReadLoop","detail":"{readStateIndex:14160; appliedIndex:14159; }","duration":"134.430278ms","start":"2026-05-19T17:30:18.283538Z","end":"2026-05-19T17:30:18.417969Z","steps":["trace[1362794488] 'read index received' (duration: 134.221273ms)","trace[1362794488] 'applied index is now lower than readState.Index' (duration: 208.145µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:30:18.418126Z","caller":"traceutil/trace.go:171","msg":"trace[1043766905] transaction","detail":"{read_only:false; response_revision:13694; number_of_response:1; }","duration":"264.025164ms","start":"2026-05-19T17:30:18.154051Z","end":"2026-05-19T17:30:18.418076Z","steps":["trace[1043766905] 'process raft request' (duration: 263.771217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:30:18.418209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.639405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T17:30:18.41816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.184025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T17:30:18.418309Z","caller":"traceutil/trace.go:171","msg":"trace[1780504571] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:13694; }","duration":"134.798019ms","start":"2026-05-19T17:30:18.283493Z","end":"2026-05-19T17:30:18.418291Z","steps":["trace[1780504571] 'agreement among raft nodes before linearized reading' (duration: 134.607895ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:30:18.418315Z","caller":"traceutil/trace.go:171","msg":"trace[267545538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13694; }","duration":"105.38309ms","start":"2026-05-19T17:30:18.312917Z","end":"2026-05-19T17:30:18.4183Z","steps":["trace[267545538] 'agreement among raft nodes before linearized reading' (duration: 105.191326ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:30:39.337583Z","caller":"traceutil/trace.go:171","msg":"trace[967166209] transaction","detail":"{read_only:false; response_revision:13994; number_of_response:1; }","duration":"178.313798ms","start":"2026-05-19T17:30:39.159229Z","end":"2026-05-19T17:30:39.337543Z","steps":["trace[967166209] 'process raft request' (duration: 178.055361ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:30:39.345582Z","caller":"traceutil/trace.go:171","msg":"trace[671932434] linearizableReadLoop","detail":"{readStateIndex:14474; appliedIndex:14472; }","duration":"151.320387ms","start":"2026-05-19T17:30:39.19423Z","end":"2026-05-19T17:30:39.345551Z","steps":["trace[671932434] 'read index received' (duration: 143.222831ms)","trace[671932434] 'applied index is now lower than readState.Index' (duration: 8.095946ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:30:39.345758Z","caller":"traceutil/trace.go:171","msg":"trace[440937200] transaction","detail":"{read_only:false; response_revision:13995; number_of_response:1; }","duration":"185.04543ms","start":"2026-05-19T17:30:39.160675Z","end":"2026-05-19T17:30:39.34572Z","steps":["trace[440937200] 'process raft request' (duration: 184.713382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:30:39.34583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.597844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T17:30:39.345906Z","caller":"traceutil/trace.go:171","msg":"trace[1645693655] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:13995; }","duration":"151.745387ms","start":"2026-05-19T17:30:39.194139Z","end":"2026-05-19T17:30:39.345884Z","steps":["trace[1645693655] 'agreement among raft nodes before linearized reading' (duration: 151.562233ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:31:56.409667Z","caller":"traceutil/trace.go:171","msg":"trace[570408979] transaction","detail":"{read_only:false; response_revision:14759; number_of_response:1; }","duration":"117.821079ms","start":"2026-05-19T17:31:56.291818Z","end":"2026-05-19T17:31:56.409639Z","steps":["trace[570408979] 'process raft request' (duration: 117.641635ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:31:56.689515Z","caller":"traceutil/trace.go:171","msg":"trace[855279744] transaction","detail":"{read_only:false; response_revision:14760; number_of_response:1; }","duration":"390.900201ms","start":"2026-05-19T17:31:56.298589Z","end":"2026-05-19T17:31:56.689489Z","steps":["trace[855279744] 'process raft request' (duration: 390.713517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:31:56.689691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:31:56.298563Z","time spent":"391.033134ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:31:56.689697Z","caller":"traceutil/trace.go:171","msg":"trace[1671918691] linearizableReadLoop","detail":"{readStateIndex:15261; appliedIndex:15259; }","duration":"142.557467ms","start":"2026-05-19T17:31:56.547108Z","end":"2026-05-19T17:31:56.689665Z","steps":["trace[1671918691] 'read index received' (duration: 142.243649ms)","trace[1671918691] 'applied index is now lower than readState.Index' (duration: 311.498µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:31:56.689847Z","caller":"traceutil/trace.go:171","msg":"trace[539125787] transaction","detail":"{read_only:false; response_revision:14761; number_of_response:1; }","duration":"284.650961ms","start":"2026-05-19T17:31:56.405183Z","end":"2026-05-19T17:31:56.689834Z","steps":["trace[539125787] 'process raft request' (duration: 284.261041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:31:56.69024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.12188ms","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-19T17:31:56.690274Z","caller":"traceutil/trace.go:171","msg":"trace[1250187823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14761; }","duration":"143.198622ms","start":"2026-05-19T17:31:56.547064Z","end":"2026-05-19T17:31:56.690263Z","steps":["trace[1250187823] 'agreement among raft nodes before linearized reading' (duration: 143.091009ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:33:27.113352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12995} {"level":"info","ts":"2026-05-19T17:33:27.186833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12995,"took":"70.735285ms","hash":2646896475,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":31019008,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T17:33:27.186893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2646896475,"revision":12995,"compact-revision":10956} {"level":"info","ts":"2026-05-19T17:37:55.283827Z","caller":"traceutil/trace.go:171","msg":"trace[1438001681] transaction","detail":"{read_only:false; response_revision:17401; number_of_response:1; }","duration":"138.97243ms","start":"2026-05-19T17:37:55.144809Z","end":"2026-05-19T17:37:55.283781Z","steps":["trace[1438001681] 'process raft request' (duration: 126.857418ms)","trace[1438001681] 'compare' (duration: 11.950399ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:37:55.86697Z","caller":"traceutil/trace.go:171","msg":"trace[1082852358] transaction","detail":"{read_only:false; response_revision:17403; number_of_response:1; }","duration":"235.848334ms","start":"2026-05-19T17:37:55.631096Z","end":"2026-05-19T17:37:55.866945Z","steps":["trace[1082852358] 'process raft request' (duration: 235.66286ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:37:55.908456Z","caller":"traceutil/trace.go:171","msg":"trace[569658182] linearizableReadLoop","detail":"{readStateIndex:17991; appliedIndex:17989; }","duration":"208.634999ms","start":"2026-05-19T17:37:55.699741Z","end":"2026-05-19T17:37:55.908376Z","steps":["trace[569658182] 'read index received' (duration: 167.130958ms)","trace[569658182] 'applied index is now lower than readState.Index' (duration: 41.502471ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:37:55.908746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.993737ms","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-19T17:37:55.908826Z","caller":"traceutil/trace.go:171","msg":"trace[961682049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17403; }","duration":"209.12389ms","start":"2026-05-19T17:37:55.699683Z","end":"2026-05-19T17:37:55.908806Z","steps":["trace[961682049] 'agreement among raft nodes before linearized reading' (duration: 208.888925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:37:55.908755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.147349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-t4kd6\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-05-19T17:37:55.909111Z","caller":"traceutil/trace.go:171","msg":"trace[1255287720] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-t4kd6; range_end:; response_count:1; response_revision:17403; }","duration":"167.399745ms","start":"2026-05-19T17:37:55.741553Z","end":"2026-05-19T17:37:55.908953Z","steps":["trace[1255287720] 'agreement among raft nodes before linearized reading' (duration: 167.050046ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:38:27.123774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15348} {"level":"info","ts":"2026-05-19T17:38:27.218865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15348,"took":"92.007737ms","hash":355828243,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":32624640,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-19T17:38:27.21893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":355828243,"revision":15348,"compact-revision":12995} {"level":"info","ts":"2026-05-19T17:41:57.038003Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T17:41:57.043931Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T17:41:57.044087Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-19T17:43:27.136945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17742} {"level":"info","ts":"2026-05-19T17:43:27.234232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17742,"took":"93.819811ms","hash":3928866446,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":33558528,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T17:43:27.23429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3928866446,"revision":17742,"compact-revision":15348} {"level":"info","ts":"2026-05-19T17:43:44.515893Z","caller":"traceutil/trace.go:171","msg":"trace[1568911955] transaction","detail":"{read_only:false; response_revision:20105; number_of_response:1; }","duration":"203.69791ms","start":"2026-05-19T17:43:44.312169Z","end":"2026-05-19T17:43:44.515867Z","steps":["trace[1568911955] 'process raft request' (duration: 196.65295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:43:44.778031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.736877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:43:44.779385Z","caller":"traceutil/trace.go:171","msg":"trace[247924517] transaction","detail":"{read_only:false; response_revision:20106; number_of_response:1; }","duration":"418.598641ms","start":"2026-05-19T17:43:44.359659Z","end":"2026-05-19T17:43:44.778257Z","steps":["trace[247924517] 'process raft request' (duration: 272.510565ms)","trace[247924517] 'compare' (duration: 145.570443ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:43:44.779675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:43:44.359629Z","time spent":"419.867951ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:43:45.200161Z","caller":"traceutil/trace.go:171","msg":"trace[393544468] transaction","detail":"{read_only:false; response_revision:20108; number_of_response:1; }","duration":"108.430364ms","start":"2026-05-19T17:43:45.091705Z","end":"2026-05-19T17:43:45.200136Z","steps":["trace[393544468] 'process raft request' (duration: 108.26494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:43:45.493755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.270521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:43:45.493858Z","caller":"traceutil/trace.go:171","msg":"trace[14412064] linearizableReadLoop","detail":"{readStateIndex:20787; appliedIndex:20786; }","duration":"251.427035ms","start":"2026-05-19T17:43:45.242415Z","end":"2026-05-19T17:43:45.493842Z","steps":["trace[14412064] 'read index received' (duration: 99.99742ms)","trace[14412064] 'applied index is now lower than readState.Index' (duration: 151.428095ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:43:45.494099Z","caller":"traceutil/trace.go:171","msg":"trace[1189890895] transaction","detail":"{read_only:false; response_revision:20109; number_of_response:1; }","duration":"251.737882ms","start":"2026-05-19T17:43:45.242324Z","end":"2026-05-19T17:43:45.494061Z","steps":["trace[1189890895] 'process raft request' (duration: 100.066981ms)","trace[1189890895] 'compare' (duration: 150.888752ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:43:45.494181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.751092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:43:45.494242Z","caller":"traceutil/trace.go:171","msg":"trace[367467963] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:20109; }","duration":"251.858885ms","start":"2026-05-19T17:43:45.242369Z","end":"2026-05-19T17:43:45.494228Z","steps":["trace[367467963] 'agreement among raft nodes before linearized reading' (duration: 251.692471ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:44:46.926444Z","caller":"traceutil/trace.go:171","msg":"trace[1612791456] linearizableReadLoop","detail":"{readStateIndex:21406; appliedIndex:21405; }","duration":"108.927276ms","start":"2026-05-19T17:44:46.817492Z","end":"2026-05-19T17:44:46.926419Z","steps":["trace[1612791456] 'read index received' (duration: 108.740011ms)","trace[1612791456] 'applied index is now lower than readState.Index' (duration: 186.315µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:44:46.926668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.153561ms","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-19T17:44:46.926707Z","caller":"traceutil/trace.go:171","msg":"trace[1023210726] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:20702; }","duration":"109.247783ms","start":"2026-05-19T17:44:46.81745Z","end":"2026-05-19T17:44:46.926698Z","steps":["trace[1023210726] 'agreement among raft nodes before linearized reading' (duration: 109.076579ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:44:46.926979Z","caller":"traceutil/trace.go:171","msg":"trace[420782963] transaction","detail":"{read_only:false; response_revision:20702; number_of_response:1; }","duration":"201.493707ms","start":"2026-05-19T17:44:46.725467Z","end":"2026-05-19T17:44:46.926961Z","steps":["trace[420782963] 'process raft request' (duration: 200.77221ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:47:30.815943Z","caller":"traceutil/trace.go:171","msg":"trace[1543334667] transaction","detail":"{read_only:false; response_revision:22497; number_of_response:1; }","duration":"100.858105ms","start":"2026-05-19T17:47:30.715044Z","end":"2026-05-19T17:47:30.815902Z","steps":["trace[1543334667] 'process raft request' (duration: 100.560258ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:47:31.067632Z","caller":"traceutil/trace.go:171","msg":"trace[1409023393] transaction","detail":"{read_only:false; response_revision:22498; number_of_response:1; }","duration":"238.395908ms","start":"2026-05-19T17:47:30.829212Z","end":"2026-05-19T17:47:31.067608Z","steps":["trace[1409023393] 'process raft request' (duration: 238.237835ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:47:31.095842Z","caller":"traceutil/trace.go:171","msg":"trace[1465274369] transaction","detail":"{read_only:false; response_revision:22499; number_of_response:1; }","duration":"164.108218ms","start":"2026-05-19T17:47:30.931709Z","end":"2026-05-19T17:47:31.095818Z","steps":["trace[1465274369] 'process raft request' (duration: 163.939294ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:47:31.096054Z","caller":"traceutil/trace.go:171","msg":"trace[1290441419] linearizableReadLoop","detail":"{readStateIndex:23251; appliedIndex:23249; }","duration":"139.125452ms","start":"2026-05-19T17:47:30.956878Z","end":"2026-05-19T17:47:31.096003Z","steps":["trace[1290441419] 'read index received' (duration: 110.580551ms)","trace[1290441419] 'applied index is now lower than readState.Index' (duration: 28.543001ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:47:31.096293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.40412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:47:31.096334Z","caller":"traceutil/trace.go:171","msg":"trace[1850586397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22500; }","duration":"139.501862ms","start":"2026-05-19T17:47:30.956822Z","end":"2026-05-19T17:47:31.096324Z","steps":["trace[1850586397] 'agreement among raft nodes before linearized reading' (duration: 139.289167ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:47:31.212421Z","caller":"traceutil/trace.go:171","msg":"trace[90031924] linearizableReadLoop","detail":"{readStateIndex:23253; appliedIndex:23252; }","duration":"111.708347ms","start":"2026-05-19T17:47:31.100687Z","end":"2026-05-19T17:47:31.212396Z","steps":["trace[90031924] 'read index received' (duration: 92.8089ms)","trace[90031924] 'applied index is now lower than readState.Index' (duration: 18.898377ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:47:31.212552Z","caller":"traceutil/trace.go:171","msg":"trace[1797728533] transaction","detail":"{read_only:false; response_revision:22501; number_of_response:1; }","duration":"137.80743ms","start":"2026-05-19T17:47:31.074703Z","end":"2026-05-19T17:47:31.21251Z","steps":["trace[1797728533] 'process raft request' (duration: 118.860422ms)","trace[1797728533] 'compare' (duration: 18.729573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:47:31.212604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.706951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:47:31.212704Z","caller":"traceutil/trace.go:171","msg":"trace[35147583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22501; }","duration":"113.988312ms","start":"2026-05-19T17:47:31.098691Z","end":"2026-05-19T17:47:31.212679Z","steps":["trace[35147583] 'agreement among raft nodes before linearized reading' (duration: 113.821528ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:48:27.143564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20014} {"level":"info","ts":"2026-05-19T17:48:27.248023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20014,"took":"100.331312ms","hash":1479466626,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":41615360,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-19T17:48:27.248093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1479466626,"revision":20014,"compact-revision":17742} {"level":"warn","ts":"2026-05-19T17:50:40.134038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.944019ms","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-19T17:50:40.134107Z","caller":"traceutil/trace.go:171","msg":"trace[200718430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24489; }","duration":"106.05311ms","start":"2026-05-19T17:50:40.02804Z","end":"2026-05-19T17:50:40.134093Z","steps":["trace[200718430] 'range keys from in-memory index tree' (duration: 105.786565ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:40.549195Z","caller":"traceutil/trace.go:171","msg":"trace[373627665] transaction","detail":"{read_only:false; response_revision:24490; number_of_response:1; }","duration":"411.107774ms","start":"2026-05-19T17:50:40.138064Z","end":"2026-05-19T17:50:40.549172Z","steps":["trace[373627665] 'process raft request' (duration: 410.995292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:40.549316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:40.138042Z","time spent":"411.214386ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:40.554806Z","caller":"traceutil/trace.go:171","msg":"trace[275095182] transaction","detail":"{read_only:false; response_revision:24491; number_of_response:1; }","duration":"416.130276ms","start":"2026-05-19T17:50:40.138654Z","end":"2026-05-19T17:50:40.554784Z","steps":["trace[275095182] 'process raft request' (duration: 415.970683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:40.554936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:40.138637Z","time spent":"416.234458ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:40.555025Z","caller":"traceutil/trace.go:171","msg":"trace[1149368403] transaction","detail":"{read_only:false; response_revision:24492; number_of_response:1; }","duration":"408.686116ms","start":"2026-05-19T17:50:40.14632Z","end":"2026-05-19T17:50:40.555006Z","steps":["trace[1149368403] 'process raft request' (duration: 408.425599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:40.555142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:40.146303Z","time spent":"408.785619ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:40.555125Z","caller":"traceutil/trace.go:171","msg":"trace[734915432] linearizableReadLoop","detail":"{readStateIndex:25294; appliedIndex:25292; }","duration":"400.423336ms","start":"2026-05-19T17:50:40.154676Z","end":"2026-05-19T17:50:40.5551Z","steps":["trace[734915432] 'read index received' (duration: 394.489521ms)","trace[734915432] 'applied index is now lower than readState.Index' (duration: 5.932645ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:40.555227Z","caller":"traceutil/trace.go:171","msg":"trace[1047240314] transaction","detail":"{read_only:false; response_revision:24493; number_of_response:1; }","duration":"263.262052ms","start":"2026-05-19T17:50:40.291957Z","end":"2026-05-19T17:50:40.555219Z","steps":["trace[1047240314] 'process raft request' (duration: 263.016955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:40.555468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.797855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-05-19T17:50:40.555497Z","caller":"traceutil/trace.go:171","msg":"trace[1442718485] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24493; }","duration":"400.858056ms","start":"2026-05-19T17:50:40.154633Z","end":"2026-05-19T17:50:40.555491Z","steps":["trace[1442718485] 'agreement among raft nodes before linearized reading' (duration: 400.666512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:40.555515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:40.15462Z","time spent":"400.891017ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-19T17:50:40.555548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.649616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-19T17:50:40.555634Z","caller":"traceutil/trace.go:171","msg":"trace[2052976923] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:24493; }","duration":"132.762589ms","start":"2026-05-19T17:50:40.422833Z","end":"2026-05-19T17:50:40.555596Z","steps":["trace[2052976923] 'agreement among raft nodes before linearized reading' (duration: 132.482162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:44.945907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.545207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:50:44.946016Z","caller":"traceutil/trace.go:171","msg":"trace[1815672659] linearizableReadLoop","detail":"{readStateIndex:25325; appliedIndex:25324; }","duration":"299.55156ms","start":"2026-05-19T17:50:44.646447Z","end":"2026-05-19T17:50:44.945999Z","steps":["trace[1815672659] 'read index received' (duration: 99.83954ms)","trace[1815672659] 'applied index is now lower than readState.Index' (duration: 199.71055ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:44.946066Z","caller":"traceutil/trace.go:171","msg":"trace[1497161922] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"359.167045ms","start":"2026-05-19T17:50:44.586857Z","end":"2026-05-19T17:50:44.946024Z","steps":["trace[1497161922] 'process raft request' (duration: 159.401363ms)","trace[1497161922] 'compare' (duration: 199.344592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:44.946181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.499361ms","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-19T17:50:44.946258Z","caller":"traceutil/trace.go:171","msg":"trace[328584945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24522; }","duration":"330.610583ms","start":"2026-05-19T17:50:44.615629Z","end":"2026-05-19T17:50:44.94624Z","steps":["trace[328584945] 'agreement among raft nodes before linearized reading' (duration: 330.432349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:44.9463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:44.615587Z","time spent":"330.703995ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:50:44.946471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.33595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T17:50:44.946571Z","caller":"traceutil/trace.go:171","msg":"trace[1120573223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24522; }","duration":"366.481954ms","start":"2026-05-19T17:50:44.580067Z","end":"2026-05-19T17:50:44.946549Z","steps":["trace[1120573223] 'agreement among raft nodes before linearized reading' (duration: 366.011082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:44.94662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:44.580057Z","time spent":"366.553905ms","remote":"127.0.0.1:44746","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-19T17:50:44.946881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:44.586848Z","time spent":"359.951475ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:45.040634Z","caller":"traceutil/trace.go:171","msg":"trace[1432175794] transaction","detail":"{read_only:false; response_revision:24524; number_of_response:1; }","duration":"370.328717ms","start":"2026-05-19T17:50:44.670291Z","end":"2026-05-19T17:50:45.04062Z","steps":["trace[1432175794] 'process raft request' (duration: 370.296056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:45.040735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:44.670269Z","time spent":"370.414159ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:45.040734Z","caller":"traceutil/trace.go:171","msg":"trace[1859237037] transaction","detail":"{read_only:false; response_revision:24523; number_of_response:1; }","duration":"388.774633ms","start":"2026-05-19T17:50:44.65193Z","end":"2026-05-19T17:50:45.040705Z","steps":["trace[1859237037] 'process raft request' (duration: 388.565698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:45.040913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:44.65191Z","time spent":"388.891176ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:45.132995Z","caller":"traceutil/trace.go:171","msg":"trace[1735721999] transaction","detail":"{read_only:false; response_revision:24526; number_of_response:1; }","duration":"179.951983ms","start":"2026-05-19T17:50:44.953018Z","end":"2026-05-19T17:50:45.13297Z","steps":["trace[1735721999] 'process raft request' (duration: 179.81787ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:45.133187Z","caller":"traceutil/trace.go:171","msg":"trace[29926121] transaction","detail":"{read_only:false; response_revision:24525; number_of_response:1; }","duration":"249.701583ms","start":"2026-05-19T17:50:44.88347Z","end":"2026-05-19T17:50:45.133171Z","steps":["trace[29926121] 'process raft request' (duration: 227.556776ms)","trace[29926121] 'compare' (duration: 21.429339ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:45.133698Z","caller":"traceutil/trace.go:171","msg":"trace[83486754] transaction","detail":"{read_only:false; response_revision:24527; number_of_response:1; }","duration":"180.544467ms","start":"2026-05-19T17:50:44.953087Z","end":"2026-05-19T17:50:45.133632Z","steps":["trace[83486754] 'process raft request' (duration: 179.83097ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:45.133884Z","caller":"traceutil/trace.go:171","msg":"trace[959489540] linearizableReadLoop","detail":"{readStateIndex:25328; appliedIndex:25325; }","duration":"187.827832ms","start":"2026-05-19T17:50:44.946044Z","end":"2026-05-19T17:50:45.133871Z","steps":["trace[959489540] 'read index received' (duration: 94.47493ms)","trace[959489540] 'applied index is now lower than readState.Index' (duration: 93.351912ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:45.133998Z","caller":"traceutil/trace.go:171","msg":"trace[432722648] transaction","detail":"{read_only:false; response_revision:24528; number_of_response:1; }","duration":"119.083856ms","start":"2026-05-19T17:50:45.014902Z","end":"2026-05-19T17:50:45.133986Z","steps":["trace[432722648] 'process raft request' (duration: 118.578534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:45.13418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.566124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T17:50:45.134214Z","caller":"traceutil/trace.go:171","msg":"trace[190081511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24528; }","duration":"243.649075ms","start":"2026-05-19T17:50:44.890556Z","end":"2026-05-19T17:50:45.134205Z","steps":["trace[190081511] 'agreement among raft nodes before linearized reading' (duration: 243.519232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:45.396568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.67278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:50:45.397046Z","caller":"traceutil/trace.go:171","msg":"trace[475485768] linearizableReadLoop","detail":"{readStateIndex:25333; appliedIndex:25332; }","duration":"122.497389ms","start":"2026-05-19T17:50:45.274534Z","end":"2026-05-19T17:50:45.397032Z","steps":["trace[475485768] 'read index received' (duration: 316.637µs)","trace[475485768] 'applied index is now lower than readState.Index' (duration: 122.179562ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:45.397157Z","caller":"traceutil/trace.go:171","msg":"trace[127247984] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"259.002778ms","start":"2026-05-19T17:50:45.138121Z","end":"2026-05-19T17:50:45.397124Z","steps":["trace[127247984] 'process raft request' (duration: 136.714174ms)","trace[127247984] 'compare' (duration: 121.461445ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:45.397372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.840168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:50:45.397642Z","caller":"traceutil/trace.go:171","msg":"trace[343085883] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24530; }","duration":"123.120454ms","start":"2026-05-19T17:50:45.27449Z","end":"2026-05-19T17:50:45.397611Z","steps":["trace[343085883] 'agreement among raft nodes before linearized reading' (duration: 122.836107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:45.712206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.363718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:50:45.712406Z","caller":"traceutil/trace.go:171","msg":"trace[1606302870] transaction","detail":"{read_only:false; response_revision:24532; number_of_response:1; }","duration":"185.030825ms","start":"2026-05-19T17:50:45.527355Z","end":"2026-05-19T17:50:45.712386Z","steps":["trace[1606302870] 'process raft request' (duration: 184.960163ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:45.712777Z","caller":"traceutil/trace.go:171","msg":"trace[599640475] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"249.309093ms","start":"2026-05-19T17:50:45.463294Z","end":"2026-05-19T17:50:45.712603Z","steps":["trace[599640475] 'process raft request' (duration: 109.480253ms)","trace[599640475] 'compare' (duration: 139.04727ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:45.85594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.888795ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T17:50:45.856036Z","caller":"traceutil/trace.go:171","msg":"trace[978671993] linearizableReadLoop","detail":"{readStateIndex:25336; appliedIndex:25335; }","duration":"158.102473ms","start":"2026-05-19T17:50:45.697918Z","end":"2026-05-19T17:50:45.856021Z","steps":["trace[978671993] 'read index received' (duration: 17.085714ms)","trace[978671993] 'applied index is now lower than readState.Index' (duration: 141.015379ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:45.857116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.156648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-05-19T17:50:45.857213Z","caller":"traceutil/trace.go:171","msg":"trace[378245160] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24532; }","duration":"159.306552ms","start":"2026-05-19T17:50:45.697886Z","end":"2026-05-19T17:50:45.857192Z","steps":["trace[378245160] 'agreement among raft nodes before linearized reading' (duration: 158.991654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:45.857145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.380443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:50:45.857464Z","caller":"traceutil/trace.go:171","msg":"trace[891784065] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24532; }","duration":"107.750252ms","start":"2026-05-19T17:50:45.749695Z","end":"2026-05-19T17:50:45.857445Z","steps":["trace[891784065] 'agreement among raft nodes before linearized reading' (duration: 107.376563ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:46.182068Z","caller":"traceutil/trace.go:171","msg":"trace[586431674] linearizableReadLoop","detail":"{readStateIndex:25338; appliedIndex:25337; }","duration":"156.446532ms","start":"2026-05-19T17:50:46.025604Z","end":"2026-05-19T17:50:46.182051Z","steps":["trace[586431674] 'read index received' (duration: 156.283468ms)","trace[586431674] 'applied index is now lower than readState.Index' (duration: 162.364µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:46.1823Z","caller":"traceutil/trace.go:171","msg":"trace[857050379] transaction","detail":"{read_only:false; response_revision:24534; number_of_response:1; }","duration":"158.814499ms","start":"2026-05-19T17:50:46.023478Z","end":"2026-05-19T17:50:46.182292Z","steps":["trace[857050379] 'process raft request' (duration: 158.45748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:46.182434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.37181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:50:46.18249Z","caller":"traceutil/trace.go:171","msg":"trace[1463591588] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:24534; }","duration":"135.488093ms","start":"2026-05-19T17:50:46.046988Z","end":"2026-05-19T17:50:46.182476Z","steps":["trace[1463591588] 'agreement among raft nodes before linearized reading' (duration: 135.381651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:46.182561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.940904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-05-19T17:50:46.182588Z","caller":"traceutil/trace.go:171","msg":"trace[800560764] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24534; }","duration":"156.990975ms","start":"2026-05-19T17:50:46.02559Z","end":"2026-05-19T17:50:46.182581Z","steps":["trace[800560764] 'agreement among raft nodes before linearized reading' (duration: 156.887352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:46.956724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.459696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:50:46.956832Z","caller":"traceutil/trace.go:171","msg":"trace[579094138] linearizableReadLoop","detail":"{readStateIndex:25340; appliedIndex:25339; }","duration":"290.00231ms","start":"2026-05-19T17:50:46.666814Z","end":"2026-05-19T17:50:46.956817Z","steps":["trace[579094138] 'read index received' (duration: 123.310479ms)","trace[579094138] 'applied index is now lower than readState.Index' (duration: 166.690031ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:46.956865Z","caller":"traceutil/trace.go:171","msg":"trace[1051577079] transaction","detail":"{read_only:false; response_revision:24536; number_of_response:1; }","duration":"296.965568ms","start":"2026-05-19T17:50:46.659867Z","end":"2026-05-19T17:50:46.956832Z","steps":["trace[1051577079] 'process raft request' (duration: 130.229096ms)","trace[1051577079] 'compare' (duration: 166.19621ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:46.957016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.198415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-4zkhl.18b10837fcf2f7bb\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-05-19T17:50:46.957055Z","caller":"traceutil/trace.go:171","msg":"trace[97072059] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-4zkhl.18b10837fcf2f7bb; range_end:; response_count:1; response_revision:24536; }","duration":"290.264856ms","start":"2026-05-19T17:50:46.666778Z","end":"2026-05-19T17:50:46.957043Z","steps":["trace[97072059] 'agreement among raft nodes before linearized reading' (duration: 290.122453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:46.957265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.891554ms","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-19T17:50:46.957314Z","caller":"traceutil/trace.go:171","msg":"trace[669388745] 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:24536; }","duration":"253.987207ms","start":"2026-05-19T17:50:46.703313Z","end":"2026-05-19T17:50:46.9573Z","steps":["trace[669388745] 'agreement among raft nodes before linearized reading' (duration: 253.898004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:46.957559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.599296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-19T17:50:46.957864Z","caller":"traceutil/trace.go:171","msg":"trace[1224881425] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24536; }","duration":"215.947455ms","start":"2026-05-19T17:50:46.741905Z","end":"2026-05-19T17:50:46.957853Z","steps":["trace[1224881425] 'agreement among raft nodes before linearized reading' (duration: 215.440783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.212975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.586338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:50:47.213094Z","caller":"traceutil/trace.go:171","msg":"trace[1363015473] transaction","detail":"{read_only:false; response_revision:24537; number_of_response:1; }","duration":"254.777915ms","start":"2026-05-19T17:50:46.958297Z","end":"2026-05-19T17:50:47.213075Z","steps":["trace[1363015473] 'process raft request' (duration: 120.997463ms)","trace[1363015473] 'compare' (duration: 133.24778ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:47.376788Z","caller":"traceutil/trace.go:171","msg":"trace[1331347952] transaction","detail":"{read_only:false; response_revision:24542; number_of_response:1; }","duration":"138.375635ms","start":"2026-05-19T17:50:47.238389Z","end":"2026-05-19T17:50:47.376764Z","steps":["trace[1331347952] 'process raft request' (duration: 138.335884ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:47.377124Z","caller":"traceutil/trace.go:171","msg":"trace[448928739] transaction","detail":"{read_only:false; response_revision:24540; number_of_response:1; }","duration":"237.696991ms","start":"2026-05-19T17:50:47.139418Z","end":"2026-05-19T17:50:47.377115Z","steps":["trace[448928739] 'process raft request' (duration: 237.23756ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:47.377287Z","caller":"traceutil/trace.go:171","msg":"trace[1593459032] linearizableReadLoop","detail":"{readStateIndex:25342; appliedIndex:25340; }","duration":"415.464251ms","start":"2026-05-19T17:50:46.961755Z","end":"2026-05-19T17:50:47.377219Z","steps":["trace[1593459032] 'read index received' (duration: 117.553699ms)","trace[1593459032] 'applied index is now lower than readState.Index' (duration: 297.737538ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:47.37737Z","caller":"traceutil/trace.go:171","msg":"trace[1433028325] transaction","detail":"{read_only:false; response_revision:24538; number_of_response:1; }","duration":"416.852514ms","start":"2026-05-19T17:50:46.960505Z","end":"2026-05-19T17:50:47.377357Z","steps":["trace[1433028325] 'process raft request' (duration: 373.479202ms)","trace[1433028325] 'compare' (duration: 42.481211ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:47.377393Z","caller":"traceutil/trace.go:171","msg":"trace[1435847988] transaction","detail":"{read_only:false; response_revision:24539; number_of_response:1; }","duration":"329.070857ms","start":"2026-05-19T17:50:47.048283Z","end":"2026-05-19T17:50:47.377354Z","steps":["trace[1435847988] 'process raft request' (duration: 328.309899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.377464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:46.96049Z","time spent":"416.903585ms","remote":"127.0.0.1:44544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":931,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:47.377569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.753458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:50:47.377807Z","caller":"traceutil/trace.go:171","msg":"trace[1490615549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24542; }","duration":"416.047875ms","start":"2026-05-19T17:50:46.961749Z","end":"2026-05-19T17:50:47.377797Z","steps":["trace[1490615549] 'agreement among raft nodes before linearized reading' (duration: 415.675446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.377816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.048254Z","time spent":"329.22777ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:47.377879Z","caller":"traceutil/trace.go:171","msg":"trace[1061533941] transaction","detail":"{read_only:false; response_revision:24541; number_of_response:1; }","duration":"237.152479ms","start":"2026-05-19T17:50:47.140069Z","end":"2026-05-19T17:50:47.377222Z","steps":["trace[1061533941] 'process raft request' (duration: 236.619996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.377926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:46.961695Z","time spent":"416.22167ms","remote":"127.0.0.1:44488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T17:50:47.667415Z","caller":"traceutil/trace.go:171","msg":"trace[1590642996] linearizableReadLoop","detail":"{readStateIndex:25347; appliedIndex:25346; }","duration":"290.004089ms","start":"2026-05-19T17:50:47.37738Z","end":"2026-05-19T17:50:47.667385Z","steps":["trace[1590642996] 'read index received' (duration: 289.64888ms)","trace[1590642996] 'applied index is now lower than readState.Index' (duration: 353.489µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:47.667795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.507507ms","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":"warn","ts":"2026-05-19T17:50:47.667816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.253908ms","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-19T17:50:47.667842Z","caller":"traceutil/trace.go:171","msg":"trace[386363587] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24543; }","duration":"704.570108ms","start":"2026-05-19T17:50:46.963259Z","end":"2026-05-19T17:50:47.667829Z","steps":["trace[386363587] 'agreement among raft nodes before linearized reading' (duration: 704.473885ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:47.667848Z","caller":"traceutil/trace.go:171","msg":"trace[1602850800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24543; }","duration":"623.313619ms","start":"2026-05-19T17:50:47.044525Z","end":"2026-05-19T17:50:47.667839Z","steps":["trace[1602850800] 'agreement among raft nodes before linearized reading' (duration: 623.202935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.667825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.956132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-19T17:50:47.667884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.04451Z","time spent":"623.365501ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T17:50:47.667918Z","caller":"traceutil/trace.go:171","msg":"trace[386205082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24543; }","duration":"529.104575ms","start":"2026-05-19T17:50:47.138795Z","end":"2026-05-19T17:50:47.667899Z","steps":["trace[386205082] 'agreement among raft nodes before linearized reading' (duration: 528.829118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.667979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.138774Z","time spent":"529.196038ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:50:47.667983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.599392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-19T17:50:47.667983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.492646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:50:47.668024Z","caller":"traceutil/trace.go:171","msg":"trace[58643582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24543; }","duration":"261.665264ms","start":"2026-05-19T17:50:47.406348Z","end":"2026-05-19T17:50:47.668013Z","steps":["trace[58643582] 'agreement among raft nodes before linearized reading' (duration: 261.572242ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:47.668045Z","caller":"traceutil/trace.go:171","msg":"trace[716900192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24543; }","duration":"286.632628ms","start":"2026-05-19T17:50:47.381404Z","end":"2026-05-19T17:50:47.668036Z","steps":["trace[716900192] 'agreement among raft nodes before linearized reading' (duration: 286.477755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.667583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.680924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T17:50:47.668188Z","caller":"traceutil/trace.go:171","msg":"trace[168203408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24543; }","duration":"529.311621ms","start":"2026-05-19T17:50:47.138865Z","end":"2026-05-19T17:50:47.668176Z","steps":["trace[168203408] 'agreement among raft nodes before linearized reading' (duration: 528.557762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.66823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.138857Z","time spent":"529.361612ms","remote":"127.0.0.1:44746","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-19T17:50:47.667885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:46.96325Z","time spent":"704.625351ms","remote":"127.0.0.1:44664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-05-19T17:50:47.667634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.75487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:50:47.668408Z","caller":"traceutil/trace.go:171","msg":"trace[1805146257] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:24543; }","duration":"603.655723ms","start":"2026-05-19T17:50:47.064733Z","end":"2026-05-19T17:50:47.668389Z","steps":["trace[1805146257] 'agreement among raft nodes before linearized reading' (duration: 602.715269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.668464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.064716Z","time spent":"603.732054ms","remote":"127.0.0.1:52786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:50:47.667655Z","caller":"traceutil/trace.go:171","msg":"trace[1345725959] transaction","detail":"{read_only:false; response_revision:24543; number_of_response:1; }","duration":"322.037856ms","start":"2026-05-19T17:50:47.3456Z","end":"2026-05-19T17:50:47.667638Z","steps":["trace[1345725959] 'process raft request' (duration: 321.552834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.668802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.345567Z","time spent":"323.148574ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:47.667702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.747396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:50:47.668916Z","caller":"traceutil/trace.go:171","msg":"trace[1278070384] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:24543; }","duration":"372.011908ms","start":"2026-05-19T17:50:47.29689Z","end":"2026-05-19T17:50:47.668902Z","steps":["trace[1278070384] 'agreement among raft nodes before linearized reading' (duration: 370.780168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.668958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.296864Z","time spent":"372.078369ms","remote":"127.0.0.1:44844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:50:47.667707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.586924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:50:47.669088Z","caller":"traceutil/trace.go:171","msg":"trace[1754307720] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:24543; }","duration":"403.002129ms","start":"2026-05-19T17:50:47.266077Z","end":"2026-05-19T17:50:47.66908Z","steps":["trace[1754307720] 'agreement among raft nodes before linearized reading' (duration: 401.600275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:47.669114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.266058Z","time spent":"403.04729ms","remote":"127.0.0.1:44684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:50:48.036654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.169535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:50:48.037045Z","caller":"traceutil/trace.go:171","msg":"trace[2132121101] linearizableReadLoop","detail":"{readStateIndex:25351; appliedIndex:25347; }","duration":"361.464461ms","start":"2026-05-19T17:50:47.67556Z","end":"2026-05-19T17:50:48.037025Z","steps":["trace[2132121101] 'read index received' (duration: 105.825125ms)","trace[2132121101] 'applied index is now lower than readState.Index' (duration: 255.637826ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:48.037436Z","caller":"traceutil/trace.go:171","msg":"trace[852452037] transaction","detail":"{read_only:false; response_revision:24544; number_of_response:1; }","duration":"365.378775ms","start":"2026-05-19T17:50:47.672031Z","end":"2026-05-19T17:50:48.03741Z","steps":["trace[852452037] 'process raft request' (duration: 109.36929ms)","trace[852452037] 'compare' (duration: 253.850334ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:48.037394Z","caller":"traceutil/trace.go:171","msg":"trace[1138332973] transaction","detail":"{read_only:false; response_revision:24546; number_of_response:1; }","duration":"364.470874ms","start":"2026-05-19T17:50:47.672885Z","end":"2026-05-19T17:50:48.037356Z","steps":["trace[1138332973] 'process raft request' (duration: 363.978862ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:48.037513Z","caller":"traceutil/trace.go:171","msg":"trace[1606625819] transaction","detail":"{read_only:false; response_revision:24547; number_of_response:1; }","duration":"364.309581ms","start":"2026-05-19T17:50:47.673193Z","end":"2026-05-19T17:50:48.037503Z","steps":["trace[1606625819] 'process raft request' (duration: 363.721087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.037547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.672015Z","time spent":"365.478048ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:48.037657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.673182Z","time spent":"364.386093ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:48.0378Z","caller":"traceutil/trace.go:171","msg":"trace[1889970807] transaction","detail":"{read_only:false; response_revision:24545; number_of_response:1; }","duration":"364.891115ms","start":"2026-05-19T17:50:47.672894Z","end":"2026-05-19T17:50:48.037785Z","steps":["trace[1889970807] 'process raft request' (duration: 363.8647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.037803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.672867Z","time spent":"364.787862ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:48.037854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.295533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:50:48.037882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.672877Z","time spent":"364.962787ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:48.037898Z","caller":"traceutil/trace.go:171","msg":"trace[1541464294] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24548; }","duration":"362.358204ms","start":"2026-05-19T17:50:47.675526Z","end":"2026-05-19T17:50:48.037884Z","steps":["trace[1541464294] 'agreement among raft nodes before linearized reading' (duration: 362.270732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.037934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.675515Z","time spent":"362.409845ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-05-19T17:50:48.037441Z","caller":"traceutil/trace.go:171","msg":"trace[2010569895] transaction","detail":"{read_only:false; response_revision:24548; number_of_response:1; }","duration":"318.93716ms","start":"2026-05-19T17:50:47.718459Z","end":"2026-05-19T17:50:48.037396Z","steps":["trace[2010569895] 'process raft request' (duration: 318.51017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.03812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.718433Z","time spent":"319.645567ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:48.326935Z","caller":"traceutil/trace.go:171","msg":"trace[1696603913] linearizableReadLoop","detail":"{readStateIndex:25353; appliedIndex:25352; }","duration":"289.821296ms","start":"2026-05-19T17:50:48.037092Z","end":"2026-05-19T17:50:48.326913Z","steps":["trace[1696603913] 'read index received' (duration: 289.634521ms)","trace[1696603913] 'applied index is now lower than readState.Index' (duration: 185.945µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:50:48.326975Z","caller":"traceutil/trace.go:171","msg":"trace[1538133457] transaction","detail":"{read_only:false; response_revision:24549; number_of_response:1; }","duration":"302.958983ms","start":"2026-05-19T17:50:48.023987Z","end":"2026-05-19T17:50:48.326946Z","steps":["trace[1538133457] 'process raft request' (duration: 302.789029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.327086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.902527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:50:48.327104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:48.02396Z","time spent":"303.077006ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:48.327106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.205903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-19T17:50:48.327127Z","caller":"traceutil/trace.go:171","msg":"trace[2113017254] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24549; }","duration":"282.990479ms","start":"2026-05-19T17:50:48.044124Z","end":"2026-05-19T17:50:48.327114Z","steps":["trace[2113017254] 'agreement among raft nodes before linearized reading' (duration: 282.908117ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:48.327145Z","caller":"traceutil/trace.go:171","msg":"trace[916834382] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24549; }","duration":"417.279045ms","start":"2026-05-19T17:50:47.909856Z","end":"2026-05-19T17:50:48.327135Z","steps":["trace[916834382] 'agreement among raft nodes before linearized reading' (duration: 417.0879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.32718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.909837Z","time spent":"417.336016ms","remote":"127.0.0.1:44626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1882,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"warn","ts":"2026-05-19T17:50:48.327263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.551521ms","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-19T17:50:48.327422Z","caller":"traceutil/trace.go:171","msg":"trace[160049141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24549; }","duration":"603.776865ms","start":"2026-05-19T17:50:47.723627Z","end":"2026-05-19T17:50:48.327404Z","steps":["trace[160049141] 'agreement among raft nodes before linearized reading' (duration: 603.417497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.327451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.674674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:50:48.327492Z","caller":"traceutil/trace.go:171","msg":"trace[135187046] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24549; }","duration":"273.754016ms","start":"2026-05-19T17:50:48.053728Z","end":"2026-05-19T17:50:48.327482Z","steps":["trace[135187046] 'agreement among raft nodes before linearized reading' (duration: 273.668454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.327513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:47.723615Z","time spent":"603.863518ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T17:50:48.785688Z","caller":"traceutil/trace.go:171","msg":"trace[432670100] linearizableReadLoop","detail":"{readStateIndex:25355; appliedIndex:25354; }","duration":"110.934449ms","start":"2026-05-19T17:50:48.674726Z","end":"2026-05-19T17:50:48.785661Z","steps":["trace[432670100] 'read index received' (duration: 110.705503ms)","trace[432670100] 'applied index is now lower than readState.Index' (duration: 228.136µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:50:48.785903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.111943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:50:48.786001Z","caller":"traceutil/trace.go:171","msg":"trace[537154218] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:24551; }","duration":"111.292928ms","start":"2026-05-19T17:50:48.674673Z","end":"2026-05-19T17:50:48.785966Z","steps":["trace[537154218] 'agreement among raft nodes before linearized reading' (duration: 111.091963ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:48.786439Z","caller":"traceutil/trace.go:171","msg":"trace[486377505] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"219.789188ms","start":"2026-05-19T17:50:48.566636Z","end":"2026-05-19T17:50:48.786426Z","steps":["trace[486377505] 'process raft request' (duration: 218.868445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:48.933444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.784071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:50:48.933517Z","caller":"traceutil/trace.go:171","msg":"trace[1577708730] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24551; }","duration":"135.876873ms","start":"2026-05-19T17:50:48.797621Z","end":"2026-05-19T17:50:48.933498Z","steps":["trace[1577708730] 'count revisions from in-memory index tree' (duration: 135.72221ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:49.60146Z","caller":"traceutil/trace.go:171","msg":"trace[1478181668] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"216.100618ms","start":"2026-05-19T17:50:49.385338Z","end":"2026-05-19T17:50:49.601439Z","steps":["trace[1478181668] 'process raft request' (duration: 215.951174ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:49.750154Z","caller":"traceutil/trace.go:171","msg":"trace[1354517283] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"364.736921ms","start":"2026-05-19T17:50:49.385371Z","end":"2026-05-19T17:50:49.750108Z","steps":["trace[1354517283] 'process raft request' (duration: 361.664926ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:50:49.750216Z","caller":"traceutil/trace.go:171","msg":"trace[915177504] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"364.786082ms","start":"2026-05-19T17:50:49.385389Z","end":"2026-05-19T17:50:49.750175Z","steps":["trace[915177504] 'process raft request' (duration: 364.488605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:49.750309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:49.385347Z","time spent":"364.878054ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:50:49.750339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:49.385368Z","time spent":"364.901904ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:50:49.750184Z","caller":"traceutil/trace.go:171","msg":"trace[2031571020] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"364.773031ms","start":"2026-05-19T17:50:49.385372Z","end":"2026-05-19T17:50:49.750145Z","steps":["trace[2031571020] 'process raft request' (duration: 364.415243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:50:49.750478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:50:49.385347Z","time spent":"365.062979ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:03.99875Z","caller":"traceutil/trace.go:171","msg":"trace[110092927] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"199.735751ms","start":"2026-05-19T17:51:03.798988Z","end":"2026-05-19T17:51:03.998724Z","steps":["trace[110092927] 'process raft request' (duration: 199.567757ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:04.588778Z","caller":"traceutil/trace.go:171","msg":"trace[1431324140] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"774.623247ms","start":"2026-05-19T17:51:03.814131Z","end":"2026-05-19T17:51:04.588755Z","steps":["trace[1431324140] 'process raft request' (duration: 774.564676ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:04.588831Z","caller":"traceutil/trace.go:171","msg":"trace[1056674747] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"787.654822ms","start":"2026-05-19T17:51:03.801143Z","end":"2026-05-19T17:51:04.588797Z","steps":["trace[1056674747] 'process raft request' (duration: 787.429927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:04.588901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:03.814123Z","time spent":"774.715229ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:04.588999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:03.801128Z","time spent":"787.773315ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:04.589104Z","caller":"traceutil/trace.go:171","msg":"trace[1599211832] linearizableReadLoop","detail":"{readStateIndex:25468; appliedIndex:25465; }","duration":"698.852141ms","start":"2026-05-19T17:51:03.890169Z","end":"2026-05-19T17:51:04.589021Z","steps":["trace[1599211832] 'read index received' (duration: 108.558412ms)","trace[1599211832] 'applied index is now lower than readState.Index' (duration: 590.290539ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:04.58915Z","caller":"traceutil/trace.go:171","msg":"trace[493989283] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"763.044936ms","start":"2026-05-19T17:51:03.826093Z","end":"2026-05-19T17:51:04.589138Z","steps":["trace[493989283] 'process raft request' (duration: 762.634967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:04.589227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.055265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:51:04.58939Z","caller":"traceutil/trace.go:171","msg":"trace[1657748073] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:24661; }","duration":"699.25352ms","start":"2026-05-19T17:51:03.890127Z","end":"2026-05-19T17:51:04.589381Z","steps":["trace[1657748073] 'agreement among raft nodes before linearized reading' (duration: 699.064506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:04.589416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:03.89011Z","time spent":"699.299001ms","remote":"127.0.0.1:44650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:51:04.589369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:03.826071Z","time spent":"763.205571ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:04.604415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.912264ms","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-19T17:51:04.604503Z","caller":"traceutil/trace.go:171","msg":"trace[1454221268] 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:24662; }","duration":"511.009357ms","start":"2026-05-19T17:51:04.09347Z","end":"2026-05-19T17:51:04.60448Z","steps":["trace[1454221268] 'agreement among raft nodes before linearized reading' (duration: 510.850323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:04.604553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.093408Z","time spent":"511.131209ms","remote":"127.0.0.1:52968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:51:04.604644Z","caller":"traceutil/trace.go:171","msg":"trace[1919974186] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"495.002128ms","start":"2026-05-19T17:51:04.109626Z","end":"2026-05-19T17:51:04.604628Z","steps":["trace[1919974186] 'process raft request' (duration: 492.952909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:04.604712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.317581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-19T17:51:04.604776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.109608Z","time spent":"495.067289ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:04.604795Z","caller":"traceutil/trace.go:171","msg":"trace[490305550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24662; }","duration":"152.457385ms","start":"2026-05-19T17:51:04.452313Z","end":"2026-05-19T17:51:04.604771Z","steps":["trace[490305550] 'agreement among raft nodes before linearized reading' (duration: 152.166228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:04.604822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.403675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:51:04.604885Z","caller":"traceutil/trace.go:171","msg":"trace[2054615144] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24662; }","duration":"171.470536ms","start":"2026-05-19T17:51:04.433376Z","end":"2026-05-19T17:51:04.604847Z","steps":["trace[2054615144] 'agreement among raft nodes before linearized reading' (duration: 171.408295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:04.604932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.388642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-19T17:51:04.606067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.177976ms","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-19T17:51:04.606162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.168334ms","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-19T17:51:04.60622Z","caller":"traceutil/trace.go:171","msg":"trace[2013407124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24662; }","duration":"472.34384ms","start":"2026-05-19T17:51:04.133826Z","end":"2026-05-19T17:51:04.60617Z","steps":["trace[2013407124] 'agreement among raft nodes before linearized reading' (duration: 471.07741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:04.606274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.133814Z","time spent":"472.449182ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T17:51:04.606278Z","caller":"traceutil/trace.go:171","msg":"trace[2034316601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24662; }","duration":"493.313018ms","start":"2026-05-19T17:51:04.11292Z","end":"2026-05-19T17:51:04.606233Z","steps":["trace[2034316601] 'agreement among raft nodes before linearized reading' (duration: 491.917053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:04.606332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.112898Z","time spent":"493.42175ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-19T17:51:04.607427Z","caller":"traceutil/trace.go:171","msg":"trace[518652039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24662; }","duration":"479.843041ms","start":"2026-05-19T17:51:04.127528Z","end":"2026-05-19T17:51:04.607371Z","steps":["trace[518652039] 'agreement among raft nodes before linearized reading' (duration: 477.268058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:04.607495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.127486Z","time spent":"479.993654ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:51:04.607614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.769314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:273001"} {"level":"info","ts":"2026-05-19T17:51:04.614608Z","caller":"traceutil/trace.go:171","msg":"trace[1040033670] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:24662; }","duration":"138.825786ms","start":"2026-05-19T17:51:04.475735Z","end":"2026-05-19T17:51:04.614561Z","steps":["trace[1040033670] 'agreement among raft nodes before linearized reading' (duration: 129.068248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:05.174573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.473616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:51:05.17485Z","caller":"traceutil/trace.go:171","msg":"trace[848935976] linearizableReadLoop","detail":"{readStateIndex:25473; appliedIndex:25469; }","duration":"557.440242ms","start":"2026-05-19T17:51:04.617387Z","end":"2026-05-19T17:51:05.174827Z","steps":["trace[848935976] 'read index received' (duration: 9.221243ms)","trace[848935976] 'applied index is now lower than readState.Index' (duration: 548.217269ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:05.174963Z","caller":"traceutil/trace.go:171","msg":"trace[514615802] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"561.298575ms","start":"2026-05-19T17:51:04.613647Z","end":"2026-05-19T17:51:05.174945Z","steps":["trace[514615802] 'process raft request' (duration: 283.350798ms)","trace[514615802] 'compare' (duration: 277.333292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:05.175057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.613629Z","time spent":"561.381177ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:05.175067Z","caller":"traceutil/trace.go:171","msg":"trace[1984868795] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"558.127878ms","start":"2026-05-19T17:51:04.616882Z","end":"2026-05-19T17:51:05.17501Z","steps":["trace[1984868795] 'process raft request' (duration: 557.888863ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:05.1751Z","caller":"traceutil/trace.go:171","msg":"trace[1590587508] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"559.700437ms","start":"2026-05-19T17:51:04.615368Z","end":"2026-05-19T17:51:05.175069Z","steps":["trace[1590587508] 'process raft request' (duration: 559.325008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:05.175214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.78353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T17:51:05.175243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.615357Z","time spent":"559.809519ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:05.175247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.616867Z","time spent":"558.281732ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:05.1753Z","caller":"traceutil/trace.go:171","msg":"trace[2132230985] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:24666; }","duration":"557.910554ms","start":"2026-05-19T17:51:04.61737Z","end":"2026-05-19T17:51:05.17528Z","steps":["trace[2132230985] 'agreement among raft nodes before linearized reading' (duration: 557.645367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:05.175343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.61736Z","time spent":"557.973785ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":19,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-05-19T17:51:05.189081Z","caller":"traceutil/trace.go:171","msg":"trace[1828447982] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"455.665435ms","start":"2026-05-19T17:51:04.733386Z","end":"2026-05-19T17:51:05.189051Z","steps":["trace[1828447982] 'process raft request' (duration: 455.493531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:05.189341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.733364Z","time spent":"455.88558ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:05.206353Z","caller":"traceutil/trace.go:171","msg":"trace[1967029750] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"119.268061ms","start":"2026-05-19T17:51:05.087044Z","end":"2026-05-19T17:51:05.206312Z","steps":["trace[1967029750] 'process raft request' (duration: 119.084736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:05.206407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.347054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:51:05.206411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.470141ms","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-19T17:51:05.206466Z","caller":"traceutil/trace.go:171","msg":"trace[1180690528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24668; }","duration":"249.447986ms","start":"2026-05-19T17:51:04.957Z","end":"2026-05-19T17:51:05.206448Z","steps":["trace[1180690528] 'agreement among raft nodes before linearized reading' (duration: 249.332953ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:05.206476Z","caller":"traceutil/trace.go:171","msg":"trace[330774258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24668; }","duration":"448.581673ms","start":"2026-05-19T17:51:04.757878Z","end":"2026-05-19T17:51:05.206459Z","steps":["trace[330774258] 'agreement among raft nodes before linearized reading' (duration: 448.382389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:05.206514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:04.757856Z","time spent":"448.649384ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T17:51:05.517494Z","caller":"traceutil/trace.go:171","msg":"trace[736021459] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"185.412854ms","start":"2026-05-19T17:51:05.332053Z","end":"2026-05-19T17:51:05.517466Z","steps":["trace[736021459] 'process raft request' (duration: 185.073496ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:05.517522Z","caller":"traceutil/trace.go:171","msg":"trace[1258587111] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"307.521234ms","start":"2026-05-19T17:51:05.209949Z","end":"2026-05-19T17:51:05.51747Z","steps":["trace[1258587111] 'process raft request' (duration: 264.999663ms)","trace[1258587111] 'compare' (duration: 41.334752ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:05.517747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:05.209925Z","time spent":"307.708319ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:05.517784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.749258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:51:05.517846Z","caller":"traceutil/trace.go:171","msg":"trace[1854315972] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:24670; }","duration":"118.84096ms","start":"2026-05-19T17:51:05.398986Z","end":"2026-05-19T17:51:05.517827Z","steps":["trace[1854315972] 'agreement among raft nodes before linearized reading' (duration: 118.717587ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:05.518522Z","caller":"traceutil/trace.go:171","msg":"trace[1286613860] linearizableReadLoop","detail":"{readStateIndex:25477; appliedIndex:25475; }","duration":"118.550083ms","start":"2026-05-19T17:51:05.399016Z","end":"2026-05-19T17:51:05.517566Z","steps":["trace[1286613860] 'read index received' (duration: 75.865289ms)","trace[1286613860] 'applied index is now lower than readState.Index' (duration: 42.681644ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:05.905208Z","caller":"traceutil/trace.go:171","msg":"trace[1251682882] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"247.999072ms","start":"2026-05-19T17:51:05.657168Z","end":"2026-05-19T17:51:05.905167Z","steps":["trace[1251682882] 'process raft request' (duration: 246.094065ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:07.11071Z","caller":"traceutil/trace.go:171","msg":"trace[1621006085] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"515.630568ms","start":"2026-05-19T17:51:06.595055Z","end":"2026-05-19T17:51:07.110686Z","steps":["trace[1621006085] 'process raft request' (duration: 515.422853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:07.110889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:06.595034Z","time spent":"515.750851ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:07.111076Z","caller":"traceutil/trace.go:171","msg":"trace[2025541009] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"516.002717ms","start":"2026-05-19T17:51:06.595049Z","end":"2026-05-19T17:51:07.111051Z","steps":["trace[2025541009] 'process raft request' (duration: 515.865294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:07.111264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:06.595025Z","time spent":"516.158981ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:07.111104Z","caller":"traceutil/trace.go:171","msg":"trace[1552840315] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"516.023438ms","start":"2026-05-19T17:51:06.595055Z","end":"2026-05-19T17:51:07.111079Z","steps":["trace[1552840315] 'process raft request' (duration: 515.586917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:07.111478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:06.595036Z","time spent":"516.372246ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:07.114186Z","caller":"traceutil/trace.go:171","msg":"trace[640923747] linearizableReadLoop","detail":"{readStateIndex:25483; appliedIndex:25479; }","duration":"483.425637ms","start":"2026-05-19T17:51:06.630744Z","end":"2026-05-19T17:51:07.11417Z","steps":["trace[640923747] 'read index received' (duration: 479.657986ms)","trace[640923747] 'applied index is now lower than readState.Index' (duration: 3.767031ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:07.114314Z","caller":"traceutil/trace.go:171","msg":"trace[1816198913] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"502.341036ms","start":"2026-05-19T17:51:06.611958Z","end":"2026-05-19T17:51:07.114299Z","steps":["trace[1816198913] 'process raft request' (duration: 502.11261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:07.114397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:06.611949Z","time spent":"502.395928ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:07.114598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.998409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:51:07.114657Z","caller":"traceutil/trace.go:171","msg":"trace[878386030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24675; }","duration":"158.105712ms","start":"2026-05-19T17:51:06.956537Z","end":"2026-05-19T17:51:07.114642Z","steps":["trace[878386030] 'agreement among raft nodes before linearized reading' (duration: 158.008089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:07.114626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.705688ms","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-19T17:51:07.11473Z","caller":"traceutil/trace.go:171","msg":"trace[1063435302] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24675; }","duration":"472.83899ms","start":"2026-05-19T17:51:06.641868Z","end":"2026-05-19T17:51:07.114707Z","steps":["trace[1063435302] 'agreement among raft nodes before linearized reading' (duration: 472.680816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:07.11474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.895939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-19T17:51:07.114816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:06.641857Z","time spent":"472.946163ms","remote":"127.0.0.1:44900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-19T17:51:07.114852Z","caller":"traceutil/trace.go:171","msg":"trace[2134933274] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24675; }","duration":"484.123664ms","start":"2026-05-19T17:51:06.630711Z","end":"2026-05-19T17:51:07.114835Z","steps":["trace[2134933274] 'agreement among raft nodes before linearized reading' (duration: 483.821217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:07.11497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:06.630696Z","time spent":"484.257148ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T17:51:11.368776Z","caller":"traceutil/trace.go:171","msg":"trace[889185956] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"240.063189ms","start":"2026-05-19T17:51:11.128692Z","end":"2026-05-19T17:51:11.368755Z","steps":["trace[889185956] 'process raft request' (duration: 239.993128ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:11.368797Z","caller":"traceutil/trace.go:171","msg":"trace[1504986439] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"240.081279ms","start":"2026-05-19T17:51:11.128679Z","end":"2026-05-19T17:51:11.368761Z","steps":["trace[1504986439] 'process raft request' (duration: 239.845994ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:11.369091Z","caller":"traceutil/trace.go:171","msg":"trace[906063018] linearizableReadLoop","detail":"{readStateIndex:25571; appliedIndex:25567; }","duration":"236.445311ms","start":"2026-05-19T17:51:11.132624Z","end":"2026-05-19T17:51:11.36907Z","steps":["trace[906063018] 'read index received' (duration: 86.575028ms)","trace[906063018] 'applied index is now lower than readState.Index' (duration: 149.869483ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:11.369146Z","caller":"traceutil/trace.go:171","msg":"trace[1953975618] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"162.612701ms","start":"2026-05-19T17:51:11.206519Z","end":"2026-05-19T17:51:11.369131Z","steps":["trace[1953975618] 'process raft request' (duration: 162.430797ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:11.369091Z","caller":"traceutil/trace.go:171","msg":"trace[1381293680] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"239.975148ms","start":"2026-05-19T17:51:11.129082Z","end":"2026-05-19T17:51:11.369057Z","steps":["trace[1381293680] 'process raft request' (duration: 239.63984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:11.369347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.697097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T17:51:11.369391Z","caller":"traceutil/trace.go:171","msg":"trace[1988097416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24763; }","duration":"236.774359ms","start":"2026-05-19T17:51:11.132601Z","end":"2026-05-19T17:51:11.369375Z","steps":["trace[1988097416] 'agreement among raft nodes before linearized reading' (duration: 236.584665ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:11.450655Z","caller":"traceutil/trace.go:171","msg":"trace[593131586] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"226.776547ms","start":"2026-05-19T17:51:11.223849Z","end":"2026-05-19T17:51:11.450626Z","steps":["trace[593131586] 'process raft request' (duration: 226.584803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:11.450753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.303996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T17:51:11.450821Z","caller":"traceutil/trace.go:171","msg":"trace[1462638492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24764; }","duration":"252.423049ms","start":"2026-05-19T17:51:11.198382Z","end":"2026-05-19T17:51:11.450805Z","steps":["trace[1462638492] 'agreement among raft nodes before linearized reading' (duration: 252.196283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:11.450819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.438284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-19T17:51:11.450831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.387543ms","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-19T17:51:11.450896Z","caller":"traceutil/trace.go:171","msg":"trace[241503957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24764; }","duration":"251.588058ms","start":"2026-05-19T17:51:11.199289Z","end":"2026-05-19T17:51:11.450877Z","steps":["trace[241503957] 'agreement among raft nodes before linearized reading' (duration: 251.373373ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:11.450923Z","caller":"traceutil/trace.go:171","msg":"trace[1623723343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24764; }","duration":"251.524946ms","start":"2026-05-19T17:51:11.199366Z","end":"2026-05-19T17:51:11.450891Z","steps":["trace[1623723343] 'agreement among raft nodes before linearized reading' (duration: 251.26481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:11.610221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.06891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:51:11.610301Z","caller":"traceutil/trace.go:171","msg":"trace[810355100] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"236.888931ms","start":"2026-05-19T17:51:11.373398Z","end":"2026-05-19T17:51:11.610287Z","steps":["trace[810355100] 'process raft request' (duration: 123.691698ms)","trace[810355100] 'compare' (duration: 112.974758ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:11.733055Z","caller":"traceutil/trace.go:171","msg":"trace[1522525424] linearizableReadLoop","detail":"{readStateIndex:25579; appliedIndex:25574; }","duration":"192.808034ms","start":"2026-05-19T17:51:11.540229Z","end":"2026-05-19T17:51:11.733037Z","steps":["trace[1522525424] 'read index received' (duration: 178.737143ms)","trace[1522525424] 'applied index is now lower than readState.Index' (duration: 14.070231ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:11.733148Z","caller":"traceutil/trace.go:171","msg":"trace[1621622568] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"277.482646ms","start":"2026-05-19T17:51:11.455657Z","end":"2026-05-19T17:51:11.733139Z","steps":["trace[1621622568] 'process raft request' (duration: 277.268381ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:11.733314Z","caller":"traceutil/trace.go:171","msg":"trace[447125871] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"197.955538ms","start":"2026-05-19T17:51:11.535351Z","end":"2026-05-19T17:51:11.733307Z","steps":["trace[447125871] 'process raft request' (duration: 197.657931ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:11.733053Z","caller":"traceutil/trace.go:171","msg":"trace[1340992828] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"277.407184ms","start":"2026-05-19T17:51:11.455602Z","end":"2026-05-19T17:51:11.733009Z","steps":["trace[1340992828] 'process raft request' (duration: 263.355084ms)","trace[1340992828] 'compare' (duration: 13.838975ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:11.733361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.841343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8b6ef4c5-c2a5-4c2e-870b-cd3c4d8ea6d0\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-19T17:51:11.733417Z","caller":"traceutil/trace.go:171","msg":"trace[663212333] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8b6ef4c5-c2a5-4c2e-870b-cd3c4d8ea6d0; range_end:; response_count:1; response_revision:24770; }","duration":"154.950346ms","start":"2026-05-19T17:51:11.578456Z","end":"2026-05-19T17:51:11.733407Z","steps":["trace[663212333] 'agreement among raft nodes before linearized reading' (duration: 154.802902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:11.733431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.179723ms","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-19T17:51:11.733507Z","caller":"traceutil/trace.go:171","msg":"trace[418688374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24770; }","duration":"193.306426ms","start":"2026-05-19T17:51:11.540183Z","end":"2026-05-19T17:51:11.733489Z","steps":["trace[418688374] 'agreement among raft nodes before linearized reading' (duration: 193.039299ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:11.733628Z","caller":"traceutil/trace.go:171","msg":"trace[28786029] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"230.044917ms","start":"2026-05-19T17:51:11.503519Z","end":"2026-05-19T17:51:11.733564Z","steps":["trace[28786029] 'process raft request' (duration: 229.468323ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:11.734024Z","caller":"traceutil/trace.go:171","msg":"trace[626811557] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"277.68062ms","start":"2026-05-19T17:51:11.455953Z","end":"2026-05-19T17:51:11.733633Z","steps":["trace[626811557] 'process raft request' (duration: 277.004794ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:14.081458Z","caller":"traceutil/trace.go:171","msg":"trace[45554154] transaction","detail":"{read_only:false; response_revision:24789; number_of_response:1; }","duration":"340.454932ms","start":"2026-05-19T17:51:13.740968Z","end":"2026-05-19T17:51:14.081423Z","steps":["trace[45554154] 'process raft request' (duration: 331.133326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:14.081621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:13.740949Z","time spent":"340.586555ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:14.08163Z","caller":"traceutil/trace.go:171","msg":"trace[1362043450] transaction","detail":"{read_only:false; response_revision:24790; number_of_response:1; }","duration":"339.368596ms","start":"2026-05-19T17:51:13.74222Z","end":"2026-05-19T17:51:14.081588Z","steps":["trace[1362043450] 'process raft request' (duration: 339.094899ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:14.081747Z","caller":"traceutil/trace.go:171","msg":"trace[1621473279] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"145.999938ms","start":"2026-05-19T17:51:13.9357Z","end":"2026-05-19T17:51:14.081699Z","steps":["trace[1621473279] 'process raft request' (duration: 145.888846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:14.081816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:13.742205Z","time spent":"339.50854ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:14.081819Z","caller":"traceutil/trace.go:171","msg":"trace[85083333] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"339.015707ms","start":"2026-05-19T17:51:13.742771Z","end":"2026-05-19T17:51:14.081787Z","steps":["trace[85083333] 'process raft request' (duration: 338.742941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:14.081937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:13.742754Z","time spent":"339.1286ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:14.081964Z","caller":"traceutil/trace.go:171","msg":"trace[1855123686] linearizableReadLoop","detail":"{readStateIndex:25599; appliedIndex:25596; }","duration":"339.711534ms","start":"2026-05-19T17:51:13.742235Z","end":"2026-05-19T17:51:14.081946Z","steps":["trace[1855123686] 'read index received' (duration: 82.05933ms)","trace[1855123686] 'applied index is now lower than readState.Index' (duration: 257.651064ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:14.082236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.406817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8b6ef4c5-c2a5-4c2e-870b-cd3c4d8ea6d0\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-05-19T17:51:14.082292Z","caller":"traceutil/trace.go:171","msg":"trace[773103259] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8b6ef4c5-c2a5-4c2e-870b-cd3c4d8ea6d0; range_end:; response_count:1; response_revision:24792; }","duration":"181.50847ms","start":"2026-05-19T17:51:13.90077Z","end":"2026-05-19T17:51:14.082279Z","steps":["trace[773103259] 'agreement among raft nodes before linearized reading' (duration: 181.283615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:14.082287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.047113ms","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-19T17:51:14.08234Z","caller":"traceutil/trace.go:171","msg":"trace[2111493292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24792; }","duration":"340.120935ms","start":"2026-05-19T17:51:13.742204Z","end":"2026-05-19T17:51:14.082325Z","steps":["trace[2111493292] 'agreement among raft nodes before linearized reading' (duration: 339.848099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:14.082386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:13.742193Z","time spent":"340.179766ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T17:51:19.441028Z","caller":"traceutil/trace.go:171","msg":"trace[1670553269] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"111.076262ms","start":"2026-05-19T17:51:19.32993Z","end":"2026-05-19T17:51:19.441006Z","steps":["trace[1670553269] 'process raft request' (duration: 110.910747ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:19.684217Z","caller":"traceutil/trace.go:171","msg":"trace[1911159116] linearizableReadLoop","detail":"{readStateIndex:25665; appliedIndex:25664; }","duration":"101.746596ms","start":"2026-05-19T17:51:19.582441Z","end":"2026-05-19T17:51:19.684188Z","steps":["trace[1911159116] 'read index received' (duration: 101.536081ms)","trace[1911159116] 'applied index is now lower than readState.Index' (duration: 205.975µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:19.684372Z","caller":"traceutil/trace.go:171","msg":"trace[317917371] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"209.388185ms","start":"2026-05-19T17:51:19.474969Z","end":"2026-05-19T17:51:19.684358Z","steps":["trace[317917371] 'process raft request' (duration: 209.005096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.684575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.098074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:51:19.684665Z","caller":"traceutil/trace.go:171","msg":"trace[2088918619] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:24855; }","duration":"102.210297ms","start":"2026-05-19T17:51:19.582436Z","end":"2026-05-19T17:51:19.684646Z","steps":["trace[2088918619] 'agreement among raft nodes before linearized reading' (duration: 102.025153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.942894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.503032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:51:19.943Z","caller":"traceutil/trace.go:171","msg":"trace[1153186821] linearizableReadLoop","detail":"{readStateIndex:25666; appliedIndex:25665; }","duration":"232.623178ms","start":"2026-05-19T17:51:19.71036Z","end":"2026-05-19T17:51:19.942984Z","steps":["trace[1153186821] 'read index received' (duration: 99.932792ms)","trace[1153186821] 'applied index is now lower than readState.Index' (duration: 132.689206ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:19.943018Z","caller":"traceutil/trace.go:171","msg":"trace[518280228] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"254.504529ms","start":"2026-05-19T17:51:19.688484Z","end":"2026-05-19T17:51:19.942988Z","steps":["trace[518280228] 'process raft request' (duration: 121.797181ms)","trace[518280228] 'compare' (duration: 132.132543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:19.94476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.39563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-19T17:51:19.944829Z","caller":"traceutil/trace.go:171","msg":"trace[1249094100] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24856; }","duration":"234.493213ms","start":"2026-05-19T17:51:19.710308Z","end":"2026-05-19T17:51:19.944801Z","steps":["trace[1249094100] 'agreement among raft nodes before linearized reading' (duration: 232.743431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.955008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.331495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:51:19.955088Z","caller":"traceutil/trace.go:171","msg":"trace[1964114798] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24857; }","duration":"181.442688ms","start":"2026-05-19T17:51:19.773622Z","end":"2026-05-19T17:51:19.955065Z","steps":["trace[1964114798] 'agreement among raft nodes before linearized reading' (duration: 181.314775ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:19.955548Z","caller":"traceutil/trace.go:171","msg":"trace[371212853] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"114.71317ms","start":"2026-05-19T17:51:19.840818Z","end":"2026-05-19T17:51:19.955531Z","steps":["trace[371212853] 'process raft request' (duration: 114.007773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:19.955859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.530348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-19T17:51:19.955958Z","caller":"traceutil/trace.go:171","msg":"trace[1481427197] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24857; }","duration":"124.637311ms","start":"2026-05-19T17:51:19.8313Z","end":"2026-05-19T17:51:19.955938Z","steps":["trace[1481427197] 'agreement among raft nodes before linearized reading' (duration: 124.366574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:32.378453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.274554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:51:32.378589Z","caller":"traceutil/trace.go:171","msg":"trace[286392140] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"331.29387ms","start":"2026-05-19T17:51:32.047272Z","end":"2026-05-19T17:51:32.378566Z","steps":["trace[286392140] 'process raft request' (duration: 135.699008ms)","trace[286392140] 'compare' (duration: 195.045179ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:32.378659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:32.047253Z","time spent":"331.374002ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:32.641922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.562365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:51:32.642046Z","caller":"traceutil/trace.go:171","msg":"trace[1263309418] linearizableReadLoop","detail":"{readStateIndex:25771; appliedIndex:25769; }","duration":"482.309251ms","start":"2026-05-19T17:51:32.159717Z","end":"2026-05-19T17:51:32.642026Z","steps":["trace[1263309418] 'read index received' (duration: 23.262224ms)","trace[1263309418] 'applied index is now lower than readState.Index' (duration: 459.045417ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:32.642127Z","caller":"traceutil/trace.go:171","msg":"trace[2027651626] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"501.232089ms","start":"2026-05-19T17:51:32.140866Z","end":"2026-05-19T17:51:32.642098Z","steps":["trace[2027651626] 'process raft request' (duration: 356.387328ms)","trace[2027651626] 'compare' (duration: 144.38411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:32.642265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.546526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-19T17:51:32.642272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:32.140844Z","time spent":"501.347362ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:32.642299Z","caller":"traceutil/trace.go:171","msg":"trace[1747653204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24957; }","duration":"482.581757ms","start":"2026-05-19T17:51:32.159709Z","end":"2026-05-19T17:51:32.64229Z","steps":["trace[1747653204] 'agreement among raft nodes before linearized reading' (duration: 482.419083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:32.642334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:32.159647Z","time spent":"482.67925ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T17:51:32.642455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.595417ms","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-19T17:51:32.642566Z","caller":"traceutil/trace.go:171","msg":"trace[1641935617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24957; }","duration":"482.773692ms","start":"2026-05-19T17:51:32.159747Z","end":"2026-05-19T17:51:32.64252Z","steps":["trace[1641935617] 'agreement among raft nodes before linearized reading' (duration: 482.496665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:32.642579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.404999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-19T17:51:32.642623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:32.159726Z","time spent":"482.887335ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-19T17:51:32.642636Z","caller":"traceutil/trace.go:171","msg":"trace[1553721070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24957; }","duration":"467.538313ms","start":"2026-05-19T17:51:32.17508Z","end":"2026-05-19T17:51:32.642618Z","steps":["trace[1553721070] 'agreement among raft nodes before linearized reading' (duration: 467.337268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:32.642675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:32.175064Z","time spent":"467.601944ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T17:51:32.643005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.149667ms","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-19T17:51:32.643147Z","caller":"traceutil/trace.go:171","msg":"trace[101493336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24957; }","duration":"468.317471ms","start":"2026-05-19T17:51:32.174816Z","end":"2026-05-19T17:51:32.643134Z","steps":["trace[101493336] 'agreement among raft nodes before linearized reading' (duration: 467.967233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:32.643181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:32.1748Z","time spent":"468.373992ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-19T17:51:32.883848Z","caller":"traceutil/trace.go:171","msg":"trace[1699170708] linearizableReadLoop","detail":"{readStateIndex:25776; appliedIndex:25775; }","duration":"144.890482ms","start":"2026-05-19T17:51:32.738933Z","end":"2026-05-19T17:51:32.883823Z","steps":["trace[1699170708] 'read index received' (duration: 144.686847ms)","trace[1699170708] 'applied index is now lower than readState.Index' (duration: 202.525µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:32.883979Z","caller":"traceutil/trace.go:171","msg":"trace[1292001532] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"146.088251ms","start":"2026-05-19T17:51:32.737854Z","end":"2026-05-19T17:51:32.883943Z","steps":["trace[1292001532] 'process raft request' (duration: 145.759243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:32.88406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.120907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-05-19T17:51:32.884094Z","caller":"traceutil/trace.go:171","msg":"trace[244170251] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24962; }","duration":"145.226511ms","start":"2026-05-19T17:51:32.738857Z","end":"2026-05-19T17:51:32.884083Z","steps":["trace[244170251] 'agreement among raft nodes before linearized reading' (duration: 145.059147ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:36.797919Z","caller":"traceutil/trace.go:171","msg":"trace[1654699521] linearizableReadLoop","detail":"{readStateIndex:25809; appliedIndex:25808; }","duration":"113.678475ms","start":"2026-05-19T17:51:36.684209Z","end":"2026-05-19T17:51:36.797887Z","steps":["trace[1654699521] 'read index received' (duration: 113.506281ms)","trace[1654699521] 'applied index is now lower than readState.Index' (duration: 171.574µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:36.797957Z","caller":"traceutil/trace.go:171","msg":"trace[1275018976] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"143.074848ms","start":"2026-05-19T17:51:36.654852Z","end":"2026-05-19T17:51:36.797927Z","steps":["trace[1275018976] 'process raft request' (duration: 142.906974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:36.798226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.282165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T17:51:36.798262Z","caller":"traceutil/trace.go:171","msg":"trace[709256495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24994; }","duration":"113.368788ms","start":"2026-05-19T17:51:36.684884Z","end":"2026-05-19T17:51:36.798253Z","steps":["trace[709256495] 'agreement among raft nodes before linearized reading' (duration: 113.229844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:36.798225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.965718ms","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-19T17:51:36.798348Z","caller":"traceutil/trace.go:171","msg":"trace[2033707678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24994; }","duration":"113.109432ms","start":"2026-05-19T17:51:36.685217Z","end":"2026-05-19T17:51:36.798326Z","steps":["trace[2033707678] 'agreement among raft nodes before linearized reading' (duration: 112.924107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:36.799828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.615882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T17:51:36.799912Z","caller":"traceutil/trace.go:171","msg":"trace[335809207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24994; }","duration":"115.680264ms","start":"2026-05-19T17:51:36.684174Z","end":"2026-05-19T17:51:36.799854Z","steps":["trace[335809207] 'agreement among raft nodes before linearized reading' (duration: 113.860999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:36.800013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.811713ms","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-19T17:51:36.800043Z","caller":"traceutil/trace.go:171","msg":"trace[464986187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24994; }","duration":"114.841524ms","start":"2026-05-19T17:51:36.685194Z","end":"2026-05-19T17:51:36.800035Z","steps":["trace[464986187] 'agreement among raft nodes before linearized reading' (duration: 113.058151ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:36.922467Z","caller":"traceutil/trace.go:171","msg":"trace[1183541619] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"119.051167ms","start":"2026-05-19T17:51:36.803395Z","end":"2026-05-19T17:51:36.922447Z","steps":["trace[1183541619] 'process raft request' (duration: 118.711088ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:36.922493Z","caller":"traceutil/trace.go:171","msg":"trace[1095836547] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"117.797835ms","start":"2026-05-19T17:51:36.804672Z","end":"2026-05-19T17:51:36.92247Z","steps":["trace[1095836547] 'process raft request' (duration: 117.459007ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:36.922563Z","caller":"traceutil/trace.go:171","msg":"trace[1184418281] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"119.689292ms","start":"2026-05-19T17:51:36.802852Z","end":"2026-05-19T17:51:36.922542Z","steps":["trace[1184418281] 'process raft request' (duration: 107.871976ms)","trace[1184418281] 'compare' (duration: 11.206751ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:36.922591Z","caller":"traceutil/trace.go:171","msg":"trace[1882453951] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"119.267582ms","start":"2026-05-19T17:51:36.803302Z","end":"2026-05-19T17:51:36.922569Z","steps":["trace[1882453951] 'process raft request' (duration: 118.76687ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:37.364853Z","caller":"traceutil/trace.go:171","msg":"trace[1630127474] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"101.710416ms","start":"2026-05-19T17:51:37.263111Z","end":"2026-05-19T17:51:37.364821Z","steps":["trace[1630127474] 'process raft request' (duration: 101.531792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:45.079274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.014438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:51:45.079359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.06276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T17:51:45.079377Z","caller":"traceutil/trace.go:171","msg":"trace[2080277503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25056; }","duration":"122.168582ms","start":"2026-05-19T17:51:44.957191Z","end":"2026-05-19T17:51:45.079359Z","steps":["trace[2080277503] 'range keys from in-memory index tree' (duration: 121.847114ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:45.079411Z","caller":"traceutil/trace.go:171","msg":"trace[1862519625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25056; }","duration":"122.145761ms","start":"2026-05-19T17:51:44.957251Z","end":"2026-05-19T17:51:45.079397Z","steps":["trace[1862519625] 'range keys from in-memory index tree' (duration: 121.868454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:45.079546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.625697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-19T17:51:45.079572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.748427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T17:51:45.079642Z","caller":"traceutil/trace.go:171","msg":"trace[973922554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25056; }","duration":"118.757889ms","start":"2026-05-19T17:51:44.960864Z","end":"2026-05-19T17:51:45.079622Z","steps":["trace[973922554] 'range keys from in-memory index tree' (duration: 118.442522ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:45.079664Z","caller":"traceutil/trace.go:171","msg":"trace[266184225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25056; }","duration":"120.88824ms","start":"2026-05-19T17:51:44.958754Z","end":"2026-05-19T17:51:45.079642Z","steps":["trace[266184225] 'range keys from in-memory index tree' (duration: 120.492861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:45.079823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.326656ms","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-19T17:51:45.079909Z","caller":"traceutil/trace.go:171","msg":"trace[1224629020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25056; }","duration":"120.44362ms","start":"2026-05-19T17:51:44.959446Z","end":"2026-05-19T17:51:45.07989Z","steps":["trace[1224629020] 'range keys from in-memory index tree' (duration: 119.553437ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:48.234056Z","caller":"traceutil/trace.go:171","msg":"trace[1462465829] linearizableReadLoop","detail":"{readStateIndex:25898; appliedIndex:25897; }","duration":"263.264221ms","start":"2026-05-19T17:51:47.970756Z","end":"2026-05-19T17:51:48.234021Z","steps":["trace[1462465829] 'read index received' (duration: 262.968284ms)","trace[1462465829] 'applied index is now lower than readState.Index' (duration: 293.967µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:48.234122Z","caller":"traceutil/trace.go:171","msg":"trace[1347402936] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"331.909336ms","start":"2026-05-19T17:51:47.902187Z","end":"2026-05-19T17:51:48.234096Z","steps":["trace[1347402936] 'process raft request' (duration: 331.603949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:48.234218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.61644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T17:51:48.23426Z","caller":"traceutil/trace.go:171","msg":"trace[279140973] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25081; }","duration":"322.689832ms","start":"2026-05-19T17:51:47.911556Z","end":"2026-05-19T17:51:48.234246Z","steps":["trace[279140973] 'agreement among raft nodes before linearized reading' (duration: 322.61228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:48.234293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:47.911546Z","time spent":"322.736672ms","remote":"127.0.0.1:44836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-19T17:51:48.234367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:47.902171Z","time spent":"332.069969ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T17:51:48.480383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.761658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T17:51:48.480532Z","caller":"traceutil/trace.go:171","msg":"trace[1538972661] linearizableReadLoop","detail":"{readStateIndex:25899; appliedIndex:25898; }","duration":"246.332471ms","start":"2026-05-19T17:51:48.234177Z","end":"2026-05-19T17:51:48.480509Z","steps":["trace[1538972661] 'read index received' (duration: 101.002168ms)","trace[1538972661] 'applied index is now lower than readState.Index' (duration: 145.328293ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:48.480559Z","caller":"traceutil/trace.go:171","msg":"trace[451786306] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"370.021699ms","start":"2026-05-19T17:51:48.110501Z","end":"2026-05-19T17:51:48.480523Z","steps":["trace[451786306] 'process raft request' (duration: 224.638575ms)","trace[451786306] 'compare' (duration: 144.43485ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:48.480717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.316188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T17:51:48.480756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:48.110476Z","time spent":"370.167513ms","remote":"127.0.0.1:44746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T17:51:48.480777Z","caller":"traceutil/trace.go:171","msg":"trace[238336935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25082; }","duration":"170.405101ms","start":"2026-05-19T17:51:48.310356Z","end":"2026-05-19T17:51:48.480761Z","steps":["trace[238336935] 'agreement among raft nodes before linearized reading' (duration: 170.290618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:48.480829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.690365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-05-19T17:51:48.48093Z","caller":"traceutil/trace.go:171","msg":"trace[368562482] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25082; }","duration":"403.834198ms","start":"2026-05-19T17:51:48.077075Z","end":"2026-05-19T17:51:48.480909Z","steps":["trace[368562482] 'agreement among raft nodes before linearized reading' (duration: 403.556732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:48.480984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T17:51:48.077062Z","time spent":"403.91182ms","remote":"127.0.0.1:44654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-19T17:51:48.480607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.517036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:51:48.481202Z","caller":"traceutil/trace.go:171","msg":"trace[1720468149] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25082; }","duration":"292.207843ms","start":"2026-05-19T17:51:48.188978Z","end":"2026-05-19T17:51:48.481186Z","steps":["trace[1720468149] 'agreement among raft nodes before linearized reading' (duration: 291.544077ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:48.661171Z","caller":"traceutil/trace.go:171","msg":"trace[1741818318] linearizableReadLoop","detail":"{readStateIndex:25900; appliedIndex:25899; }","duration":"176.082058ms","start":"2026-05-19T17:51:48.485067Z","end":"2026-05-19T17:51:48.661149Z","steps":["trace[1741818318] 'read index received' (duration: 140.820854ms)","trace[1741818318] 'applied index is now lower than readState.Index' (duration: 35.260254ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:48.66138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.065135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:51:48.66143Z","caller":"traceutil/trace.go:171","msg":"trace[1028028739] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"176.57643ms","start":"2026-05-19T17:51:48.484843Z","end":"2026-05-19T17:51:48.66142Z","steps":["trace[1028028739] 'process raft request' (duration: 141.100571ms)","trace[1028028739] 'compare' (duration: 35.08638ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:48.66145Z","caller":"traceutil/trace.go:171","msg":"trace[2101982062] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25083; }","duration":"173.177008ms","start":"2026-05-19T17:51:48.488255Z","end":"2026-05-19T17:51:48.661432Z","steps":["trace[2101982062] 'agreement among raft nodes before linearized reading' (duration: 173.044034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:48.661496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.404775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18542"} {"level":"info","ts":"2026-05-19T17:51:48.66158Z","caller":"traceutil/trace.go:171","msg":"trace[831654727] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25083; }","duration":"176.509229ms","start":"2026-05-19T17:51:48.485051Z","end":"2026-05-19T17:51:48.66156Z","steps":["trace[831654727] 'agreement among raft nodes before linearized reading' (duration: 176.224342ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:50.894567Z","caller":"traceutil/trace.go:171","msg":"trace[1163490326] linearizableReadLoop","detail":"{readStateIndex:25916; appliedIndex:25915; }","duration":"102.921344ms","start":"2026-05-19T17:51:50.791613Z","end":"2026-05-19T17:51:50.894534Z","steps":["trace[1163490326] 'read index received' (duration: 102.481614ms)","trace[1163490326] 'applied index is now lower than readState.Index' (duration: 437.77µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:50.894694Z","caller":"traceutil/trace.go:171","msg":"trace[1454559853] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"226.427098ms","start":"2026-05-19T17:51:50.668217Z","end":"2026-05-19T17:51:50.894644Z","steps":["trace[1454559853] 'process raft request' (duration: 225.978017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:50.89485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.20193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:51:50.895024Z","caller":"traceutil/trace.go:171","msg":"trace[817218997] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25098; }","duration":"103.439797ms","start":"2026-05-19T17:51:50.791559Z","end":"2026-05-19T17:51:50.894999Z","steps":["trace[817218997] 'agreement among raft nodes before linearized reading' (duration: 103.142929ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:51.057995Z","caller":"traceutil/trace.go:171","msg":"trace[683457750] linearizableReadLoop","detail":"{readStateIndex:25917; appliedIndex:25916; }","duration":"100.864175ms","start":"2026-05-19T17:51:50.957114Z","end":"2026-05-19T17:51:51.057978Z","steps":["trace[683457750] 'read index received' (duration: 43.625447ms)","trace[683457750] 'applied index is now lower than readState.Index' (duration: 57.238078ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:51:51.058083Z","caller":"traceutil/trace.go:171","msg":"trace[1458137912] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"138.043917ms","start":"2026-05-19T17:51:50.920029Z","end":"2026-05-19T17:51:51.058072Z","steps":["trace[1458137912] 'process raft request' (duration: 80.770048ms)","trace[1458137912] 'compare' (duration: 57.098254ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:51.058171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.036329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T17:51:51.058248Z","caller":"traceutil/trace.go:171","msg":"trace[375762531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25099; }","duration":"101.168092ms","start":"2026-05-19T17:51:50.957059Z","end":"2026-05-19T17:51:51.058227Z","steps":["trace[375762531] 'agreement among raft nodes before linearized reading' (duration: 101.016949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:51.327799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.495684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-19T17:51:51.32782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.80965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-19T17:51:51.327862Z","caller":"traceutil/trace.go:171","msg":"trace[1955017238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25100; }","duration":"168.597746ms","start":"2026-05-19T17:51:51.159252Z","end":"2026-05-19T17:51:51.327849Z","steps":["trace[1955017238] 'range keys from in-memory index tree' (duration: 168.33107ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:51.327902Z","caller":"traceutil/trace.go:171","msg":"trace[651439509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25100; }","duration":"158.939013ms","start":"2026-05-19T17:51:51.168943Z","end":"2026-05-19T17:51:51.327882Z","steps":["trace[651439509] 'range keys from in-memory index tree' (duration: 158.566574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:51:51.327799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.520165ms","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-19T17:51:51.328001Z","caller":"traceutil/trace.go:171","msg":"trace[1404533787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25100; }","duration":"161.743211ms","start":"2026-05-19T17:51:51.166224Z","end":"2026-05-19T17:51:51.327967Z","steps":["trace[1404533787] 'range keys from in-memory index tree' (duration: 161.388002ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:51:51.328079Z","caller":"traceutil/trace.go:171","msg":"trace[925628834] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"147.757292ms","start":"2026-05-19T17:51:51.180309Z","end":"2026-05-19T17:51:51.328066Z","steps":["trace[925628834] 'process raft request' (duration: 63.814617ms)","trace[925628834] 'compare' (duration: 83.619057ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T17:51:51.328109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.455889ms","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-19T17:51:51.328229Z","caller":"traceutil/trace.go:171","msg":"trace[1189949705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25100; }","duration":"160.627073ms","start":"2026-05-19T17:51:51.167573Z","end":"2026-05-19T17:51:51.3282Z","steps":["trace[1189949705] 'range keys from in-memory index tree' (duration: 160.237334ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:07.10595Z","caller":"traceutil/trace.go:171","msg":"trace[2054884602] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"123.276078ms","start":"2026-05-19T17:52:06.982645Z","end":"2026-05-19T17:52:07.105922Z","steps":["trace[2054884602] 'process raft request' (duration: 123.095684ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:13.370289Z","caller":"traceutil/trace.go:171","msg":"trace[565951823] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"184.246785ms","start":"2026-05-19T17:52:13.186006Z","end":"2026-05-19T17:52:13.370252Z","steps":["trace[565951823] 'process raft request' (duration: 171.791033ms)","trace[565951823] 'compare' (duration: 11.906998ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:13.414781Z","caller":"traceutil/trace.go:171","msg":"trace[1278652059] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"148.376356ms","start":"2026-05-19T17:52:13.266374Z","end":"2026-05-19T17:52:13.414751Z","steps":["trace[1278652059] 'process raft request' (duration: 148.222162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:13.610134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.369384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-19T17:52:13.610162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.612582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-19T17:52:13.610186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.656018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T17:52:13.610218Z","caller":"traceutil/trace.go:171","msg":"trace[1783770616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25272; }","duration":"124.505217ms","start":"2026-05-19T17:52:13.485693Z","end":"2026-05-19T17:52:13.610198Z","steps":["trace[1783770616] 'range keys from in-memory index tree' (duration: 124.147309ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:13.610257Z","caller":"traceutil/trace.go:171","msg":"trace[517490068] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25272; }","duration":"134.762966ms","start":"2026-05-19T17:52:13.475475Z","end":"2026-05-19T17:52:13.610238Z","steps":["trace[517490068] 'range keys from in-memory index tree' (duration: 134.383667ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:13.610267Z","caller":"traceutil/trace.go:171","msg":"trace[1089611308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25272; }","duration":"135.814341ms","start":"2026-05-19T17:52:13.474432Z","end":"2026-05-19T17:52:13.610246Z","steps":["trace[1089611308] 'range keys from in-memory index tree' (duration: 135.434863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T17:52:13.610351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.516988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T17:52:13.610447Z","caller":"traceutil/trace.go:171","msg":"trace[575904322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25272; }","duration":"124.707223ms","start":"2026-05-19T17:52:13.485722Z","end":"2026-05-19T17:52:13.61043Z","steps":["trace[575904322] 'range keys from in-memory index tree' (duration: 124.122818ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:13.880881Z","caller":"traceutil/trace.go:171","msg":"trace[1438674708] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"263.24184ms","start":"2026-05-19T17:52:13.617595Z","end":"2026-05-19T17:52:13.880836Z","steps":["trace[1438674708] 'process raft request' (duration: 226.199973ms)","trace[1438674708] 'compare' (duration: 36.602847ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T17:52:13.881386Z","caller":"traceutil/trace.go:171","msg":"trace[591888593] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"260.866833ms","start":"2026-05-19T17:52:13.620506Z","end":"2026-05-19T17:52:13.881373Z","steps":["trace[591888593] 'process raft request' (duration: 260.438953ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:13.881409Z","caller":"traceutil/trace.go:171","msg":"trace[1508132072] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"263.687241ms","start":"2026-05-19T17:52:13.617691Z","end":"2026-05-19T17:52:13.881379Z","steps":["trace[1508132072] 'process raft request' (duration: 262.974494ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:13.881058Z","caller":"traceutil/trace.go:171","msg":"trace[2123986962] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"259.08186ms","start":"2026-05-19T17:52:13.62195Z","end":"2026-05-19T17:52:13.881032Z","steps":["trace[2123986962] 'process raft request' (duration: 259.031959ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:13.9138Z","caller":"traceutil/trace.go:171","msg":"trace[2067087082] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"222.994166ms","start":"2026-05-19T17:52:13.690779Z","end":"2026-05-19T17:52:13.913774Z","steps":["trace[2067087082] 'process raft request' (duration: 222.769ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:13.91415Z","caller":"traceutil/trace.go:171","msg":"trace[1826514993] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"211.547018ms","start":"2026-05-19T17:52:13.702559Z","end":"2026-05-19T17:52:13.914106Z","steps":["trace[1826514993] 'process raft request' (duration: 211.169928ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:13.91416Z","caller":"traceutil/trace.go:171","msg":"trace[1754789974] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"209.010897ms","start":"2026-05-19T17:52:13.705127Z","end":"2026-05-19T17:52:13.914138Z","steps":["trace[1754789974] 'process raft request' (duration: 208.852583ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:52:13.914303Z","caller":"traceutil/trace.go:171","msg":"trace[234707266] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"189.76761ms","start":"2026-05-19T17:52:13.724516Z","end":"2026-05-19T17:52:13.914284Z","steps":["trace[234707266] 'process raft request' (duration: 189.581656ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T17:53:18.244752Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068a5.wal"} {"level":"info","ts":"2026-05-19T17:53:27.212128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23191} {"level":"info","ts":"2026-05-19T17:53:27.619735Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23191,"took":"403.018488ms","hash":840558900,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":36118528,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-19T17:53:27.619862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":840558900,"revision":23191,"compact-revision":20014} {"level":"info","ts":"2026-05-19T17:58:27.218188Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26046} {"level":"info","ts":"2026-05-19T17:58:27.34207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26046,"took":"119.486974ms","hash":4171038624,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":36188160,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-19T17:58:27.342133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4171038624,"revision":26046,"compact-revision":23191} {"level":"info","ts":"2026-05-19T17:59:20.080303Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T17:59:20.085895Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-19T17:59:20.086028Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-19T18:03:27.232699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28690} {"level":"info","ts":"2026-05-19T18:03:27.367116Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28690,"took":"129.780344ms","hash":1765922395,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":30371840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T18:03:27.367195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1765922395,"revision":28690,"compact-revision":26046} {"level":"info","ts":"2026-05-19T18:08:27.240318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30979} {"level":"info","ts":"2026-05-19T18:08:27.355673Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30979,"took":"110.005917ms","hash":1250062492,"current-db-size-bytes":58818560,"current-db-size":"59 MB","current-db-size-in-use-bytes":31195136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T18:08:27.355926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1250062492,"revision":30979,"compact-revision":28690} {"level":"info","ts":"2026-05-19T18:13:27.246425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33276} {"level":"info","ts":"2026-05-19T18:13:27.403924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33276,"took":"153.14114ms","hash":3058631446,"current-db-size-bytes":59858944,"current-db-size":"60 MB","current-db-size-in-use-bytes":32079872,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T18:13:27.403996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3058631446,"revision":33276,"compact-revision":30979} {"level":"info","ts":"2026-05-19T18:18:27.256701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35575} {"level":"info","ts":"2026-05-19T18:18:27.421742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35575,"took":"162.006612ms","hash":3187266717,"current-db-size-bytes":61173760,"current-db-size":"61 MB","current-db-size-in-use-bytes":31072256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T18:18:27.421819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3187266717,"revision":35575,"compact-revision":33276} {"level":"info","ts":"2026-05-19T18:20:30.524417Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T18:20:30.52903Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-19T18:20:30.529841Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}