{"level":"warn","ts":"2026-10-04T12:47:13.236492Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T12:47:13.236619Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.91:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.91:2380","--initial-cluster=instance=https://162.253.55.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.91:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-04T12:47:13.236745Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T12:47:13.236767Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-10-04T12:47:13.236792Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-04T12:47:13.237927Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"]} {"level":"info","ts":"2026-10-04T12:47:13.238075Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.91:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-04T12:47:13.242694Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.265319ms"} {"level":"info","ts":"2026-10-04T12:47:13.256779Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a5b61ed9120d6b3","cluster-id":"9e0c1e8055c016ad"} {"level":"info","ts":"2026-10-04T12:47:13.256912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T12:47:13.256997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 0"} {"level":"info","ts":"2026-10-04T12:47:13.257115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a5b61ed9120d6b3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T12:47:13.257133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 1"} {"level":"info","ts":"2026-10-04T12:47:13.25718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"warn","ts":"2026-10-04T12:47:13.262363Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T12:47:13.265482Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T12:47:13.267113Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-04T12:47:13.269529Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a5b61ed9120d6b3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T12:47:13.272239Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-04T12:47:13.27245Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a5b61ed9120d6b3","initial-advertise-peer-urls":["https://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T12:47:13.272478Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T12:47:13.272649Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a5b61ed9120d6b3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T12:47:13.272815Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T12:47:13.273038Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T12:47:13.273061Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-04T12:47:13.273146Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-10-04T12:47:13.27337Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-10-04T12:47:13.27373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"info","ts":"2026-10-04T12:47:13.273813Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","added-peer-id":"8a5b61ed9120d6b3","added-peer-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-10-04T12:47:13.957759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T12:47:13.957867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T12:47:13.957897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgPreVoteResp from 8a5b61ed9120d6b3 at term 1"} {"level":"info","ts":"2026-10-04T12:47:13.957919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became candidate at term 2"} {"level":"info","ts":"2026-10-04T12:47:13.957927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgVoteResp from 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-10-04T12:47:13.95794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became leader at term 2"} {"level":"info","ts":"2026-10-04T12:47:13.957956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a5b61ed9120d6b3 elected leader 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-10-04T12:47:13.959161Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:47:13.960259Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T12:47:13.960254Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a5b61ed9120d6b3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.91:2379]}","request-path":"/0/members/8a5b61ed9120d6b3/attributes","cluster-id":"9e0c1e8055c016ad","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T12:47:13.960496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T12:47:13.96074Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T12:47:13.960776Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T12:47:13.961074Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:47:13.961197Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:47:13.961232Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T12:47:13.96416Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.91:2379"} {"level":"info","ts":"2026-10-04T12:47:13.964788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T12:48:01.06704Z","caller":"traceutil/trace.go:171","msg":"trace[134418092] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"259.392514ms","start":"2026-10-04T12:48:00.807566Z","end":"2026-10-04T12:48:01.066959Z","steps":["trace[134418092] 'process raft request' (duration: 258.443727ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:48:01.505072Z","caller":"traceutil/trace.go:171","msg":"trace[1561933820] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"381.700023ms","start":"2026-10-04T12:48:01.123338Z","end":"2026-10-04T12:48:01.505038Z","steps":["trace[1561933820] 'process raft request' (duration: 381.425067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:48:01.505826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:48:01.123299Z","time spent":"381.870985ms","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T12:48:01.549597Z","caller":"traceutil/trace.go:171","msg":"trace[1028691002] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"206.886977ms","start":"2026-10-04T12:48:01.342682Z","end":"2026-10-04T12:48:01.549569Z","steps":["trace[1028691002] 'read index received' (duration: 162.207378ms)","trace[1028691002] 'applied index is now lower than readState.Index' (duration: 44.678449ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:48:01.549681Z","caller":"traceutil/trace.go:171","msg":"trace[1860957946] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"395.274937ms","start":"2026-10-04T12:48:01.154362Z","end":"2026-10-04T12:48:01.549637Z","steps":["trace[1860957946] 'process raft request' (duration: 395.098814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:48:01.549844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.148942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-04T12:48:01.549901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T12:48:01.154328Z","time spent":"395.46041ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T12:48:01.549926Z","caller":"traceutil/trace.go:171","msg":"trace[619717646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"207.334275ms","start":"2026-10-04T12:48:01.342581Z","end":"2026-10-04T12:48:01.549915Z","steps":["trace[619717646] 'agreement among raft nodes before linearized reading' (duration: 207.086781ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:49:34.590919Z","caller":"traceutil/trace.go:171","msg":"trace[388701788] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"122.208526ms","start":"2026-10-04T12:49:34.468681Z","end":"2026-10-04T12:49:34.590889Z","steps":["trace[388701788] 'process raft request' (duration: 122.015132ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:49:34.610932Z","caller":"traceutil/trace.go:171","msg":"trace[93483697] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"142.160034ms","start":"2026-10-04T12:49:34.468714Z","end":"2026-10-04T12:49:34.610874Z","steps":["trace[93483697] 'process raft request' (duration: 142.003041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:50:24.665156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.033232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-04T12:50:24.665248Z","caller":"traceutil/trace.go:171","msg":"trace[1657720175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2195; }","duration":"159.191165ms","start":"2026-10-04T12:50:24.506035Z","end":"2026-10-04T12:50:24.665226Z","steps":["trace[1657720175] 'range keys from in-memory index tree' (duration: 158.750868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:51:14.005825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.902954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-04T12:51:14.005915Z","caller":"traceutil/trace.go:171","msg":"trace[652209124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2636; }","duration":"103.002736ms","start":"2026-10-04T12:51:13.902894Z","end":"2026-10-04T12:51:14.005897Z","steps":["trace[652209124] 'range keys from in-memory index tree' (duration: 102.7074ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:48.042506Z","caller":"traceutil/trace.go:171","msg":"trace[1438221334] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"133.526575ms","start":"2026-10-04T12:51:47.908953Z","end":"2026-10-04T12:51:48.04248Z","steps":["trace[1438221334] 'process raft request' (duration: 133.391972ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:48.077424Z","caller":"traceutil/trace.go:171","msg":"trace[823234392] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"166.644155ms","start":"2026-10-04T12:51:47.910754Z","end":"2026-10-04T12:51:48.077398Z","steps":["trace[823234392] 'process raft request' (duration: 166.477141ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:51:48.077705Z","caller":"traceutil/trace.go:171","msg":"trace[612657391] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"157.544952ms","start":"2026-10-04T12:51:47.920117Z","end":"2026-10-04T12:51:48.077662Z","steps":["trace[612657391] 'process raft request' (duration: 157.240956ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:56:38.143217Z","caller":"traceutil/trace.go:171","msg":"trace[1712457315] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"120.534387ms","start":"2026-10-04T12:56:38.022646Z","end":"2026-10-04T12:56:38.14318Z","steps":["trace[1712457315] 'process raft request' (duration: 94.432779ms)","trace[1712457315] 'compare' (duration: 25.920414ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:56:40.245032Z","caller":"traceutil/trace.go:171","msg":"trace[1492691788] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"219.33381ms","start":"2026-10-04T12:56:40.025654Z","end":"2026-10-04T12:56:40.244988Z","steps":["trace[1492691788] 'process raft request' (duration: 218.834081ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:56:45.283012Z","caller":"traceutil/trace.go:171","msg":"trace[934688700] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"193.706692ms","start":"2026-10-04T12:56:45.089266Z","end":"2026-10-04T12:56:45.282972Z","steps":["trace[934688700] 'process raft request' (duration: 193.439347ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:56:45.339763Z","caller":"traceutil/trace.go:171","msg":"trace[548176294] linearizableReadLoop","detail":"{readStateIndex:5544; appliedIndex:5542; }","duration":"153.180109ms","start":"2026-10-04T12:56:45.186555Z","end":"2026-10-04T12:56:45.339736Z","steps":["trace[548176294] 'read index received' (duration: 96.206783ms)","trace[548176294] 'applied index is now lower than readState.Index' (duration: 56.971946ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:56:45.339812Z","caller":"traceutil/trace.go:171","msg":"trace[1290214212] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"158.294286ms","start":"2026-10-04T12:56:45.181495Z","end":"2026-10-04T12:56:45.339789Z","steps":["trace[1290214212] 'process raft request' (duration: 158.078342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:56:45.340476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.889103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786885"} {"level":"info","ts":"2026-10-04T12:56:45.340521Z","caller":"traceutil/trace.go:171","msg":"trace[1327581173] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5341; }","duration":"153.991355ms","start":"2026-10-04T12:56:45.186518Z","end":"2026-10-04T12:56:45.340509Z","steps":["trace[1327581173] 'agreement among raft nodes before linearized reading' (duration: 153.331532ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:56:45.644852Z","caller":"traceutil/trace.go:171","msg":"trace[1361099977] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"127.072762ms","start":"2026-10-04T12:56:45.517745Z","end":"2026-10-04T12:56:45.644818Z","steps":["trace[1361099977] 'process raft request' (duration: 126.865188ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:56:45.774007Z","caller":"traceutil/trace.go:171","msg":"trace[1341204224] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"120.033748ms","start":"2026-10-04T12:56:45.653942Z","end":"2026-10-04T12:56:45.773976Z","steps":["trace[1341204224] 'process raft request' (duration: 119.847664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:56:59.177872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.500273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:56:59.177989Z","caller":"traceutil/trace.go:171","msg":"trace[87909764] linearizableReadLoop","detail":"{readStateIndex:5798; appliedIndex:5797; }","duration":"187.339881ms","start":"2026-10-04T12:56:58.990634Z","end":"2026-10-04T12:56:59.177974Z","steps":["trace[87909764] 'read index received' (duration: 31.43159ms)","trace[87909764] 'applied index is now lower than readState.Index' (duration: 155.906901ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:56:59.178037Z","caller":"traceutil/trace.go:171","msg":"trace[1918853821] transaction","detail":"{read_only:false; response_revision:5587; number_of_response:1; }","duration":"241.327649ms","start":"2026-10-04T12:56:58.93667Z","end":"2026-10-04T12:56:59.177997Z","steps":["trace[1918853821] 'process raft request' (duration: 85.502ms)","trace[1918853821] 'compare' (duration: 155.258478ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:56:59.178135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.489013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T12:56:59.178165Z","caller":"traceutil/trace.go:171","msg":"trace[1200606572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5587; }","duration":"187.587775ms","start":"2026-10-04T12:56:58.990567Z","end":"2026-10-04T12:56:59.178155Z","steps":["trace[1200606572] 'agreement among raft nodes before linearized reading' (duration: 187.483643ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:57:07.432804Z","caller":"traceutil/trace.go:171","msg":"trace[2128559788] transaction","detail":"{read_only:false; response_revision:5745; number_of_response:1; }","duration":"102.626585ms","start":"2026-10-04T12:57:07.330144Z","end":"2026-10-04T12:57:07.43277Z","steps":["trace[2128559788] 'process raft request' (duration: 39.094475ms)","trace[2128559788] 'compare' (duration: 63.384237ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:57:07.653409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.908352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T12:57:07.653506Z","caller":"traceutil/trace.go:171","msg":"trace[1259829445] linearizableReadLoop","detail":"{readStateIndex:5959; appliedIndex:5958; }","duration":"218.556684ms","start":"2026-10-04T12:57:07.434934Z","end":"2026-10-04T12:57:07.65349Z","steps":["trace[1259829445] 'read index received' (duration: 105.414239ms)","trace[1259829445] 'applied index is now lower than readState.Index' (duration: 113.141405ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:57:07.653584Z","caller":"traceutil/trace.go:171","msg":"trace[1755786359] transaction","detail":"{read_only:false; response_revision:5746; number_of_response:1; }","duration":"236.566068ms","start":"2026-10-04T12:57:07.417006Z","end":"2026-10-04T12:57:07.653572Z","steps":["trace[1755786359] 'process raft request' (duration: 123.411882ms)","trace[1755786359] 'compare' (duration: 112.595116ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T12:57:07.653811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.85839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-10-04T12:57:07.653883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.073793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-86728bb6-4c4a-4c40-a2c5-f5dadb1b3d72\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-04T12:57:07.653913Z","caller":"traceutil/trace.go:171","msg":"trace[1031266239] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5746; }","duration":"219.004073ms","start":"2026-10-04T12:57:07.434886Z","end":"2026-10-04T12:57:07.65389Z","steps":["trace[1031266239] 'agreement among raft nodes before linearized reading' (duration: 218.83602ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:57:07.653935Z","caller":"traceutil/trace.go:171","msg":"trace[1633694509] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-86728bb6-4c4a-4c40-a2c5-f5dadb1b3d72; range_end:; response_count:1; response_revision:5746; }","duration":"115.164915ms","start":"2026-10-04T12:57:07.538755Z","end":"2026-10-04T12:57:07.65392Z","steps":["trace[1633694509] 'agreement among raft nodes before linearized reading' (duration: 114.93814ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:57:07.891448Z","caller":"traceutil/trace.go:171","msg":"trace[2107754963] linearizableReadLoop","detail":"{readStateIndex:5960; appliedIndex:5959; }","duration":"198.045614ms","start":"2026-10-04T12:57:07.693374Z","end":"2026-10-04T12:57:07.891419Z","steps":["trace[2107754963] 'read index received' (duration: 197.221759ms)","trace[2107754963] 'applied index is now lower than readState.Index' (duration: 822.915µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T12:57:07.891528Z","caller":"traceutil/trace.go:171","msg":"trace[1653547930] transaction","detail":"{read_only:false; response_revision:5747; number_of_response:1; }","duration":"229.636736ms","start":"2026-10-04T12:57:07.661859Z","end":"2026-10-04T12:57:07.891496Z","steps":["trace[1653547930] 'process raft request' (duration: 228.74972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T12:57:07.891637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.249987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-10-04T12:57:07.891676Z","caller":"traceutil/trace.go:171","msg":"trace[1191364572] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5747; }","duration":"198.331659ms","start":"2026-10-04T12:57:07.693334Z","end":"2026-10-04T12:57:07.891666Z","steps":["trace[1191364572] 'agreement among raft nodes before linearized reading' (duration: 198.175776ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T12:57:13.996723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2984} {"level":"info","ts":"2026-10-04T12:57:14.069559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2984,"took":"71.429902ms","hash":2526815525,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":32124928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T12:57:14.069621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2526815525,"revision":2984,"compact-revision":-1} {"level":"info","ts":"2026-10-04T13:02:14.004836Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5884} {"level":"info","ts":"2026-10-04T13:02:14.101674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5884,"took":"94.698915ms","hash":2808100200,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":26710016,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-04T13:02:14.10175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2808100200,"revision":5884,"compact-revision":2984} {"level":"info","ts":"2026-10-04T13:03:14.169293Z","caller":"traceutil/trace.go:171","msg":"trace[1118488166] linearizableReadLoop","detail":"{readStateIndex:8891; appliedIndex:8890; }","duration":"240.867569ms","start":"2026-10-04T13:03:13.928393Z","end":"2026-10-04T13:03:14.16926Z","steps":["trace[1118488166] 'read index received' (duration: 240.697905ms)","trace[1118488166] 'applied index is now lower than readState.Index' (duration: 168.273µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:03:14.169408Z","caller":"traceutil/trace.go:171","msg":"trace[597281568] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"241.950428ms","start":"2026-10-04T13:03:13.92743Z","end":"2026-10-04T13:03:14.169381Z","steps":["trace[597281568] 'process raft request' (duration: 241.378887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:03:14.169633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.207385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-04T13:03:14.16973Z","caller":"traceutil/trace.go:171","msg":"trace[1509381230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8587; }","duration":"241.332357ms","start":"2026-10-04T13:03:13.928374Z","end":"2026-10-04T13:03:14.169706Z","steps":["trace[1509381230] 'agreement among raft nodes before linearized reading' (duration: 241.029541ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:03:14.283431Z","caller":"traceutil/trace.go:171","msg":"trace[1217927529] linearizableReadLoop","detail":"{readStateIndex:8892; appliedIndex:8891; }","duration":"114.003653ms","start":"2026-10-04T13:03:14.169399Z","end":"2026-10-04T13:03:14.283402Z","steps":["trace[1217927529] 'read index received' (duration: 113.249309ms)","trace[1217927529] 'applied index is now lower than readState.Index' (duration: 753.294µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:03:14.283507Z","caller":"traceutil/trace.go:171","msg":"trace[942627673] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"263.11461ms","start":"2026-10-04T13:03:14.020364Z","end":"2026-10-04T13:03:14.283479Z","steps":["trace[942627673] 'process raft request' (duration: 262.324344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:03:14.283642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.77696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-04T13:03:14.283682Z","caller":"traceutil/trace.go:171","msg":"trace[633192466] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8588; }","duration":"352.841161ms","start":"2026-10-04T13:03:13.930826Z","end":"2026-10-04T13:03:14.283668Z","steps":["trace[633192466] 'agreement among raft nodes before linearized reading' (duration: 352.671019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:03:14.283749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:03:13.93082Z","time spent":"352.919794ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-04T13:03:14.283733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.652169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T13:03:14.283745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.794258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T13:03:14.283799Z","caller":"traceutil/trace.go:171","msg":"trace[1662642145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8588; }","duration":"293.762891ms","start":"2026-10-04T13:03:13.990017Z","end":"2026-10-04T13:03:14.28378Z","steps":["trace[1662642145] 'agreement among raft nodes before linearized reading' (duration: 293.632929ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:03:14.283849Z","caller":"traceutil/trace.go:171","msg":"trace[1588688380] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8588; }","duration":"151.972621ms","start":"2026-10-04T13:03:14.131851Z","end":"2026-10-04T13:03:14.283824Z","steps":["trace[1588688380] 'agreement among raft nodes before linearized reading' (duration: 151.765987ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:03:14.284174Z","caller":"traceutil/trace.go:171","msg":"trace[1894952177] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"108.533218ms","start":"2026-10-04T13:03:14.175616Z","end":"2026-10-04T13:03:14.28415Z","steps":["trace[1894952177] 'process raft request' (duration: 108.409345ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:03:26.328615Z","caller":"traceutil/trace.go:171","msg":"trace[244347788] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"117.508558ms","start":"2026-10-04T13:03:26.211082Z","end":"2026-10-04T13:03:26.328591Z","steps":["trace[244347788] 'process raft request' (duration: 117.05471ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:03:27.058026Z","caller":"traceutil/trace.go:171","msg":"trace[530028588] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"277.744587ms","start":"2026-10-04T13:03:26.780228Z","end":"2026-10-04T13:03:27.057973Z","steps":["trace[530028588] 'process raft request' (duration: 277.534704ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:03:27.496553Z","caller":"traceutil/trace.go:171","msg":"trace[1284365833] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"105.731845ms","start":"2026-10-04T13:03:27.390795Z","end":"2026-10-04T13:03:27.496527Z","steps":["trace[1284365833] 'process raft request' (duration: 74.022653ms)","trace[1284365833] 'compare' (duration: 31.559909ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:03:55.877348Z","caller":"traceutil/trace.go:171","msg":"trace[323780712] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"274.051937ms","start":"2026-10-04T13:03:55.603268Z","end":"2026-10-04T13:03:55.87732Z","steps":["trace[323780712] 'process raft request' (duration: 273.882083ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:03:56.073644Z","caller":"traceutil/trace.go:171","msg":"trace[876308888] transaction","detail":"{read_only:false; response_revision:8861; number_of_response:1; }","duration":"115.996111ms","start":"2026-10-04T13:03:55.957604Z","end":"2026-10-04T13:03:56.0736Z","steps":["trace[876308888] 'process raft request' (duration: 89.705672ms)","trace[876308888] 'compare' (duration: 26.129015ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:04:10.52324Z","caller":"traceutil/trace.go:171","msg":"trace[160790316] transaction","detail":"{read_only:false; response_revision:8977; number_of_response:1; }","duration":"100.873953ms","start":"2026-10-04T13:04:10.422311Z","end":"2026-10-04T13:04:10.523185Z","steps":["trace[160790316] 'process raft request' (duration: 100.67546ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:04:10.665942Z","caller":"traceutil/trace.go:171","msg":"trace[967772220] transaction","detail":"{read_only:false; response_revision:8979; number_of_response:1; }","duration":"124.40808ms","start":"2026-10-04T13:04:10.54149Z","end":"2026-10-04T13:04:10.665898Z","steps":["trace[967772220] 'process raft request' (duration: 101.180929ms)","trace[967772220] 'compare' (duration: 22.985237ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:04:10.956767Z","caller":"traceutil/trace.go:171","msg":"trace[128402127] transaction","detail":"{read_only:false; response_revision:8981; number_of_response:1; }","duration":"153.178986ms","start":"2026-10-04T13:04:10.80356Z","end":"2026-10-04T13:04:10.956739Z","steps":["trace[128402127] 'process raft request' (duration: 152.976152ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:04:21.578551Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024ad.wal"} {"level":"info","ts":"2026-10-04T13:05:25.980019Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T13:05:25.987853Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-04T13:05:25.987945Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-04T13:05:29.818728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.429683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-04T13:05:29.818823Z","caller":"traceutil/trace.go:171","msg":"trace[675073360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9681; }","duration":"147.559165ms","start":"2026-10-04T13:05:29.67124Z","end":"2026-10-04T13:05:29.818799Z","steps":["trace[675073360] 'range keys from in-memory index tree' (duration: 147.27112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:05:29.818891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.519059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T13:05:29.818988Z","caller":"traceutil/trace.go:171","msg":"trace[593127591] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9681; }","duration":"153.664571ms","start":"2026-10-04T13:05:29.665306Z","end":"2026-10-04T13:05:29.818971Z","steps":["trace[593127591] 'range keys from in-memory index tree' (duration: 153.445876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:05:30.154745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.759284ms","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-10-04T13:05:30.154833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.087553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:29 size:261385"} {"level":"info","ts":"2026-10-04T13:05:30.154911Z","caller":"traceutil/trace.go:171","msg":"trace[202563466] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:29; response_revision:9682; }","duration":"236.188415ms","start":"2026-10-04T13:05:29.918702Z","end":"2026-10-04T13:05:30.15489Z","steps":["trace[202563466] 'range keys from in-memory index tree' (duration: 235.575933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:05:30.154801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.606584ms","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-10-04T13:05:30.155057Z","caller":"traceutil/trace.go:171","msg":"trace[436267296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9682; }","duration":"158.865759ms","start":"2026-10-04T13:05:29.996165Z","end":"2026-10-04T13:05:30.15503Z","steps":["trace[436267296] 'range keys from in-memory index tree' (duration: 158.441401ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:05:30.155089Z","caller":"traceutil/trace.go:171","msg":"trace[872536358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9682; }","duration":"161.917177ms","start":"2026-10-04T13:05:29.992956Z","end":"2026-10-04T13:05:30.154873Z","steps":["trace[872536358] 'range keys from in-memory index tree' (duration: 161.599341ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:05:30.28082Z","caller":"traceutil/trace.go:171","msg":"trace[1680798733] transaction","detail":"{read_only:false; response_revision:9684; number_of_response:1; }","duration":"120.306308ms","start":"2026-10-04T13:05:30.160481Z","end":"2026-10-04T13:05:30.280787Z","steps":["trace[1680798733] 'process raft request' (duration: 105.002168ms)","trace[1680798733] 'compare' (duration: 15.184828ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:07:14.012246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8041} {"level":"info","ts":"2026-10-04T13:07:14.079387Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8041,"took":"64.975321ms","hash":185229801,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":29032448,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-04T13:07:14.079456Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":185229801,"revision":8041,"compact-revision":5884} {"level":"info","ts":"2026-10-04T13:09:13.554736Z","caller":"traceutil/trace.go:171","msg":"trace[1252240420] linearizableReadLoop","detail":"{readStateIndex:12024; appliedIndex:12023; }","duration":"110.685136ms","start":"2026-10-04T13:09:13.444023Z","end":"2026-10-04T13:09:13.554708Z","steps":["trace[1252240420] 'read index received' (duration: 110.481182ms)","trace[1252240420] 'applied index is now lower than readState.Index' (duration: 202.764µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:09:13.554786Z","caller":"traceutil/trace.go:171","msg":"trace[46875671] transaction","detail":"{read_only:false; response_revision:11625; number_of_response:1; }","duration":"120.097284ms","start":"2026-10-04T13:09:13.434656Z","end":"2026-10-04T13:09:13.554753Z","steps":["trace[46875671] 'process raft request' (duration: 119.870099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:09:13.555202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.152845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-04T13:09:13.555278Z","caller":"traceutil/trace.go:171","msg":"trace[1683593225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11625; }","duration":"111.286038ms","start":"2026-10-04T13:09:13.443979Z","end":"2026-10-04T13:09:13.555265Z","steps":["trace[1683593225] 'agreement among raft nodes before linearized reading' (duration: 110.839089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:09:13.798396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.652547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T13:09:13.798567Z","caller":"traceutil/trace.go:171","msg":"trace[1429640435] transaction","detail":"{read_only:false; response_revision:11627; number_of_response:1; }","duration":"238.773162ms","start":"2026-10-04T13:09:13.559777Z","end":"2026-10-04T13:09:13.79855Z","steps":["trace[1429640435] 'process raft request' (duration: 238.713851ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:09:13.798705Z","caller":"traceutil/trace.go:171","msg":"trace[769514890] transaction","detail":"{read_only:false; response_revision:11626; number_of_response:1; }","duration":"319.44646ms","start":"2026-10-04T13:09:13.479194Z","end":"2026-10-04T13:09:13.79864Z","steps":["trace[769514890] 'process raft request' (duration: 191.468856ms)","trace[769514890] 'compare' (duration: 127.495265ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:09:13.798896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:09:13.479168Z","time spent":"319.627074ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:12:14.019703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10510} {"level":"info","ts":"2026-10-04T13:12:14.111812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10510,"took":"89.354112ms","hash":72980081,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":28131328,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-04T13:12:14.11189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":72980081,"revision":10510,"compact-revision":8041} {"level":"info","ts":"2026-10-04T13:15:26.735585Z","caller":"traceutil/trace.go:171","msg":"trace[1733875621] transaction","detail":"{read_only:false; response_revision:14141; number_of_response:1; }","duration":"145.811901ms","start":"2026-10-04T13:15:26.589738Z","end":"2026-10-04T13:15:26.73555Z","steps":["trace[1733875621] 'process raft request' (duration: 145.634348ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:15:43.154282Z","caller":"traceutil/trace.go:171","msg":"trace[1856232429] linearizableReadLoop","detail":"{readStateIndex:14735; appliedIndex:14734; }","duration":"108.094876ms","start":"2026-10-04T13:15:43.046143Z","end":"2026-10-04T13:15:43.154238Z","steps":["trace[1856232429] 'read index received' (duration: 107.871341ms)","trace[1856232429] 'applied index is now lower than readState.Index' (duration: 222.335µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:15:43.154345Z","caller":"traceutil/trace.go:171","msg":"trace[1685379817] transaction","detail":"{read_only:false; response_revision:14243; number_of_response:1; }","duration":"200.314582ms","start":"2026-10-04T13:15:42.953987Z","end":"2026-10-04T13:15:43.154302Z","steps":["trace[1685379817] 'process raft request' (duration: 200.043857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:15:43.154491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.338701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-jzpsl\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-10-04T13:15:43.154532Z","caller":"traceutil/trace.go:171","msg":"trace[1463266936] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-jzpsl; range_end:; response_count:1; response_revision:14243; }","duration":"108.435943ms","start":"2026-10-04T13:15:43.046086Z","end":"2026-10-04T13:15:43.154522Z","steps":["trace[1463266936] 'agreement among raft nodes before linearized reading' (duration: 108.27819ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:15:43.741541Z","caller":"traceutil/trace.go:171","msg":"trace[2043137398] transaction","detail":"{read_only:false; response_revision:14246; number_of_response:1; }","duration":"142.430487ms","start":"2026-10-04T13:15:43.599079Z","end":"2026-10-04T13:15:43.74151Z","steps":["trace[2043137398] 'process raft request' (duration: 141.960378ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:15:53.482854Z","caller":"traceutil/trace.go:171","msg":"trace[999382585] transaction","detail":"{read_only:false; response_revision:14409; number_of_response:1; }","duration":"157.015493ms","start":"2026-10-04T13:15:53.325816Z","end":"2026-10-04T13:15:53.482832Z","steps":["trace[999382585] 'process raft request' (duration: 156.846359ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:15:53.69643Z","caller":"traceutil/trace.go:171","msg":"trace[386285520] transaction","detail":"{read_only:false; response_revision:14411; number_of_response:1; }","duration":"200.238611ms","start":"2026-10-04T13:15:53.49616Z","end":"2026-10-04T13:15:53.696398Z","steps":["trace[386285520] 'process raft request' (duration: 186.333128ms)","trace[386285520] 'compare' (duration: 13.73672ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:15:53.699067Z","caller":"traceutil/trace.go:171","msg":"trace[267589371] transaction","detail":"{read_only:false; response_revision:14412; number_of_response:1; }","duration":"194.86903ms","start":"2026-10-04T13:15:53.504173Z","end":"2026-10-04T13:15:53.699042Z","steps":["trace[267589371] 'process raft request' (duration: 194.719577ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:17:14.027002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12825} {"level":"info","ts":"2026-10-04T13:17:14.113363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12825,"took":"83.188756ms","hash":791190179,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":30945280,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T13:17:14.113445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":791190179,"revision":12825,"compact-revision":10510} {"level":"warn","ts":"2026-10-04T13:17:34.594335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.111777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T13:17:34.594426Z","caller":"traceutil/trace.go:171","msg":"trace[593819010] linearizableReadLoop","detail":"{readStateIndex:16034; appliedIndex:16033; }","duration":"137.737298ms","start":"2026-10-04T13:17:34.456673Z","end":"2026-10-04T13:17:34.59441Z","steps":["trace[593819010] 'read index received' (duration: 10.399957ms)","trace[593819010] 'applied index is now lower than readState.Index' (duration: 127.336411ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:17:34.594523Z","caller":"traceutil/trace.go:171","msg":"trace[259551249] transaction","detail":"{read_only:false; response_revision:15503; number_of_response:1; }","duration":"140.01441ms","start":"2026-10-04T13:17:34.454479Z","end":"2026-10-04T13:17:34.594493Z","steps":["trace[259551249] 'process raft request' (duration: 12.644799ms)","trace[259551249] 'compare' (duration: 127.023065ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:17:34.595083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.559805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wj5k2\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-10-04T13:17:34.595128Z","caller":"traceutil/trace.go:171","msg":"trace[1949089174] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wj5k2; range_end:; response_count:1; response_revision:15503; }","duration":"126.633126ms","start":"2026-10-04T13:17:34.468483Z","end":"2026-10-04T13:17:34.595116Z","steps":["trace[1949089174] 'agreement among raft nodes before linearized reading' (duration: 126.469944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:17:34.595209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.528722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769546"} {"level":"info","ts":"2026-10-04T13:17:34.595253Z","caller":"traceutil/trace.go:171","msg":"trace[1787778432] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15503; }","duration":"138.590234ms","start":"2026-10-04T13:17:34.456651Z","end":"2026-10-04T13:17:34.595241Z","steps":["trace[1787778432] 'agreement among raft nodes before linearized reading' (duration: 137.83014ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:17:35.092079Z","caller":"traceutil/trace.go:171","msg":"trace[1570250411] transaction","detail":"{read_only:false; response_revision:15505; number_of_response:1; }","duration":"119.972471ms","start":"2026-10-04T13:17:34.972079Z","end":"2026-10-04T13:17:35.092051Z","steps":["trace[1570250411] 'process raft request' (duration: 119.782518ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:22:14.03477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15398} {"level":"info","ts":"2026-10-04T13:22:14.138141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15398,"took":"99.633567ms","hash":2619864742,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":32104448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T13:22:14.138231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2619864742,"revision":15398,"compact-revision":12825} {"level":"warn","ts":"2026-10-04T13:23:10.397605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.734916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-478qw\" ","response":"range_response_count:1 size:15743"} {"level":"info","ts":"2026-10-04T13:23:10.397698Z","caller":"traceutil/trace.go:171","msg":"trace[498225788] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-478qw; range_end:; response_count:1; response_revision:18026; }","duration":"111.89329ms","start":"2026-10-04T13:23:10.285783Z","end":"2026-10-04T13:23:10.397677Z","steps":["trace[498225788] 'range keys from in-memory index tree' (duration: 111.565513ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:23:10.398864Z","caller":"traceutil/trace.go:171","msg":"trace[689212162] transaction","detail":"{read_only:false; response_revision:18027; number_of_response:1; }","duration":"111.218307ms","start":"2026-10-04T13:23:10.287627Z","end":"2026-10-04T13:23:10.398845Z","steps":["trace[689212162] 'process raft request' (duration: 111.040544ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:23:16.749124Z","caller":"traceutil/trace.go:171","msg":"trace[2075109846] transaction","detail":"{read_only:false; response_revision:18067; number_of_response:1; }","duration":"318.065044ms","start":"2026-10-04T13:23:16.431029Z","end":"2026-10-04T13:23:16.749094Z","steps":["trace[2075109846] 'process raft request' (duration: 317.874401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:23:16.749324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:23:16.431015Z","time spent":"318.190226ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:23:16.940763Z","caller":"traceutil/trace.go:171","msg":"trace[1658325477] linearizableReadLoop","detail":"{readStateIndex:18683; appliedIndex:18681; }","duration":"489.140155ms","start":"2026-10-04T13:23:16.451597Z","end":"2026-10-04T13:23:16.940737Z","steps":["trace[1658325477] 'read index received' (duration: 297.416253ms)","trace[1658325477] 'applied index is now lower than readState.Index' (duration: 191.722632ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:23:16.940777Z","caller":"traceutil/trace.go:171","msg":"trace[2097082250] transaction","detail":"{read_only:false; response_revision:18068; number_of_response:1; }","duration":"502.332396ms","start":"2026-10-04T13:23:16.43841Z","end":"2026-10-04T13:23:16.940742Z","steps":["trace[2097082250] 'process raft request' (duration: 473.188374ms)","trace[2097082250] 'compare' (duration: 28.925518ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:23:16.940858Z","caller":"traceutil/trace.go:171","msg":"trace[384053063] transaction","detail":"{read_only:false; response_revision:18069; number_of_response:1; }","duration":"402.553974ms","start":"2026-10-04T13:23:16.53826Z","end":"2026-10-04T13:23:16.940814Z","steps":["trace[384053063] 'process raft request' (duration: 402.416352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:23:16.940959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.476113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T13:23:16.940959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:23:16.438368Z","time spent":"502.494108ms","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20867,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:23:16.941012Z","caller":"traceutil/trace.go:171","msg":"trace[1443839177] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:18069; }","duration":"374.581685ms","start":"2026-10-04T13:23:16.566413Z","end":"2026-10-04T13:23:16.940995Z","steps":["trace[1443839177] 'agreement among raft nodes before linearized reading' (duration: 374.492683ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:23:16.941038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:23:16.538233Z","time spent":"402.710658ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:23:16.941051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:23:16.566391Z","time spent":"374.651127ms","remote":"127.0.0.1:50322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-10-04T13:23:16.941059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.437731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-04T13:23:16.941122Z","caller":"traceutil/trace.go:171","msg":"trace[691181762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18069; }","duration":"489.535753ms","start":"2026-10-04T13:23:16.45157Z","end":"2026-10-04T13:23:16.941105Z","steps":["trace[691181762] 'agreement among raft nodes before linearized reading' (duration: 489.316749ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:23:16.941164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:23:16.451557Z","time spent":"489.598884ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-04T13:23:16.941463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.1973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T13:23:16.94157Z","caller":"traceutil/trace.go:171","msg":"trace[125847992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18069; }","duration":"136.365073ms","start":"2026-10-04T13:23:16.80518Z","end":"2026-10-04T13:23:16.941545Z","steps":["trace[125847992] 'agreement among raft nodes before linearized reading' (duration: 135.892044ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:25:44.858098Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T13:25:44.863832Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-04T13:25:44.863916Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-04T13:27:14.041109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17574} {"level":"info","ts":"2026-10-04T13:27:14.14656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17574,"took":"102.148755ms","hash":1154794575,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":34627584,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-04T13:27:14.146652Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1154794575,"revision":17574,"compact-revision":15398} {"level":"info","ts":"2026-10-04T13:28:02.237357Z","caller":"traceutil/trace.go:171","msg":"trace[491966676] transaction","detail":"{read_only:false; response_revision:20346; number_of_response:1; }","duration":"196.219936ms","start":"2026-10-04T13:28:02.04111Z","end":"2026-10-04T13:28:02.23733Z","steps":["trace[491966676] 'process raft request' (duration: 196.044822ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:28:23.313335Z","caller":"traceutil/trace.go:171","msg":"trace[657602964] linearizableReadLoop","detail":"{readStateIndex:21145; appliedIndex:21144; }","duration":"337.712526ms","start":"2026-10-04T13:28:22.975597Z","end":"2026-10-04T13:28:23.31331Z","steps":["trace[657602964] 'read index received' (duration: 337.590404ms)","trace[657602964] 'applied index is now lower than readState.Index' (duration: 120.772µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:28:23.31344Z","caller":"traceutil/trace.go:171","msg":"trace[67969337] transaction","detail":"{read_only:false; response_revision:20452; number_of_response:1; }","duration":"346.242818ms","start":"2026-10-04T13:28:22.967184Z","end":"2026-10-04T13:28:23.313427Z","steps":["trace[67969337] 'process raft request' (duration: 345.955542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:28:23.313544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:28:22.967156Z","time spent":"346.309559ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:28:23.313807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.189365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-04T13:28:23.313937Z","caller":"traceutil/trace.go:171","msg":"trace[835649278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20452; }","duration":"338.336758ms","start":"2026-10-04T13:28:22.97554Z","end":"2026-10-04T13:28:23.313877Z","steps":["trace[835649278] 'agreement among raft nodes before linearized reading' (duration: 337.974361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:28:23.314032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:28:22.975515Z","time spent":"338.467781ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T13:28:23.586496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.245647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T13:28:23.586666Z","caller":"traceutil/trace.go:171","msg":"trace[577998379] transaction","detail":"{read_only:false; response_revision:20454; number_of_response:1; }","duration":"267.728371ms","start":"2026-10-04T13:28:23.318919Z","end":"2026-10-04T13:28:23.586647Z","steps":["trace[577998379] 'process raft request' (duration: 267.67596ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:28:23.586878Z","caller":"traceutil/trace.go:171","msg":"trace[582792186] linearizableReadLoop","detail":"{readStateIndex:21146; appliedIndex:21145; }","duration":"273.46007ms","start":"2026-10-04T13:28:23.313401Z","end":"2026-10-04T13:28:23.586861Z","steps":["trace[582792186] 'read index received' (duration: 115.0568ms)","trace[582792186] 'applied index is now lower than readState.Index' (duration: 158.40252ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:28:23.586849Z","caller":"traceutil/trace.go:171","msg":"trace[1539081826] transaction","detail":"{read_only:false; response_revision:20453; number_of_response:1; }","duration":"577.01039ms","start":"2026-10-04T13:28:23.009794Z","end":"2026-10-04T13:28:23.586804Z","steps":["trace[1539081826] 'process raft request' (duration: 418.369065ms)","trace[1539081826] 'compare' (duration: 158.127446ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:28:23.586965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.62845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:28:23.586989Z","caller":"traceutil/trace.go:171","msg":"trace[1342459997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20454; }","duration":"597.697171ms","start":"2026-10-04T13:28:22.989284Z","end":"2026-10-04T13:28:23.586981Z","steps":["trace[1342459997] 'agreement among raft nodes before linearized reading' (duration: 597.62728ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:28:23.58702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:28:22.989263Z","time spent":"597.751083ms","remote":"127.0.0.1:54276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T13:28:23.587053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:28:23.009777Z","time spent":"577.176493ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:28:23.587353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.145187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-04T13:28:23.587482Z","caller":"traceutil/trace.go:171","msg":"trace[2064719760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20454; }","duration":"535.32178ms","start":"2026-10-04T13:28:23.052134Z","end":"2026-10-04T13:28:23.587456Z","steps":["trace[2064719760] 'agreement among raft nodes before linearized reading' (duration: 534.904882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:28:23.587573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:28:23.052107Z","time spent":"535.453062ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-04T13:28:24.54376Z","caller":"traceutil/trace.go:171","msg":"trace[1030385485] linearizableReadLoop","detail":"{readStateIndex:21150; appliedIndex:21149; }","duration":"143.927827ms","start":"2026-10-04T13:28:24.399806Z","end":"2026-10-04T13:28:24.543734Z","steps":["trace[1030385485] 'read index received' (duration: 143.736493ms)","trace[1030385485] 'applied index is now lower than readState.Index' (duration: 190.114µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:28:24.543837Z","caller":"traceutil/trace.go:171","msg":"trace[1288119025] transaction","detail":"{read_only:false; response_revision:20457; number_of_response:1; }","duration":"193.646037ms","start":"2026-10-04T13:28:24.350154Z","end":"2026-10-04T13:28:24.5438Z","steps":["trace[1288119025] 'process raft request' (duration: 193.403863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:28:24.544323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.575994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-10-04T13:28:24.544294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.419245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T13:28:24.544446Z","caller":"traceutil/trace.go:171","msg":"trace[1138907293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20457; }","duration":"144.67926ms","start":"2026-10-04T13:28:24.399732Z","end":"2026-10-04T13:28:24.544412Z","steps":["trace[1138907293] 'agreement among raft nodes before linearized reading' (duration: 144.16118ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:28:24.544371Z","caller":"traceutil/trace.go:171","msg":"trace[1544831547] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:20457; }","duration":"138.654046ms","start":"2026-10-04T13:28:24.405705Z","end":"2026-10-04T13:28:24.544359Z","steps":["trace[1544831547] 'agreement among raft nodes before linearized reading' (duration: 138.248988ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:28:24.664813Z","caller":"traceutil/trace.go:171","msg":"trace[1655241624] transaction","detail":"{read_only:false; response_revision:20458; number_of_response:1; }","duration":"115.857334ms","start":"2026-10-04T13:28:24.548888Z","end":"2026-10-04T13:28:24.664745Z","steps":["trace[1655241624] 'process raft request' (duration: 76.513279ms)","trace[1655241624] 'compare' (duration: 39.136981ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:32:09.337806Z","caller":"traceutil/trace.go:171","msg":"trace[246944851] transaction","detail":"{read_only:false; response_revision:22868; number_of_response:1; }","duration":"123.876678ms","start":"2026-10-04T13:32:09.213902Z","end":"2026-10-04T13:32:09.337779Z","steps":["trace[246944851] 'process raft request' (duration: 123.674134ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:32:09.655575Z","caller":"traceutil/trace.go:171","msg":"trace[115989086] transaction","detail":"{read_only:false; response_revision:22869; number_of_response:1; }","duration":"254.810219ms","start":"2026-10-04T13:32:09.40074Z","end":"2026-10-04T13:32:09.65555Z","steps":["trace[115989086] 'process raft request' (duration: 244.231859ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:32:14.048617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20087} {"level":"info","ts":"2026-10-04T13:32:14.166053Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20087,"took":"113.565201ms","hash":16309467,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":42053632,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-04T13:32:14.166113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":16309467,"revision":20087,"compact-revision":17574} {"level":"info","ts":"2026-10-04T13:33:22.365601Z","caller":"traceutil/trace.go:171","msg":"trace[2101076441] transaction","detail":"{read_only:false; response_revision:23675; number_of_response:1; }","duration":"119.320461ms","start":"2026-10-04T13:33:22.246243Z","end":"2026-10-04T13:33:22.365563Z","steps":["trace[2101076441] 'process raft request' (duration: 43.708328ms)","trace[2101076441] 'compare' (duration: 75.46578ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:06.778056Z","caller":"traceutil/trace.go:171","msg":"trace[1891743290] linearizableReadLoop","detail":"{readStateIndex:25534; appliedIndex:25533; }","duration":"137.472855ms","start":"2026-10-04T13:35:06.640555Z","end":"2026-10-04T13:35:06.778028Z","steps":["trace[1891743290] 'read index received' (duration: 137.241831ms)","trace[1891743290] 'applied index is now lower than readState.Index' (duration: 230.104µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:06.778297Z","caller":"traceutil/trace.go:171","msg":"trace[903443252] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"206.075956ms","start":"2026-10-04T13:35:06.572084Z","end":"2026-10-04T13:35:06.77816Z","steps":["trace[903443252] 'process raft request' (duration: 205.746899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:06.778481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.882843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-04T13:35:06.778656Z","caller":"traceutil/trace.go:171","msg":"trace[2092326205] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24726; }","duration":"138.129907ms","start":"2026-10-04T13:35:06.640504Z","end":"2026-10-04T13:35:06.778634Z","steps":["trace[2092326205] 'agreement among raft nodes before linearized reading' (duration: 137.73173ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:06.897057Z","caller":"traceutil/trace.go:171","msg":"trace[1511813190] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"113.884967ms","start":"2026-10-04T13:35:06.783084Z","end":"2026-10-04T13:35:06.896969Z","steps":["trace[1511813190] 'process raft request' (duration: 113.51985ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:06.897124Z","caller":"traceutil/trace.go:171","msg":"trace[681504536] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"111.89049ms","start":"2026-10-04T13:35:06.785202Z","end":"2026-10-04T13:35:06.897093Z","steps":["trace[681504536] 'process raft request' (duration: 111.706196ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:06.897397Z","caller":"traceutil/trace.go:171","msg":"trace[672003035] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"111.082805ms","start":"2026-10-04T13:35:06.78627Z","end":"2026-10-04T13:35:06.897353Z","steps":["trace[672003035] 'process raft request' (duration: 110.697117ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:06.897491Z","caller":"traceutil/trace.go:171","msg":"trace[1763344468] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"112.670695ms","start":"2026-10-04T13:35:06.784787Z","end":"2026-10-04T13:35:06.897458Z","steps":["trace[1763344468] 'process raft request' (duration: 112.007452ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:06.897805Z","caller":"traceutil/trace.go:171","msg":"trace[1539845747] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"105.281126ms","start":"2026-10-04T13:35:06.792503Z","end":"2026-10-04T13:35:06.897784Z","steps":["trace[1539845747] 'process raft request' (duration: 105.112613ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:06.89792Z","caller":"traceutil/trace.go:171","msg":"trace[4903697] linearizableReadLoop","detail":"{readStateIndex:25536; appliedIndex:25535; }","duration":"112.997611ms","start":"2026-10-04T13:35:06.784762Z","end":"2026-10-04T13:35:06.89776Z","steps":["trace[4903697] 'read index received' (duration: 20.745403ms)","trace[4903697] 'applied index is now lower than readState.Index' (duration: 92.250848ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:06.898021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.239746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-04T13:35:06.898103Z","caller":"traceutil/trace.go:171","msg":"trace[679092796] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24732; }","duration":"113.374129ms","start":"2026-10-04T13:35:06.78471Z","end":"2026-10-04T13:35:06.898084Z","steps":["trace[679092796] 'agreement among raft nodes before linearized reading' (duration: 113.179225ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:07.045031Z","caller":"traceutil/trace.go:171","msg":"trace[1861553788] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"141.317687ms","start":"2026-10-04T13:35:06.903687Z","end":"2026-10-04T13:35:07.045004Z","steps":["trace[1861553788] 'process raft request' (duration: 141.118614ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:07.186019Z","caller":"traceutil/trace.go:171","msg":"trace[1829357612] linearizableReadLoop","detail":"{readStateIndex:25542; appliedIndex:25540; }","duration":"206.784238ms","start":"2026-10-04T13:35:06.979201Z","end":"2026-10-04T13:35:07.185985Z","steps":["trace[1829357612] 'read index received' (duration: 65.717755ms)","trace[1829357612] 'applied index is now lower than readState.Index' (duration: 141.064953ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:07.186091Z","caller":"traceutil/trace.go:171","msg":"trace[548812615] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"275.606934ms","start":"2026-10-04T13:35:06.91045Z","end":"2026-10-04T13:35:07.186057Z","steps":["trace[548812615] 'process raft request' (duration: 275.296088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:07.186359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.130335ms","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-10-04T13:35:07.186494Z","caller":"traceutil/trace.go:171","msg":"trace[396269294] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24734; }","duration":"207.341669ms","start":"2026-10-04T13:35:06.979124Z","end":"2026-10-04T13:35:07.186465Z","steps":["trace[396269294] 'agreement among raft nodes before linearized reading' (duration: 207.028763ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:07.35344Z","caller":"traceutil/trace.go:171","msg":"trace[1527372661] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"289.73496ms","start":"2026-10-04T13:35:07.063683Z","end":"2026-10-04T13:35:07.353418Z","steps":["trace[1527372661] 'process raft request' (duration: 289.578757ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:07.353522Z","caller":"traceutil/trace.go:171","msg":"trace[931192069] linearizableReadLoop","detail":"{readStateIndex:25543; appliedIndex:25543; }","duration":"167.390131ms","start":"2026-10-04T13:35:07.186106Z","end":"2026-10-04T13:35:07.353496Z","steps":["trace[931192069] 'read index received' (duration: 167.379761ms)","trace[931192069] 'applied index is now lower than readState.Index' (duration: 8.73µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:07.353788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.315064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:07.353875Z","caller":"traceutil/trace.go:171","msg":"trace[633237172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24735; }","duration":"363.470027ms","start":"2026-10-04T13:35:06.990381Z","end":"2026-10-04T13:35:07.353851Z","steps":["trace[633237172] 'agreement among raft nodes before linearized reading' (duration: 363.295604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:07.353916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:06.990346Z","time spent":"363.562679ms","remote":"127.0.0.1:54270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T13:35:07.35485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.052859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:07.354903Z","caller":"traceutil/trace.go:171","msg":"trace[72862144] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24736; }","duration":"164.156101ms","start":"2026-10-04T13:35:07.190734Z","end":"2026-10-04T13:35:07.35489Z","steps":["trace[72862144] 'agreement among raft nodes before linearized reading' (duration: 164.041559ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:07.355014Z","caller":"traceutil/trace.go:171","msg":"trace[499522705] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"108.915405ms","start":"2026-10-04T13:35:07.246051Z","end":"2026-10-04T13:35:07.354967Z","steps":["trace[499522705] 'process raft request' (duration: 108.607379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:09.25143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.613841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T13:35:09.251507Z","caller":"traceutil/trace.go:171","msg":"trace[1053938587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24750; }","duration":"201.758833ms","start":"2026-10-04T13:35:09.049729Z","end":"2026-10-04T13:35:09.251488Z","steps":["trace[1053938587] 'range keys from in-memory index tree' (duration: 201.371236ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:09.251501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.935995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-10-04T13:35:09.251582Z","caller":"traceutil/trace.go:171","msg":"trace[690422077] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:24750; }","duration":"251.080918ms","start":"2026-10-04T13:35:09.00048Z","end":"2026-10-04T13:35:09.251561Z","steps":["trace[690422077] 'range keys from in-memory index tree' (duration: 250.724341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:09.252229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.053256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:09.252322Z","caller":"traceutil/trace.go:171","msg":"trace[3910077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24750; }","duration":"262.190319ms","start":"2026-10-04T13:35:08.990107Z","end":"2026-10-04T13:35:09.252298Z","steps":["trace[3910077] 'range keys from in-memory index tree' (duration: 261.912354ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:09.394344Z","caller":"traceutil/trace.go:171","msg":"trace[1947129500] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"134.161963ms","start":"2026-10-04T13:35:09.260152Z","end":"2026-10-04T13:35:09.394314Z","steps":["trace[1947129500] 'process raft request' (duration: 133.901328ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:09.778156Z","caller":"traceutil/trace.go:171","msg":"trace[1936000516] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"418.134804ms","start":"2026-10-04T13:35:09.359989Z","end":"2026-10-04T13:35:09.778124Z","steps":["trace[1936000516] 'process raft request' (duration: 337.924534ms)","trace[1936000516] 'compare' (duration: 79.871044ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:09.77829Z","caller":"traceutil/trace.go:171","msg":"trace[903099963] linearizableReadLoop","detail":"{readStateIndex:25562; appliedIndex:25560; }","duration":"286.425199ms","start":"2026-10-04T13:35:09.491832Z","end":"2026-10-04T13:35:09.778257Z","steps":["trace[903099963] 'read index received' (duration: 206.052166ms)","trace[903099963] 'applied index is now lower than readState.Index' (duration: 80.371033ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:09.778397Z","caller":"traceutil/trace.go:171","msg":"trace[1632007851] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"417.94782ms","start":"2026-10-04T13:35:09.360437Z","end":"2026-10-04T13:35:09.778385Z","steps":["trace[1632007851] 'process raft request' (duration: 417.626874ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:09.778417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:09.359963Z","time spent":"418.339018ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:09.778509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:09.360424Z","time spent":"418.004591ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:09.778826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.972759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-04T13:35:09.778942Z","caller":"traceutil/trace.go:171","msg":"trace[785070308] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24753; }","duration":"287.164712ms","start":"2026-10-04T13:35:09.491749Z","end":"2026-10-04T13:35:09.778914Z","steps":["trace[785070308] 'agreement among raft nodes before linearized reading' (duration: 286.656923ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:10.083913Z","caller":"traceutil/trace.go:171","msg":"trace[677043699] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"341.883619ms","start":"2026-10-04T13:35:09.741997Z","end":"2026-10-04T13:35:10.08388Z","steps":["trace[677043699] 'process raft request' (duration: 312.254877ms)","trace[677043699] 'compare' (duration: 29.50351ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:10.084062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:09.741966Z","time spent":"342.017101ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:10.13116Z","caller":"traceutil/trace.go:171","msg":"trace[1429719858] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"345.954806ms","start":"2026-10-04T13:35:09.785126Z","end":"2026-10-04T13:35:10.13108Z","steps":["trace[1429719858] 'process raft request' (duration: 345.792423ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:10.131324Z","caller":"traceutil/trace.go:171","msg":"trace[1657802397] linearizableReadLoop","detail":"{readStateIndex:25564; appliedIndex:25562; }","duration":"352.949709ms","start":"2026-10-04T13:35:09.778346Z","end":"2026-10-04T13:35:10.131296Z","steps":["trace[1657802397] 'read index received' (duration: 275.922749ms)","trace[1657802397] 'applied index is now lower than readState.Index' (duration: 77.02574ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:10.131412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:09.785092Z","time spent":"346.1989ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:10.131581Z","caller":"traceutil/trace.go:171","msg":"trace[168193947] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"130.024084ms","start":"2026-10-04T13:35:10.001545Z","end":"2026-10-04T13:35:10.131569Z","steps":["trace[168193947] 'process raft request' (duration: 129.443733ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:10.131611Z","caller":"traceutil/trace.go:171","msg":"trace[923773671] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"379.860709ms","start":"2026-10-04T13:35:09.751722Z","end":"2026-10-04T13:35:10.131583Z","steps":["trace[923773671] 'process raft request' (duration: 378.963222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:10.131749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:09.751679Z","time spent":"379.990702ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:10.131849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.101024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-04T13:35:10.13189Z","caller":"traceutil/trace.go:171","msg":"trace[1990877923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24758; }","duration":"503.205717ms","start":"2026-10-04T13:35:09.628673Z","end":"2026-10-04T13:35:10.131878Z","steps":["trace[1990877923] 'agreement among raft nodes before linearized reading' (duration: 503.057194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:10.131918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:09.628647Z","time spent":"503.263747ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T13:35:10.131939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.264894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:10.132007Z","caller":"traceutil/trace.go:171","msg":"trace[1190059674] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24758; }","duration":"630.372386ms","start":"2026-10-04T13:35:09.50162Z","end":"2026-10-04T13:35:10.131992Z","steps":["trace[1190059674] 'agreement among raft nodes before linearized reading' (duration: 630.277824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:10.132051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:09.501596Z","time spent":"630.438327ms","remote":"127.0.0.1:55114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-04T13:35:10.132081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.154925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:10.132139Z","caller":"traceutil/trace.go:171","msg":"trace[939858625] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24758; }","duration":"588.231507ms","start":"2026-10-04T13:35:09.543891Z","end":"2026-10-04T13:35:10.132122Z","steps":["trace[939858625] 'agreement among raft nodes before linearized reading' (duration: 588.142785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:10.132151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.348674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:10.132205Z","caller":"traceutil/trace.go:171","msg":"trace[1924632544] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24758; }","duration":"250.428635ms","start":"2026-10-04T13:35:09.881745Z","end":"2026-10-04T13:35:10.132173Z","steps":["trace[1924632544] 'agreement among raft nodes before linearized reading' (duration: 250.366034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:10.132178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:09.543879Z","time spent":"588.290808ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-10-04T13:35:10.132495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.268632ms","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-10-04T13:35:10.132531Z","caller":"traceutil/trace.go:171","msg":"trace[1700478995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24758; }","duration":"508.304182ms","start":"2026-10-04T13:35:09.624217Z","end":"2026-10-04T13:35:10.132521Z","steps":["trace[1700478995] 'agreement among raft nodes before linearized reading' (duration: 508.156579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:10.132556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:09.624119Z","time spent":"508.430114ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-04T13:35:10.132673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.892158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:10.132729Z","caller":"traceutil/trace.go:171","msg":"trace[698424614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24758; }","duration":"142.038371ms","start":"2026-10-04T13:35:09.990675Z","end":"2026-10-04T13:35:10.132713Z","steps":["trace[698424614] 'agreement among raft nodes before linearized reading' (duration: 141.930099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:10.132901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.415749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:10.132972Z","caller":"traceutil/trace.go:171","msg":"trace[441725185] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:24758; }","duration":"599.507891ms","start":"2026-10-04T13:35:09.533446Z","end":"2026-10-04T13:35:10.132954Z","steps":["trace[441725185] 'agreement among raft nodes before linearized reading' (duration: 599.301977ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:10.132972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.647892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T13:35:10.133002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:09.533378Z","time spent":"599.617202ms","remote":"127.0.0.1:47890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-10-04T13:35:10.133025Z","caller":"traceutil/trace.go:171","msg":"trace[2100659772] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24758; }","duration":"143.752314ms","start":"2026-10-04T13:35:09.989256Z","end":"2026-10-04T13:35:10.133009Z","steps":["trace[2100659772] 'agreement among raft nodes before linearized reading' (duration: 143.661652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:26.019893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.937291ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:26.020053Z","caller":"traceutil/trace.go:171","msg":"trace[163752453] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24881; }","duration":"103.127315ms","start":"2026-10-04T13:35:25.916906Z","end":"2026-10-04T13:35:26.020033Z","steps":["trace[163752453] 'range keys from in-memory index tree' (duration: 102.917951ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:26.230606Z","caller":"traceutil/trace.go:171","msg":"trace[1603295302] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"378.140968ms","start":"2026-10-04T13:35:25.852428Z","end":"2026-10-04T13:35:26.230569Z","steps":["trace[1603295302] 'process raft request' (duration: 377.983974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:26.23091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:25.852411Z","time spent":"378.324621ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:26.860771Z","caller":"traceutil/trace.go:171","msg":"trace[694680577] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"717.863217ms","start":"2026-10-04T13:35:26.142882Z","end":"2026-10-04T13:35:26.860745Z","steps":["trace[694680577] 'process raft request' (duration: 717.786995ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:26.860855Z","caller":"traceutil/trace.go:171","msg":"trace[1145142126] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"1.008175889s","start":"2026-10-04T13:35:25.852628Z","end":"2026-10-04T13:35:26.860804Z","steps":["trace[1145142126] 'process raft request' (duration: 1.007857723s)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:26.860891Z","caller":"traceutil/trace.go:171","msg":"trace[427846413] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"720.815713ms","start":"2026-10-04T13:35:26.140029Z","end":"2026-10-04T13:35:26.860845Z","steps":["trace[427846413] 'process raft request' (duration: 720.597678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:26.860931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.142868Z","time spent":"717.977139ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:26.861051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:25.852593Z","time spent":"1.008349523s","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:26.861074Z","caller":"traceutil/trace.go:171","msg":"trace[439782129] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"656.552815ms","start":"2026-10-04T13:35:26.204497Z","end":"2026-10-04T13:35:26.86105Z","steps":["trace[439782129] 'process raft request' (duration: 656.210278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:26.861101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.139991Z","time spent":"720.993166ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:26.861156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.204468Z","time spent":"656.646237ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:26.861126Z","caller":"traceutil/trace.go:171","msg":"trace[827703106] linearizableReadLoop","detail":"{readStateIndex:25696; appliedIndex:25694; }","duration":"867.368ms","start":"2026-10-04T13:35:25.993731Z","end":"2026-10-04T13:35:26.861099Z","steps":["trace[827703106] 'read index received' (duration: 236.643405ms)","trace[827703106] 'applied index is now lower than readState.Index' (duration: 630.723455ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:26.861235Z","caller":"traceutil/trace.go:171","msg":"trace[1254632894] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"653.11997ms","start":"2026-10-04T13:35:26.208032Z","end":"2026-10-04T13:35:26.861152Z","steps":["trace[1254632894] 'process raft request' (duration: 653.014298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:26.861299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.532133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:26.861233Z","caller":"traceutil/trace.go:171","msg":"trace[1429567167] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"655.491354ms","start":"2026-10-04T13:35:26.205666Z","end":"2026-10-04T13:35:26.861158Z","steps":["trace[1429567167] 'process raft request' (duration: 655.256889ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:26.861351Z","caller":"traceutil/trace.go:171","msg":"trace[1804789615] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:24888; }","duration":"867.648665ms","start":"2026-10-04T13:35:25.993689Z","end":"2026-10-04T13:35:26.861337Z","steps":["trace[1804789615] 'agreement among raft nodes before linearized reading' (duration: 867.490032ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:26.861401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:25.993669Z","time spent":"867.721396ms","remote":"127.0.0.1:55168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-04T13:35:26.861416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.208008Z","time spent":"653.324643ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:26.861522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.205656Z","time spent":"655.729669ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:26.972927Z","caller":"traceutil/trace.go:171","msg":"trace[804892996] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"555.221714ms","start":"2026-10-04T13:35:26.417678Z","end":"2026-10-04T13:35:26.9729Z","steps":["trace[804892996] 'process raft request' (duration: 532.30865ms)","trace[804892996] 'compare' (duration: 22.632539ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:26.972953Z","caller":"traceutil/trace.go:171","msg":"trace[1137596136] linearizableReadLoop","detail":"{readStateIndex:25703; appliedIndex:25701; }","duration":"111.658046ms","start":"2026-10-04T13:35:26.861266Z","end":"2026-10-04T13:35:26.972924Z","steps":["trace[1137596136] 'read index received' (duration: 88.641079ms)","trace[1137596136] 'applied index is now lower than readState.Index' (duration: 23.015697ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:26.973014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.231307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T13:35:26.973071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.417643Z","time spent":"555.355996ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:26.973089Z","caller":"traceutil/trace.go:171","msg":"trace[182902480] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24890; }","duration":"342.324518ms","start":"2026-10-04T13:35:26.630746Z","end":"2026-10-04T13:35:26.97307Z","steps":["trace[182902480] 'agreement among raft nodes before linearized reading' (duration: 342.186446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:26.973131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.630662Z","time spent":"342.459841ms","remote":"127.0.0.1:36088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-10-04T13:35:26.97324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.761526ms","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-10-04T13:35:26.97328Z","caller":"traceutil/trace.go:171","msg":"trace[56148226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24890; }","duration":"724.911489ms","start":"2026-10-04T13:35:26.248354Z","end":"2026-10-04T13:35:26.973266Z","steps":["trace[56148226] 'agreement among raft nodes before linearized reading' (duration: 724.714475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:26.973313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.248325Z","time spent":"724.979751ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T13:35:26.973552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.395364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-04T13:35:26.973572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.209718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-04T13:35:26.973629Z","caller":"traceutil/trace.go:171","msg":"trace[533928394] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24890; }","duration":"732.30406ms","start":"2026-10-04T13:35:26.241308Z","end":"2026-10-04T13:35:26.973612Z","steps":["trace[533928394] 'agreement among raft nodes before linearized reading' (duration: 732.130897ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:26.973627Z","caller":"traceutil/trace.go:171","msg":"trace[925314737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24890; }","duration":"738.535148ms","start":"2026-10-04T13:35:26.235071Z","end":"2026-10-04T13:35:26.973606Z","steps":["trace[925314737] 'agreement among raft nodes before linearized reading' (duration: 738.307433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:26.973672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.23504Z","time spent":"738.621149ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-04T13:35:26.973675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.241292Z","time spent":"732.362941ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-04T13:35:26.974035Z","caller":"traceutil/trace.go:171","msg":"trace[205781189] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"538.707851ms","start":"2026-10-04T13:35:26.435259Z","end":"2026-10-04T13:35:26.973363Z","steps":["trace[205781189] 'process raft request' (duration: 537.555478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:26.974554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.435227Z","time spent":"538.923355ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:27.380978Z","caller":"traceutil/trace.go:171","msg":"trace[278917879] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"402.258734ms","start":"2026-10-04T13:35:26.978683Z","end":"2026-10-04T13:35:27.380942Z","steps":["trace[278917879] 'process raft request' (duration: 368.30972ms)","trace[278917879] 'compare' (duration: 33.73681ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:27.381136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.978665Z","time spent":"402.382987ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:27.60725Z","caller":"traceutil/trace.go:171","msg":"trace[2027801755] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"628.503291ms","start":"2026-10-04T13:35:26.978721Z","end":"2026-10-04T13:35:27.607224Z","steps":["trace[2027801755] 'process raft request' (duration: 628.281717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:27.607398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.978697Z","time spent":"628.623044ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:27.607476Z","caller":"traceutil/trace.go:171","msg":"trace[1888128465] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"628.695796ms","start":"2026-10-04T13:35:26.97875Z","end":"2026-10-04T13:35:27.607446Z","steps":["trace[1888128465] 'process raft request' (duration: 628.4091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:27.607557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.978728Z","time spent":"628.786927ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:27.607585Z","caller":"traceutil/trace.go:171","msg":"trace[531459888] linearizableReadLoop","detail":"{readStateIndex:25707; appliedIndex:25703; }","duration":"616.522555ms","start":"2026-10-04T13:35:26.991023Z","end":"2026-10-04T13:35:27.607545Z","steps":["trace[531459888] 'read index received' (duration: 355.984327ms)","trace[531459888] 'applied index is now lower than readState.Index' (duration: 260.536098ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:27.607687Z","caller":"traceutil/trace.go:171","msg":"trace[1000582243] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"627.988772ms","start":"2026-10-04T13:35:26.979668Z","end":"2026-10-04T13:35:27.607657Z","steps":["trace[1000582243] 'process raft request' (duration: 627.711006ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:27.607764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.718468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:27.607797Z","caller":"traceutil/trace.go:171","msg":"trace[842253521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24894; }","duration":"616.81396ms","start":"2026-10-04T13:35:26.990973Z","end":"2026-10-04T13:35:27.607787Z","steps":["trace[842253521] 'agreement among raft nodes before linearized reading' (duration: 616.724169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:27.607834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.990952Z","time spent":"616.869881ms","remote":"127.0.0.1:54270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T13:35:27.607827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:26.979646Z","time spent":"628.103375ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:27.607865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.272504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.91\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-04T13:35:27.608498Z","caller":"traceutil/trace.go:171","msg":"trace[233067692] range","detail":"{range_begin:/registry/masterleases/162.253.55.91; range_end:; response_count:1; response_revision:24894; }","duration":"164.938207ms","start":"2026-10-04T13:35:27.443541Z","end":"2026-10-04T13:35:27.60848Z","steps":["trace[233067692] 'agreement among raft nodes before linearized reading' (duration: 164.285564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:27.607856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.542562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-04T13:35:27.608718Z","caller":"traceutil/trace.go:171","msg":"trace[941371872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24894; }","duration":"582.442579ms","start":"2026-10-04T13:35:27.026265Z","end":"2026-10-04T13:35:27.608707Z","steps":["trace[941371872] 'agreement among raft nodes before linearized reading' (duration: 581.485371ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:27.608753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:27.026249Z","time spent":"582.490641ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-04T13:35:27.607958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.535331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"info","ts":"2026-10-04T13:35:27.608901Z","caller":"traceutil/trace.go:171","msg":"trace[1067088481] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:24894; }","duration":"401.526311ms","start":"2026-10-04T13:35:27.20736Z","end":"2026-10-04T13:35:27.608886Z","steps":["trace[1067088481] 'agreement among raft nodes before linearized reading' (duration: 400.530771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:27.608936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:27.207328Z","time spent":"401.597972ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-10-04T13:35:27.608156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.767609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-10-04T13:35:27.609111Z","caller":"traceutil/trace.go:171","msg":"trace[130469494] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:24894; }","duration":"446.768868ms","start":"2026-10-04T13:35:27.162318Z","end":"2026-10-04T13:35:27.609087Z","steps":["trace[130469494] 'agreement among raft nodes before linearized reading' (duration: 445.636787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:27.609216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:27.162292Z","time spent":"446.86896ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-10-04T13:35:27.608242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.980038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T13:35:27.609293Z","caller":"traceutil/trace.go:171","msg":"trace[491901616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24894; }","duration":"127.066519ms","start":"2026-10-04T13:35:27.482215Z","end":"2026-10-04T13:35:27.609281Z","steps":["trace[491901616] 'agreement among raft nodes before linearized reading' (duration: 125.799345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:28.030954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.515366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T13:35:28.03112Z","caller":"traceutil/trace.go:171","msg":"trace[1862982637] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"417.512464ms","start":"2026-10-04T13:35:27.613588Z","end":"2026-10-04T13:35:28.0311Z","steps":["trace[1862982637] 'process raft request' (duration: 260.765073ms)","trace[1862982637] 'compare' (duration: 156.290512ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:28.0312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:27.613565Z","time spent":"417.587485ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:28.031503Z","caller":"traceutil/trace.go:171","msg":"trace[1847286999] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"417.190917ms","start":"2026-10-04T13:35:27.614279Z","end":"2026-10-04T13:35:28.031469Z","steps":["trace[1847286999] 'process raft request' (duration: 416.765739ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:28.03158Z","caller":"traceutil/trace.go:171","msg":"trace[319431097] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"416.681657ms","start":"2026-10-04T13:35:27.614866Z","end":"2026-10-04T13:35:28.031548Z","steps":["trace[319431097] 'process raft request' (duration: 416.486634ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:28.031637Z","caller":"traceutil/trace.go:171","msg":"trace[484245892] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"410.149924ms","start":"2026-10-04T13:35:27.621457Z","end":"2026-10-04T13:35:28.031607Z","steps":["trace[484245892] 'process raft request' (duration: 410.017332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:28.031678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:27.614259Z","time spent":"417.32067ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:28.031677Z","caller":"traceutil/trace.go:171","msg":"trace[269374338] linearizableReadLoop","detail":"{readStateIndex:25711; appliedIndex:25708; }","duration":"412.013999ms","start":"2026-10-04T13:35:27.619623Z","end":"2026-10-04T13:35:28.031637Z","steps":["trace[269374338] 'read index received' (duration: 254.741618ms)","trace[269374338] 'applied index is now lower than readState.Index' (duration: 157.266271ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:28.031729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:27.621448Z","time spent":"410.233966ms","remote":"127.0.0.1:54326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:28.03173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:27.614855Z","time spent":"416.791599ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:28.03191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.132039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T13:35:28.031922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.480882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"info","ts":"2026-10-04T13:35:28.031998Z","caller":"traceutil/trace.go:171","msg":"trace[2072139052] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:24898; }","duration":"418.577684ms","start":"2026-10-04T13:35:27.6134Z","end":"2026-10-04T13:35:28.031977Z","steps":["trace[2072139052] 'agreement among raft nodes before linearized reading' (duration: 418.363039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:28.032034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:27.613389Z","time spent":"418.636926ms","remote":"127.0.0.1:54496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-10-04T13:35:28.032035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.471944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-10-04T13:35:28.032045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.617398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:28.031955Z","caller":"traceutil/trace.go:171","msg":"trace[65018471] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24898; }","duration":"128.195081ms","start":"2026-10-04T13:35:27.903747Z","end":"2026-10-04T13:35:28.031942Z","steps":["trace[65018471] 'agreement among raft nodes before linearized reading' (duration: 128.113599ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:28.032089Z","caller":"traceutil/trace.go:171","msg":"trace[1714523729] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:24898; }","duration":"156.685719ms","start":"2026-10-04T13:35:27.875389Z","end":"2026-10-04T13:35:28.032075Z","steps":["trace[1714523729] 'agreement among raft nodes before linearized reading' (duration: 156.613798ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:28.032089Z","caller":"traceutil/trace.go:171","msg":"trace[802140293] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24898; }","duration":"321.598655ms","start":"2026-10-04T13:35:27.710476Z","end":"2026-10-04T13:35:28.032074Z","steps":["trace[802140293] 'agreement among raft nodes before linearized reading' (duration: 321.354071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:28.032143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:27.710441Z","time spent":"321.689687ms","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-04T13:35:28.355904Z","caller":"traceutil/trace.go:171","msg":"trace[2042643526] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"119.294801ms","start":"2026-10-04T13:35:28.236583Z","end":"2026-10-04T13:35:28.355878Z","steps":["trace[2042643526] 'process raft request' (duration: 119.012385ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:29.136916Z","caller":"traceutil/trace.go:171","msg":"trace[744772944] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"269.039159ms","start":"2026-10-04T13:35:28.867849Z","end":"2026-10-04T13:35:29.136888Z","steps":["trace[744772944] 'process raft request' (duration: 268.831075ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:29.13716Z","caller":"traceutil/trace.go:171","msg":"trace[1620715434] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"269.261773ms","start":"2026-10-04T13:35:28.867888Z","end":"2026-10-04T13:35:29.13715Z","steps":["trace[1620715434] 'process raft request' (duration: 268.953257ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:29.137693Z","caller":"traceutil/trace.go:171","msg":"trace[543951581] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"268.435187ms","start":"2026-10-04T13:35:28.869228Z","end":"2026-10-04T13:35:29.137663Z","steps":["trace[543951581] 'process raft request' (duration: 267.873276ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:29.137866Z","caller":"traceutil/trace.go:171","msg":"trace[1780857576] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"268.55765ms","start":"2026-10-04T13:35:28.869287Z","end":"2026-10-04T13:35:29.137845Z","steps":["trace[1780857576] 'process raft request' (duration: 268.260694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:29.398269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.364907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T13:35:29.398335Z","caller":"traceutil/trace.go:171","msg":"trace[1866488012] linearizableReadLoop","detail":"{readStateIndex:25719; appliedIndex:25715; }","duration":"407.505955ms","start":"2026-10-04T13:35:28.990819Z","end":"2026-10-04T13:35:29.398325Z","steps":["trace[1866488012] 'read index received' (duration: 146.001758ms)","trace[1866488012] 'applied index is now lower than readState.Index' (duration: 261.503567ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:29.398397Z","caller":"traceutil/trace.go:171","msg":"trace[208862331] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"417.343681ms","start":"2026-10-04T13:35:28.981014Z","end":"2026-10-04T13:35:29.398357Z","steps":["trace[208862331] 'process raft request' (duration: 272.830711ms)","trace[208862331] 'compare' (duration: 143.948549ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:29.398521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.806342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:29.399094Z","caller":"traceutil/trace.go:171","msg":"trace[76750310] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24905; }","duration":"139.460583ms","start":"2026-10-04T13:35:29.259615Z","end":"2026-10-04T13:35:29.399076Z","steps":["trace[76750310] 'agreement among raft nodes before linearized reading' (duration: 138.817242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:29.398445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.628207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-04T13:35:29.399156Z","caller":"traceutil/trace.go:171","msg":"trace[131532308] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24905; }","duration":"408.372791ms","start":"2026-10-04T13:35:28.990776Z","end":"2026-10-04T13:35:29.399148Z","steps":["trace[131532308] 'agreement among raft nodes before linearized reading' (duration: 407.606987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:29.399192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:28.990754Z","time spent":"408.416361ms","remote":"127.0.0.1:54450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1658,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-10-04T13:35:29.398539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.978414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:29.399299Z","caller":"traceutil/trace.go:171","msg":"trace[1063291024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24905; }","duration":"407.81313ms","start":"2026-10-04T13:35:28.99148Z","end":"2026-10-04T13:35:29.399293Z","steps":["trace[1063291024] 'agreement among raft nodes before linearized reading' (duration: 406.949204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:29.399314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:28.99146Z","time spent":"407.84945ms","remote":"127.0.0.1:54270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T13:35:29.398608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:28.980987Z","time spent":"417.506974ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:29.536765Z","caller":"traceutil/trace.go:171","msg":"trace[1988442192] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"134.58112ms","start":"2026-10-04T13:35:29.402159Z","end":"2026-10-04T13:35:29.53674Z","steps":["trace[1988442192] 'process raft request' (duration: 130.005434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:29.925554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.754222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T13:35:29.925825Z","caller":"traceutil/trace.go:171","msg":"trace[953872748] linearizableReadLoop","detail":"{readStateIndex:25723; appliedIndex:25720; }","duration":"197.31969ms","start":"2026-10-04T13:35:29.728489Z","end":"2026-10-04T13:35:29.925809Z","steps":["trace[953872748] 'read index received' (duration: 181.922359ms)","trace[953872748] 'applied index is now lower than readState.Index' (duration: 15.396451ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:29.92596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.481173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T13:35:29.925968Z","caller":"traceutil/trace.go:171","msg":"trace[456953140] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"308.311034ms","start":"2026-10-04T13:35:29.617635Z","end":"2026-10-04T13:35:29.925946Z","steps":["trace[456953140] 'process raft request' (duration: 308.035888ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:29.925989Z","caller":"traceutil/trace.go:171","msg":"trace[92673053] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:24909; }","duration":"197.567874ms","start":"2026-10-04T13:35:29.728414Z","end":"2026-10-04T13:35:29.925982Z","steps":["trace[92673053] 'agreement among raft nodes before linearized reading' (duration: 197.446822ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:29.925965Z","caller":"traceutil/trace.go:171","msg":"trace[252619426] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"308.349235ms","start":"2026-10-04T13:35:29.617559Z","end":"2026-10-04T13:35:29.925909Z","steps":["trace[252619426] 'process raft request' (duration: 74.34908ms)","trace[252619426] 'compare' (duration: 232.546338ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:29.92605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:29.617614Z","time spent":"308.394686ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:29.926027Z","caller":"traceutil/trace.go:171","msg":"trace[1469559126] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"307.251923ms","start":"2026-10-04T13:35:29.61874Z","end":"2026-10-04T13:35:29.925992Z","steps":["trace[1469559126] 'process raft request' (duration: 307.029599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:29.926161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:29.617522Z","time spent":"308.533179ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:29.926237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:29.618705Z","time spent":"307.400517ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:30.147046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.626916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-04T13:35:30.147148Z","caller":"traceutil/trace.go:171","msg":"trace[184252529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24909; }","duration":"110.760679ms","start":"2026-10-04T13:35:30.036362Z","end":"2026-10-04T13:35:30.147123Z","steps":["trace[184252529] 'range keys from in-memory index tree' (duration: 110.513404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:30.147166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.898631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T13:35:30.147265Z","caller":"traceutil/trace.go:171","msg":"trace[696401208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24909; }","duration":"112.072285ms","start":"2026-10-04T13:35:30.035175Z","end":"2026-10-04T13:35:30.147247Z","steps":["trace[696401208] 'range keys from in-memory index tree' (duration: 111.740868ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:30.252385Z","caller":"traceutil/trace.go:171","msg":"trace[997641816] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"100.75322ms","start":"2026-10-04T13:35:30.151605Z","end":"2026-10-04T13:35:30.252358Z","steps":["trace[997641816] 'process raft request' (duration: 100.534995ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:30.427642Z","caller":"traceutil/trace.go:171","msg":"trace[1597083480] linearizableReadLoop","detail":"{readStateIndex:25725; appliedIndex:25723; }","duration":"224.896743ms","start":"2026-10-04T13:35:30.202716Z","end":"2026-10-04T13:35:30.427613Z","steps":["trace[1597083480] 'read index received' (duration: 49.496659ms)","trace[1597083480] 'applied index is now lower than readState.Index' (duration: 175.399064ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:30.427782Z","caller":"traceutil/trace.go:171","msg":"trace[1728233691] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"273.734798ms","start":"2026-10-04T13:35:30.15399Z","end":"2026-10-04T13:35:30.427725Z","steps":["trace[1728233691] 'process raft request' (duration: 235.778528ms)","trace[1728233691] 'compare' (duration: 37.697635ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:30.427832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.074266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:30.427888Z","caller":"traceutil/trace.go:171","msg":"trace[693476973] 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:24911; }","duration":"225.206619ms","start":"2026-10-04T13:35:30.202662Z","end":"2026-10-04T13:35:30.427868Z","steps":["trace[693476973] 'agreement among raft nodes before linearized reading' (duration: 225.077496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:30.459115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.431589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:30.459224Z","caller":"traceutil/trace.go:171","msg":"trace[1542471745] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:24912; }","duration":"152.589003ms","start":"2026-10-04T13:35:30.306591Z","end":"2026-10-04T13:35:30.45918Z","steps":["trace[1542471745] 'agreement among raft nodes before linearized reading' (duration: 152.44604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:30.952483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.889685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T13:35:30.952592Z","caller":"traceutil/trace.go:171","msg":"trace[381262065] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"474.964222ms","start":"2026-10-04T13:35:30.477607Z","end":"2026-10-04T13:35:30.952571Z","steps":["trace[381262065] 'process raft request' (duration: 127.903264ms)","trace[381262065] 'compare' (duration: 345.762274ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:30.952651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:30.477583Z","time spent":"475.040263ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:31.379714Z","caller":"traceutil/trace.go:171","msg":"trace[1149309455] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"441.892055ms","start":"2026-10-04T13:35:30.937796Z","end":"2026-10-04T13:35:31.379688Z","steps":["trace[1149309455] 'process raft request' (duration: 441.733162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:31.379869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:30.937769Z","time spent":"442.021388ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:31.380424Z","caller":"traceutil/trace.go:171","msg":"trace[1552257715] linearizableReadLoop","detail":"{readStateIndex:25728; appliedIndex:25728; }","duration":"390.331329ms","start":"2026-10-04T13:35:30.990077Z","end":"2026-10-04T13:35:31.380409Z","steps":["trace[1552257715] 'read index received' (duration: 390.323599ms)","trace[1552257715] 'applied index is now lower than readState.Index' (duration: 6.25µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:31.380511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.430031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:31.380552Z","caller":"traceutil/trace.go:171","msg":"trace[1147852848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24914; }","duration":"390.473752ms","start":"2026-10-04T13:35:30.990069Z","end":"2026-10-04T13:35:31.380543Z","steps":["trace[1147852848] 'agreement among raft nodes before linearized reading' (duration: 390.40351ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:31.380578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:30.990006Z","time spent":"390.566233ms","remote":"127.0.0.1:54270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-04T13:35:31.40176Z","caller":"traceutil/trace.go:171","msg":"trace[1855637972] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"259.470168ms","start":"2026-10-04T13:35:31.142275Z","end":"2026-10-04T13:35:31.401745Z","steps":["trace[1855637972] 'process raft request' (duration: 258.988159ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:31.401893Z","caller":"traceutil/trace.go:171","msg":"trace[237927339] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"259.185492ms","start":"2026-10-04T13:35:31.142699Z","end":"2026-10-04T13:35:31.401885Z","steps":["trace[237927339] 'process raft request' (duration: 258.714063ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:31.402014Z","caller":"traceutil/trace.go:171","msg":"trace[2057920900] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"258.600162ms","start":"2026-10-04T13:35:31.143406Z","end":"2026-10-04T13:35:31.402006Z","steps":["trace[2057920900] 'process raft request' (duration: 258.059802ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:31.402138Z","caller":"traceutil/trace.go:171","msg":"trace[334938318] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"258.050572ms","start":"2026-10-04T13:35:31.14408Z","end":"2026-10-04T13:35:31.40213Z","steps":["trace[334938318] 'process raft request' (duration: 257.43796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:31.402328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.526247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:31.402369Z","caller":"traceutil/trace.go:171","msg":"trace[1126941205] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24918; }","duration":"204.577938ms","start":"2026-10-04T13:35:31.197781Z","end":"2026-10-04T13:35:31.402359Z","steps":["trace[1126941205] 'agreement among raft nodes before linearized reading' (duration: 204.500686ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:31.402579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.648185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-10-04T13:35:31.40262Z","caller":"traceutil/trace.go:171","msg":"trace[655187949] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:24918; }","duration":"361.723717ms","start":"2026-10-04T13:35:31.040883Z","end":"2026-10-04T13:35:31.402607Z","steps":["trace[655187949] 'agreement among raft nodes before linearized reading' (duration: 361.610335ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:31.402646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:31.040859Z","time spent":"361.779727ms","remote":"127.0.0.1:54450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1960,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-10-04T13:35:31.732327Z","caller":"traceutil/trace.go:171","msg":"trace[1803523658] linearizableReadLoop","detail":"{readStateIndex:25733; appliedIndex:25732; }","duration":"325.135153ms","start":"2026-10-04T13:35:31.407148Z","end":"2026-10-04T13:35:31.732284Z","steps":["trace[1803523658] 'read index received' (duration: 246.427971ms)","trace[1803523658] 'applied index is now lower than readState.Index' (duration: 78.704952ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:31.732522Z","caller":"traceutil/trace.go:171","msg":"trace[1511969417] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"328.413415ms","start":"2026-10-04T13:35:31.404082Z","end":"2026-10-04T13:35:31.732495Z","steps":["trace[1511969417] 'process raft request' (duration: 249.57093ms)","trace[1511969417] 'compare' (duration: 78.311625ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:31.732679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:31.404064Z","time spent":"328.487676ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:31.732995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.215875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T13:35:31.733103Z","caller":"traceutil/trace.go:171","msg":"trace[2060569248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24919; }","duration":"191.366158ms","start":"2026-10-04T13:35:31.541706Z","end":"2026-10-04T13:35:31.733072Z","steps":["trace[2060569248] 'agreement among raft nodes before linearized reading' (duration: 190.913779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:31.733421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.267365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a4fa2bb5-77d5-4f61-a609-3e819c3bd288\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-10-04T13:35:31.733477Z","caller":"traceutil/trace.go:171","msg":"trace[1832472578] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a4fa2bb5-77d5-4f61-a609-3e819c3bd288; range_end:; response_count:1; response_revision:24919; }","duration":"326.360335ms","start":"2026-10-04T13:35:31.407103Z","end":"2026-10-04T13:35:31.733464Z","steps":["trace[1832472578] 'agreement among raft nodes before linearized reading' (duration: 326.148402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:31.733518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:31.407082Z","time spent":"326.429087ms","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1475,"request content":"key:\"/registry/persistentvolumes/pvc-a4fa2bb5-77d5-4f61-a609-3e819c3bd288\" "} {"level":"warn","ts":"2026-10-04T13:35:31.733473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.719165ms","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-10-04T13:35:31.733727Z","caller":"traceutil/trace.go:171","msg":"trace[562787055] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24919; }","duration":"104.027791ms","start":"2026-10-04T13:35:31.629683Z","end":"2026-10-04T13:35:31.73371Z","steps":["trace[562787055] 'agreement among raft nodes before linearized reading' (duration: 103.710666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.291241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.902778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T13:35:32.291425Z","caller":"traceutil/trace.go:171","msg":"trace[1255385329] linearizableReadLoop","detail":"{readStateIndex:25735; appliedIndex:25733; }","duration":"361.630254ms","start":"2026-10-04T13:35:31.929778Z","end":"2026-10-04T13:35:32.291408Z","steps":["trace[1255385329] 'read index received' (duration: 165.890444ms)","trace[1255385329] 'applied index is now lower than readState.Index' (duration: 195.73875ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:32.291516Z","caller":"traceutil/trace.go:171","msg":"trace[575755475] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"424.923464ms","start":"2026-10-04T13:35:31.86658Z","end":"2026-10-04T13:35:32.291503Z","steps":["trace[575755475] 'process raft request' (duration: 424.767321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.291577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:31.866551Z","time spent":"424.990824ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:32.291839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.061692ms","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-10-04T13:35:32.291864Z","caller":"traceutil/trace.go:171","msg":"trace[885560236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24921; }","duration":"362.138724ms","start":"2026-10-04T13:35:31.929717Z","end":"2026-10-04T13:35:32.291856Z","steps":["trace[885560236] 'agreement among raft nodes before linearized reading' (duration: 362.021111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.291891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:31.929691Z","time spent":"362.193265ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-04T13:35:32.291987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.514876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-04T13:35:32.291987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.032442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-04T13:35:32.292056Z","caller":"traceutil/trace.go:171","msg":"trace[2011329595] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:24921; }","duration":"281.635798ms","start":"2026-10-04T13:35:32.010399Z","end":"2026-10-04T13:35:32.292035Z","steps":["trace[2011329595] 'agreement among raft nodes before linearized reading' (duration: 281.525626ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:32.292064Z","caller":"traceutil/trace.go:171","msg":"trace[634942026] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24921; }","duration":"361.177395ms","start":"2026-10-04T13:35:31.930868Z","end":"2026-10-04T13:35:32.292046Z","steps":["trace[634942026] 'agreement among raft nodes before linearized reading' (duration: 360.91636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.292071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.604563ms","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":"warn","ts":"2026-10-04T13:35:32.292083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.831919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-04T13:35:32.292085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.354942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:32.292119Z","caller":"traceutil/trace.go:171","msg":"trace[2081890120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24921; }","duration":"360.883259ms","start":"2026-10-04T13:35:31.931227Z","end":"2026-10-04T13:35:32.29211Z","steps":["trace[2081890120] 'agreement among raft nodes before linearized reading' (duration: 360.792908ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:32.29212Z","caller":"traceutil/trace.go:171","msg":"trace[637221010] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:24921; }","duration":"313.683645ms","start":"2026-10-04T13:35:31.978423Z","end":"2026-10-04T13:35:32.292107Z","steps":["trace[637221010] 'agreement among raft nodes before linearized reading' (duration: 313.591314ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.292116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:31.930841Z","time spent":"361.262507ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":583,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-04T13:35:32.292135Z","caller":"traceutil/trace.go:171","msg":"trace[1482913720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24921; }","duration":"301.435833ms","start":"2026-10-04T13:35:31.990687Z","end":"2026-10-04T13:35:32.292123Z","steps":["trace[1482913720] 'agreement among raft nodes before linearized reading' (duration: 301.334101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.292144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:31.931212Z","time spent":"360.924731ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T13:35:32.292152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:31.978406Z","time spent":"313.737026ms","remote":"127.0.0.1:54478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":32,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-10-04T13:35:32.292171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:31.990677Z","time spent":"301.486343ms","remote":"127.0.0.1:54270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-04T13:35:32.291433Z","caller":"traceutil/trace.go:171","msg":"trace[1922013939] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"543.859708ms","start":"2026-10-04T13:35:31.747544Z","end":"2026-10-04T13:35:32.291404Z","steps":["trace[1922013939] 'process raft request' (duration: 181.685074ms)","trace[1922013939] 'compare' (duration: 361.757496ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:32.292614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:31.747514Z","time spent":"544.98997ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:32.872548Z","caller":"traceutil/trace.go:171","msg":"trace[1672804997] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"575.909607ms","start":"2026-10-04T13:35:32.296613Z","end":"2026-10-04T13:35:32.872523Z","steps":["trace[1672804997] 'process raft request' (duration: 575.705343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.872717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:32.296594Z","time spent":"576.035029ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:32.872797Z","caller":"traceutil/trace.go:171","msg":"trace[205765961] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"575.874835ms","start":"2026-10-04T13:35:32.2969Z","end":"2026-10-04T13:35:32.872775Z","steps":["trace[205765961] 'process raft request' (duration: 575.58552ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:32.872925Z","caller":"traceutil/trace.go:171","msg":"trace[1649964858] linearizableReadLoop","detail":"{readStateIndex:25739; appliedIndex:25736; }","duration":"440.899736ms","start":"2026-10-04T13:35:32.432004Z","end":"2026-10-04T13:35:32.872904Z","steps":["trace[1649964858] 'read index received' (duration: 440.402107ms)","trace[1649964858] 'applied index is now lower than readState.Index' (duration: 496.839µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:32.872988Z","caller":"traceutil/trace.go:171","msg":"trace[1093828999] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"575.397535ms","start":"2026-10-04T13:35:32.29756Z","end":"2026-10-04T13:35:32.872957Z","steps":["trace[1093828999] 'process raft request' (duration: 575.141361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.872998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:32.296869Z","time spent":"576.051048ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:32.873229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:32.297546Z","time spent":"575.537658ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:32.873612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.560729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-04T13:35:32.873741Z","caller":"traceutil/trace.go:171","msg":"trace[336500776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24925; }","duration":"441.753543ms","start":"2026-10-04T13:35:32.431959Z","end":"2026-10-04T13:35:32.873712Z","steps":["trace[336500776] 'agreement among raft nodes before linearized reading' (duration: 441.071879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.873808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:32.431936Z","time spent":"441.861844ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-04T13:35:32.899932Z","caller":"traceutil/trace.go:171","msg":"trace[830682008] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"435.85154ms","start":"2026-10-04T13:35:32.464043Z","end":"2026-10-04T13:35:32.899895Z","steps":["trace[830682008] 'process raft request' (duration: 433.090788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.900157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:32.463996Z","time spent":"436.045594ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:32.914005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.108588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:32.914069Z","caller":"traceutil/trace.go:171","msg":"trace[727151712] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24927; }","duration":"339.22534ms","start":"2026-10-04T13:35:32.574826Z","end":"2026-10-04T13:35:32.914052Z","steps":["trace[727151712] 'agreement among raft nodes before linearized reading' (duration: 339.122688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:32.914104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:32.574803Z","time spent":"339.290831ms","remote":"127.0.0.1:36244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"info","ts":"2026-10-04T13:35:39.807899Z","caller":"traceutil/trace.go:171","msg":"trace[361517636] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"268.216823ms","start":"2026-10-04T13:35:39.539642Z","end":"2026-10-04T13:35:39.807859Z","steps":["trace[361517636] 'process raft request' (duration: 267.910777ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:40.0816Z","caller":"traceutil/trace.go:171","msg":"trace[1681032178] linearizableReadLoop","detail":"{readStateIndex:25793; appliedIndex:25788; }","duration":"277.47842ms","start":"2026-10-04T13:35:39.804095Z","end":"2026-10-04T13:35:40.081573Z","steps":["trace[1681032178] 'read index received' (duration: 3.393894ms)","trace[1681032178] 'applied index is now lower than readState.Index' (duration: 274.083426ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:40.08193Z","caller":"traceutil/trace.go:171","msg":"trace[357756335] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"528.006017ms","start":"2026-10-04T13:35:39.553908Z","end":"2026-10-04T13:35:40.081914Z","steps":["trace[357756335] 'process raft request' (duration: 527.392796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:40.082035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:39.553878Z","time spent":"528.083268ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:40.082311Z","caller":"traceutil/trace.go:171","msg":"trace[2147334346] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"527.543699ms","start":"2026-10-04T13:35:39.554752Z","end":"2026-10-04T13:35:40.082295Z","steps":["trace[2147334346] 'process raft request' (duration: 526.703583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:40.082396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:39.554728Z","time spent":"527.62814ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:40.082488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.386146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:40.08255Z","caller":"traceutil/trace.go:171","msg":"trace[973388311] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24977; }","duration":"278.454817ms","start":"2026-10-04T13:35:39.804079Z","end":"2026-10-04T13:35:40.082534Z","steps":["trace[973388311] 'agreement among raft nodes before linearized reading' (duration: 278.343355ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:40.082745Z","caller":"traceutil/trace.go:171","msg":"trace[1387849441] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"525.891518ms","start":"2026-10-04T13:35:39.55681Z","end":"2026-10-04T13:35:40.082701Z","steps":["trace[1387849441] 'process raft request' (duration: 524.690825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:40.082753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.584109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419049"} {"level":"info","ts":"2026-10-04T13:35:40.082784Z","caller":"traceutil/trace.go:171","msg":"trace[1265800754] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"304.299388ms","start":"2026-10-04T13:35:39.778454Z","end":"2026-10-04T13:35:40.082753Z","steps":["trace[1265800754] 'process raft request' (duration: 303.082135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:40.082764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.675676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:40.082857Z","caller":"traceutil/trace.go:171","msg":"trace[1751166794] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24977; }","duration":"250.760573ms","start":"2026-10-04T13:35:39.832071Z","end":"2026-10-04T13:35:40.082831Z","steps":["trace[1751166794] 'agreement among raft nodes before linearized reading' (duration: 250.299554ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:40.082898Z","caller":"traceutil/trace.go:171","msg":"trace[16847345] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:24977; }","duration":"178.882521ms","start":"2026-10-04T13:35:39.903982Z","end":"2026-10-04T13:35:40.082865Z","steps":["trace[16847345] 'agreement among raft nodes before linearized reading' (duration: 178.594125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:40.082918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:39.55677Z","time spent":"526.055321ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:40.082958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:39.778425Z","time spent":"304.433751ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:40.390987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.148396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:40.391068Z","caller":"traceutil/trace.go:171","msg":"trace[910604967] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24977; }","duration":"207.280209ms","start":"2026-10-04T13:35:40.183766Z","end":"2026-10-04T13:35:40.391046Z","steps":["trace[910604967] 'count revisions from in-memory index tree' (duration: 207.054634ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:40.69395Z","caller":"traceutil/trace.go:171","msg":"trace[2036200067] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"299.066909ms","start":"2026-10-04T13:35:40.394861Z","end":"2026-10-04T13:35:40.693928Z","steps":["trace[2036200067] 'process raft request' (duration: 298.951357ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:41.053741Z","caller":"traceutil/trace.go:171","msg":"trace[518517647] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"658.140864ms","start":"2026-10-04T13:35:40.395568Z","end":"2026-10-04T13:35:41.053709Z","steps":["trace[518517647] 'process raft request' (duration: 657.837158ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:41.053963Z","caller":"traceutil/trace.go:171","msg":"trace[1342875806] linearizableReadLoop","detail":"{readStateIndex:25795; appliedIndex:25793; }","duration":"570.684596ms","start":"2026-10-04T13:35:40.483237Z","end":"2026-10-04T13:35:41.053921Z","steps":["trace[1342875806] 'read index received' (duration: 210.662143ms)","trace[1342875806] 'applied index is now lower than readState.Index' (duration: 360.021183ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:41.053992Z","caller":"traceutil/trace.go:171","msg":"trace[1161762828] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"507.281895ms","start":"2026-10-04T13:35:40.546677Z","end":"2026-10-04T13:35:41.053958Z","steps":["trace[1161762828] 'process raft request' (duration: 507.00922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:41.054027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:40.395547Z","time spent":"658.278076ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:41.054132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:40.546644Z","time spent":"507.409897ms","remote":"127.0.0.1:40796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419107,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:41.054135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.88319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:41.054272Z","caller":"traceutil/trace.go:171","msg":"trace[1004038462] 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:24980; }","duration":"571.098145ms","start":"2026-10-04T13:35:40.483155Z","end":"2026-10-04T13:35:41.054253Z","steps":["trace[1004038462] 'agreement among raft nodes before linearized reading' (duration: 570.893951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:41.054322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:40.483125Z","time spent":"571.184536ms","remote":"127.0.0.1:36220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-10-04T13:35:41.207999Z","caller":"traceutil/trace.go:171","msg":"trace[739381111] linearizableReadLoop","detail":"{readStateIndex:25797; appliedIndex:25796; }","duration":"153.950268ms","start":"2026-10-04T13:35:41.054021Z","end":"2026-10-04T13:35:41.207971Z","steps":["trace[739381111] 'read index received' (duration: 141.669566ms)","trace[739381111] 'applied index is now lower than readState.Index' (duration: 12.279462ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:35:41.20825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.399211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-04T13:35:41.208314Z","caller":"traceutil/trace.go:171","msg":"trace[1221910861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24981; }","duration":"226.490593ms","start":"2026-10-04T13:35:40.981807Z","end":"2026-10-04T13:35:41.208298Z","steps":["trace[1221910861] 'agreement among raft nodes before linearized reading' (duration: 226.249778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:41.20858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.767718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:41.208546Z","caller":"traceutil/trace.go:171","msg":"trace[509710220] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"253.008966ms","start":"2026-10-04T13:35:40.955487Z","end":"2026-10-04T13:35:41.208496Z","steps":["trace[509710220] 'process raft request' (duration: 240.394627ms)","trace[509710220] 'compare' (duration: 11.841954ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:41.208616Z","caller":"traceutil/trace.go:171","msg":"trace[2123695091] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24981; }","duration":"169.833018ms","start":"2026-10-04T13:35:41.038772Z","end":"2026-10-04T13:35:41.208605Z","steps":["trace[2123695091] 'agreement among raft nodes before linearized reading' (duration: 169.774607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:41.208848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.73222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-04T13:35:41.208905Z","caller":"traceutil/trace.go:171","msg":"trace[586935638] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24981; }","duration":"454.846502ms","start":"2026-10-04T13:35:40.754042Z","end":"2026-10-04T13:35:41.208889Z","steps":["trace[586935638] 'agreement among raft nodes before linearized reading' (duration: 454.594237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:41.208938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:40.754019Z","time spent":"454.910643ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":557,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-04T13:35:41.208944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.275692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-04T13:35:41.208982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.112029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:35:41.209014Z","caller":"traceutil/trace.go:171","msg":"trace[205341219] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24981; }","duration":"232.388785ms","start":"2026-10-04T13:35:40.976608Z","end":"2026-10-04T13:35:41.208997Z","steps":["trace[205341219] 'agreement among raft nodes before linearized reading' (duration: 232.160271ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:41.209064Z","caller":"traceutil/trace.go:171","msg":"trace[1646422367] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24981; }","duration":"147.243592ms","start":"2026-10-04T13:35:41.0618Z","end":"2026-10-04T13:35:41.209044Z","steps":["trace[1646422367] 'agreement among raft nodes before linearized reading' (duration: 147.102929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:41.209005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.330009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T13:35:41.209172Z","caller":"traceutil/trace.go:171","msg":"trace[821684218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24981; }","duration":"247.516062ms","start":"2026-10-04T13:35:40.961613Z","end":"2026-10-04T13:35:41.209129Z","steps":["trace[821684218] 'agreement among raft nodes before linearized reading' (duration: 247.200696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:41.20926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.954956ms","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-10-04T13:35:41.20934Z","caller":"traceutil/trace.go:171","msg":"trace[458478179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24981; }","duration":"232.074798ms","start":"2026-10-04T13:35:40.977252Z","end":"2026-10-04T13:35:41.209327Z","steps":["trace[458478179] 'agreement among raft nodes before linearized reading' (duration: 230.851405ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:43.821365Z","caller":"traceutil/trace.go:171","msg":"trace[167205355] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"212.196422ms","start":"2026-10-04T13:35:43.60914Z","end":"2026-10-04T13:35:43.821337Z","steps":["trace[167205355] 'process raft request' (duration: 212.007719ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:35:50.504671Z","caller":"traceutil/trace.go:171","msg":"trace[1511112379] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"376.75235ms","start":"2026-10-04T13:35:50.127897Z","end":"2026-10-04T13:35:50.504649Z","steps":["trace[1511112379] 'process raft request' (duration: 376.619388ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:50.504812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:50.12787Z","time spent":"376.866502ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:50.52989Z","caller":"traceutil/trace.go:171","msg":"trace[315517026] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"398.966951ms","start":"2026-10-04T13:35:50.130903Z","end":"2026-10-04T13:35:50.52987Z","steps":["trace[315517026] 'process raft request' (duration: 398.796878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:50.530041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:50.130877Z","time spent":"399.085453ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:50.530071Z","caller":"traceutil/trace.go:171","msg":"trace[1365028358] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"399.050353ms","start":"2026-10-04T13:35:50.131001Z","end":"2026-10-04T13:35:50.530051Z","steps":["trace[1365028358] 'process raft request' (duration: 398.814518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:50.530165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:50.130992Z","time spent":"399.127654ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:35:50.530167Z","caller":"traceutil/trace.go:171","msg":"trace[2125708277] linearizableReadLoop","detail":"{readStateIndex:25951; appliedIndex:25947; }","duration":"346.218982ms","start":"2026-10-04T13:35:50.183922Z","end":"2026-10-04T13:35:50.530141Z","steps":["trace[2125708277] 'read index received' (duration: 320.658267ms)","trace[2125708277] 'applied index is now lower than readState.Index' (duration: 25.559595ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:35:50.530351Z","caller":"traceutil/trace.go:171","msg":"trace[1529204756] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"399.292447ms","start":"2026-10-04T13:35:50.131047Z","end":"2026-10-04T13:35:50.53034Z","steps":["trace[1529204756] 'process raft request' (duration: 398.941111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:50.530454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:50.131041Z","time spent":"399.339008ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:35:50.530574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.65927ms","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-10-04T13:35:50.530608Z","caller":"traceutil/trace.go:171","msg":"trace[65941299] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25133; }","duration":"346.724771ms","start":"2026-10-04T13:35:50.183875Z","end":"2026-10-04T13:35:50.5306Z","steps":["trace[65941299] 'agreement among raft nodes before linearized reading' (duration: 346.623859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:35:50.530629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:35:50.183859Z","time spent":"346.765502ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-10-04T13:36:21.486971Z","caller":"traceutil/trace.go:171","msg":"trace[622626123] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"124.418337ms","start":"2026-10-04T13:36:21.362525Z","end":"2026-10-04T13:36:21.486943Z","steps":["trace[622626123] 'process raft request' (duration: 124.219214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:24.129076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.504734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-10-04T13:36:24.12916Z","caller":"traceutil/trace.go:171","msg":"trace[1437701464] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25398; }","duration":"139.627467ms","start":"2026-10-04T13:36:23.989512Z","end":"2026-10-04T13:36:24.129139Z","steps":["trace[1437701464] 'range keys from in-memory index tree' (duration: 139.362542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:24.129384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.597747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:36:24.129419Z","caller":"traceutil/trace.go:171","msg":"trace[496232229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25398; }","duration":"139.643328ms","start":"2026-10-04T13:36:23.989767Z","end":"2026-10-04T13:36:24.12941Z","steps":["trace[496232229] 'range keys from in-memory index tree' (duration: 139.516485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:24.577545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.346778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-04T13:36:24.577635Z","caller":"traceutil/trace.go:171","msg":"trace[682468768] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25399; }","duration":"209.493501ms","start":"2026-10-04T13:36:24.368121Z","end":"2026-10-04T13:36:24.577615Z","steps":["trace[682468768] 'range keys from in-memory index tree' (duration: 209.138535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:25.171525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.913462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T13:36:25.171655Z","caller":"traceutil/trace.go:171","msg":"trace[466182994] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"493.561114ms","start":"2026-10-04T13:36:24.678081Z","end":"2026-10-04T13:36:25.171642Z","steps":["trace[466182994] 'process raft request' (duration: 493.512763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:25.171693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:36:24.678056Z","time spent":"493.618455ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:36:25.171774Z","caller":"traceutil/trace.go:171","msg":"trace[743797280] linearizableReadLoop","detail":"{readStateIndex:26228; appliedIndex:26225; }","duration":"472.506015ms","start":"2026-10-04T13:36:24.699261Z","end":"2026-10-04T13:36:25.171767Z","steps":["trace[743797280] 'read index received' (duration: 122.283327ms)","trace[743797280] 'applied index is now lower than readState.Index' (duration: 350.222048ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:25.171746Z","caller":"traceutil/trace.go:171","msg":"trace[185909867] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"515.163534ms","start":"2026-10-04T13:36:24.656539Z","end":"2026-10-04T13:36:25.171703Z","steps":["trace[185909867] 'process raft request' (duration: 165.002327ms)","trace[185909867] 'compare' (duration: 349.76097ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:25.171845Z","caller":"traceutil/trace.go:171","msg":"trace[572438956] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"492.439583ms","start":"2026-10-04T13:36:24.679401Z","end":"2026-10-04T13:36:25.17184Z","steps":["trace[572438956] 'process raft request' (duration: 492.222419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:25.171869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:36:24.679382Z","time spent":"492.471484ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:36:25.171919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.667218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:36:25.171933Z","caller":"traceutil/trace.go:171","msg":"trace[1575983112] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25403; }","duration":"472.71088ms","start":"2026-10-04T13:36:24.699218Z","end":"2026-10-04T13:36:25.171929Z","steps":["trace[1575983112] 'agreement among raft nodes before linearized reading' (duration: 472.679759ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:25.171945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:36:24.699204Z","time spent":"472.73895ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-10-04T13:36:25.17193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:36:24.656513Z","time spent":"515.315767ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:36:25.172071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.471237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:36:25.17212Z","caller":"traceutil/trace.go:171","msg":"trace[1378228913] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:25403; }","duration":"164.561319ms","start":"2026-10-04T13:36:25.007545Z","end":"2026-10-04T13:36:25.172106Z","steps":["trace[1378228913] 'agreement among raft nodes before linearized reading' (duration: 164.449857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:25.172498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.701305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T13:36:25.172556Z","caller":"traceutil/trace.go:171","msg":"trace[1831405656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25403; }","duration":"104.785846ms","start":"2026-10-04T13:36:25.067754Z","end":"2026-10-04T13:36:25.172539Z","steps":["trace[1831405656] 'agreement among raft nodes before linearized reading' (duration: 104.629183ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:25.347355Z","caller":"traceutil/trace.go:171","msg":"trace[1486397188] linearizableReadLoop","detail":"{readStateIndex:26229; appliedIndex:26228; }","duration":"170.254097ms","start":"2026-10-04T13:36:25.177079Z","end":"2026-10-04T13:36:25.347333Z","steps":["trace[1486397188] 'read index received' (duration: 97.706562ms)","trace[1486397188] 'applied index is now lower than readState.Index' (duration: 72.546765ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:25.347452Z","caller":"traceutil/trace.go:171","msg":"trace[1720237139] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"170.706975ms","start":"2026-10-04T13:36:25.176709Z","end":"2026-10-04T13:36:25.347416Z","steps":["trace[1720237139] 'process raft request' (duration: 98.15165ms)","trace[1720237139] 'compare' (duration: 72.372573ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:36:25.347814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.714255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19189"} {"level":"info","ts":"2026-10-04T13:36:25.347912Z","caller":"traceutil/trace.go:171","msg":"trace[1619395900] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25404; }","duration":"170.827027ms","start":"2026-10-04T13:36:25.177051Z","end":"2026-10-04T13:36:25.347878Z","steps":["trace[1619395900] 'agreement among raft nodes before linearized reading' (duration: 170.393039ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:25.621982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.187104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:36:25.622049Z","caller":"traceutil/trace.go:171","msg":"trace[619229669] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25405; }","duration":"124.305525ms","start":"2026-10-04T13:36:25.497727Z","end":"2026-10-04T13:36:25.622033Z","steps":["trace[619229669] 'count revisions from in-memory index tree' (duration: 124.06078ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:25.74803Z","caller":"traceutil/trace.go:171","msg":"trace[1797148866] linearizableReadLoop","detail":"{readStateIndex:26231; appliedIndex:26230; }","duration":"238.195615ms","start":"2026-10-04T13:36:25.509808Z","end":"2026-10-04T13:36:25.748004Z","steps":["trace[1797148866] 'read index received' (duration: 238.093513ms)","trace[1797148866] 'applied index is now lower than readState.Index' (duration: 100.662µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:25.748081Z","caller":"traceutil/trace.go:171","msg":"trace[1391813366] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"248.812526ms","start":"2026-10-04T13:36:25.499237Z","end":"2026-10-04T13:36:25.748049Z","steps":["trace[1391813366] 'process raft request' (duration: 248.619002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:25.748296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.47384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-04T13:36:25.748341Z","caller":"traceutil/trace.go:171","msg":"trace[2147356986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25406; }","duration":"238.561192ms","start":"2026-10-04T13:36:25.509768Z","end":"2026-10-04T13:36:25.748329Z","steps":["trace[2147356986] 'agreement among raft nodes before linearized reading' (duration: 238.367539ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:25.784988Z","caller":"traceutil/trace.go:171","msg":"trace[1898025740] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"118.875103ms","start":"2026-10-04T13:36:25.666086Z","end":"2026-10-04T13:36:25.784961Z","steps":["trace[1898025740] 'process raft request' (duration: 118.669659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:25.785035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.738936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-04T13:36:25.785092Z","caller":"traceutil/trace.go:171","msg":"trace[227483723] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25407; }","duration":"266.835018ms","start":"2026-10-04T13:36:25.518241Z","end":"2026-10-04T13:36:25.785076Z","steps":["trace[227483723] 'agreement among raft nodes before linearized reading' (duration: 266.642224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:25.78509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.500399ms","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-10-04T13:36:25.785155Z","caller":"traceutil/trace.go:171","msg":"trace[389182853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25407; }","duration":"259.59745ms","start":"2026-10-04T13:36:25.525541Z","end":"2026-10-04T13:36:25.785139Z","steps":["trace[389182853] 'agreement among raft nodes before linearized reading' (duration: 259.370216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:25.785241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.578326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-04T13:36:25.785328Z","caller":"traceutil/trace.go:171","msg":"trace[406932323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25407; }","duration":"263.696938ms","start":"2026-10-04T13:36:25.52161Z","end":"2026-10-04T13:36:25.785307Z","steps":["trace[406932323] 'agreement among raft nodes before linearized reading' (duration: 263.385102ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:25.980674Z","caller":"traceutil/trace.go:171","msg":"trace[618148927] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"228.228495ms","start":"2026-10-04T13:36:25.752176Z","end":"2026-10-04T13:36:25.980404Z","steps":["trace[618148927] 'process raft request' (duration: 228.037851ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:26.018565Z","caller":"traceutil/trace.go:171","msg":"trace[1115453898] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"229.52442ms","start":"2026-10-04T13:36:25.789012Z","end":"2026-10-04T13:36:26.018537Z","steps":["trace[1115453898] 'process raft request' (duration: 229.347147ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:26.018851Z","caller":"traceutil/trace.go:171","msg":"trace[296018758] transaction","detail":"{read_only:false; response_revision:25410; number_of_response:1; }","duration":"229.476ms","start":"2026-10-04T13:36:25.789353Z","end":"2026-10-04T13:36:26.018829Z","steps":["trace[296018758] 'process raft request' (duration: 229.143393ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:26.019072Z","caller":"traceutil/trace.go:171","msg":"trace[714561834] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"228.92301ms","start":"2026-10-04T13:36:25.790138Z","end":"2026-10-04T13:36:26.019061Z","steps":["trace[714561834] 'process raft request' (duration: 228.605034ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:26.019395Z","caller":"traceutil/trace.go:171","msg":"trace[521473562] transaction","detail":"{read_only:false; response_revision:25412; number_of_response:1; }","duration":"211.884786ms","start":"2026-10-04T13:36:25.807499Z","end":"2026-10-04T13:36:26.019384Z","steps":["trace[521473562] 'process raft request' (duration: 211.5164ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:26.550437Z","caller":"traceutil/trace.go:171","msg":"trace[128350519] linearizableReadLoop","detail":"{readStateIndex:26238; appliedIndex:26237; }","duration":"299.791232ms","start":"2026-10-04T13:36:26.250621Z","end":"2026-10-04T13:36:26.550412Z","steps":["trace[128350519] 'read index received' (duration: 299.509236ms)","trace[128350519] 'applied index is now lower than readState.Index' (duration: 281.046µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:26.550504Z","caller":"traceutil/trace.go:171","msg":"trace[1345526454] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"327.491537ms","start":"2026-10-04T13:36:26.222982Z","end":"2026-10-04T13:36:26.550474Z","steps":["trace[1345526454] 'process raft request' (duration: 327.267033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:26.550653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:36:26.222958Z","time spent":"327.609259ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:36:26.550689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.046007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-10-04T13:36:26.550701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.537828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T13:36:26.550764Z","caller":"traceutil/trace.go:171","msg":"trace[1557738861] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25413; }","duration":"300.164989ms","start":"2026-10-04T13:36:26.250579Z","end":"2026-10-04T13:36:26.550744Z","steps":["trace[1557738861] 'agreement among raft nodes before linearized reading' (duration: 299.922005ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:26.550784Z","caller":"traceutil/trace.go:171","msg":"trace[1498523247] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25413; }","duration":"108.666711ms","start":"2026-10-04T13:36:26.442094Z","end":"2026-10-04T13:36:26.55076Z","steps":["trace[1498523247] 'agreement among raft nodes before linearized reading' (duration: 108.484327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:26.550803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:36:26.250556Z","time spent":"300.23781ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-10-04T13:36:26.730903Z","caller":"traceutil/trace.go:171","msg":"trace[1230314657] linearizableReadLoop","detail":"{readStateIndex:26239; appliedIndex:26238; }","duration":"127.458946ms","start":"2026-10-04T13:36:26.603421Z","end":"2026-10-04T13:36:26.73088Z","steps":["trace[1230314657] 'read index received' (duration: 50.295664ms)","trace[1230314657] 'applied index is now lower than readState.Index' (duration: 77.161662ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:26.730931Z","caller":"traceutil/trace.go:171","msg":"trace[110576648] transaction","detail":"{read_only:false; response_revision:25414; number_of_response:1; }","duration":"174.743991ms","start":"2026-10-04T13:36:26.556157Z","end":"2026-10-04T13:36:26.730901Z","steps":["trace[110576648] 'process raft request' (duration: 97.545959ms)","trace[110576648] 'compare' (duration: 76.929197ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:36:26.731028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.584508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T13:36:26.731083Z","caller":"traceutil/trace.go:171","msg":"trace[963998746] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25414; }","duration":"127.661009ms","start":"2026-10-04T13:36:26.603388Z","end":"2026-10-04T13:36:26.731049Z","steps":["trace[963998746] 'agreement among raft nodes before linearized reading' (duration: 127.578148ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:26.813Z","caller":"traceutil/trace.go:171","msg":"trace[1333466844] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"111.893041ms","start":"2026-10-04T13:36:26.701076Z","end":"2026-10-04T13:36:26.812969Z","steps":["trace[1333466844] 'process raft request' (duration: 111.740898ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:27.298088Z","caller":"traceutil/trace.go:171","msg":"trace[1699269932] transaction","detail":"{read_only:false; response_revision:25416; number_of_response:1; }","duration":"121.180738ms","start":"2026-10-04T13:36:27.176883Z","end":"2026-10-04T13:36:27.298063Z","steps":["trace[1699269932] 'process raft request' (duration: 121.042425ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:27.482912Z","caller":"traceutil/trace.go:171","msg":"trace[2088584831] linearizableReadLoop","detail":"{readStateIndex:26243; appliedIndex:26241; }","duration":"132.025502ms","start":"2026-10-04T13:36:27.35086Z","end":"2026-10-04T13:36:27.482886Z","steps":["trace[2088584831] 'read index received' (duration: 131.821888ms)","trace[2088584831] 'applied index is now lower than readState.Index' (duration: 202.144µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:27.482952Z","caller":"traceutil/trace.go:171","msg":"trace[2131289066] transaction","detail":"{read_only:false; response_revision:25418; number_of_response:1; }","duration":"303.251087ms","start":"2026-10-04T13:36:27.179683Z","end":"2026-10-04T13:36:27.482934Z","steps":["trace[2131289066] 'process raft request' (duration: 303.157675ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:27.482977Z","caller":"traceutil/trace.go:171","msg":"trace[204879582] transaction","detail":"{read_only:false; response_revision:25417; number_of_response:1; }","duration":"304.384389ms","start":"2026-10-04T13:36:27.178557Z","end":"2026-10-04T13:36:27.482942Z","steps":["trace[204879582] 'process raft request' (duration: 304.113214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:27.483052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:36:27.179665Z","time spent":"303.33619ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T13:36:27.483154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.271547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-04T13:36:27.483221Z","caller":"traceutil/trace.go:171","msg":"trace[1454178294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25418; }","duration":"132.38722ms","start":"2026-10-04T13:36:27.350822Z","end":"2026-10-04T13:36:27.483209Z","steps":["trace[1454178294] 'agreement among raft nodes before linearized reading' (duration: 132.183806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:27.483642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T13:36:27.178529Z","time spent":"304.515782ms","remote":"127.0.0.1:54556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T13:36:27.518424Z","caller":"traceutil/trace.go:171","msg":"trace[238353483] transaction","detail":"{read_only:false; response_revision:25419; number_of_response:1; }","duration":"135.677273ms","start":"2026-10-04T13:36:27.382692Z","end":"2026-10-04T13:36:27.51837Z","steps":["trace[238353483] 'process raft request' (duration: 134.840346ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:27.754224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.328182ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-10-04T13:36:27.754622Z","caller":"traceutil/trace.go:171","msg":"trace[978933314] transaction","detail":"{read_only:false; response_revision:25421; number_of_response:1; }","duration":"229.863407ms","start":"2026-10-04T13:36:27.524739Z","end":"2026-10-04T13:36:27.754603Z","steps":["trace[978933314] 'process raft request' (duration: 229.564131ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:28.014568Z","caller":"traceutil/trace.go:171","msg":"trace[47338382] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"224.699089ms","start":"2026-10-04T13:36:27.789852Z","end":"2026-10-04T13:36:28.014551Z","steps":["trace[47338382] 'process raft request' (duration: 224.653368ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:28.014779Z","caller":"traceutil/trace.go:171","msg":"trace[879182284] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"258.444348ms","start":"2026-10-04T13:36:27.756323Z","end":"2026-10-04T13:36:28.014768Z","steps":["trace[879182284] 'process raft request' (duration: 258.061871ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:28.015172Z","caller":"traceutil/trace.go:171","msg":"trace[179412895] linearizableReadLoop","detail":"{readStateIndex:26250; appliedIndex:26250; }","duration":"167.317062ms","start":"2026-10-04T13:36:27.847846Z","end":"2026-10-04T13:36:28.015163Z","steps":["trace[179412895] 'read index received' (duration: 167.313262ms)","trace[179412895] 'applied index is now lower than readState.Index' (duration: 3.07µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:36:28.01544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.582516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:36:28.015471Z","caller":"traceutil/trace.go:171","msg":"trace[245883377] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25424; }","duration":"167.654458ms","start":"2026-10-04T13:36:27.847808Z","end":"2026-10-04T13:36:28.015463Z","steps":["trace[245883377] 'agreement among raft nodes before linearized reading' (duration: 167.594167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:28.164172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.666944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T13:36:28.164365Z","caller":"traceutil/trace.go:171","msg":"trace[936267751] linearizableReadLoop","detail":"{readStateIndex:26254; appliedIndex:26251; }","duration":"110.014295ms","start":"2026-10-04T13:36:28.054338Z","end":"2026-10-04T13:36:28.164352Z","steps":["trace[936267751] 'read index received' (duration: 62.398623ms)","trace[936267751] 'applied index is now lower than readState.Index' (duration: 47.614562ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:28.164375Z","caller":"traceutil/trace.go:171","msg":"trace[1253997692] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"136.810083ms","start":"2026-10-04T13:36:28.027542Z","end":"2026-10-04T13:36:28.164352Z","steps":["trace[1253997692] 'process raft request' (duration: 22.887764ms)","trace[1253997692] 'compare' (duration: 113.561212ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:28.164496Z","caller":"traceutil/trace.go:171","msg":"trace[522666302] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"135.428677ms","start":"2026-10-04T13:36:28.029056Z","end":"2026-10-04T13:36:28.164485Z","steps":["trace[522666302] 'process raft request' (duration: 135.203592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:28.164685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.333951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:186"} {"level":"info","ts":"2026-10-04T13:36:28.164711Z","caller":"traceutil/trace.go:171","msg":"trace[1470006390] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:25428; }","duration":"110.403042ms","start":"2026-10-04T13:36:28.054299Z","end":"2026-10-04T13:36:28.164702Z","steps":["trace[1470006390] 'agreement among raft nodes before linearized reading' (duration: 110.29706ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:28.164815Z","caller":"traceutil/trace.go:171","msg":"trace[669771589] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"135.693332ms","start":"2026-10-04T13:36:28.029115Z","end":"2026-10-04T13:36:28.164808Z","steps":["trace[669771589] 'process raft request' (duration: 135.209072ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:28.645529Z","caller":"traceutil/trace.go:171","msg":"trace[863556575] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"115.472988ms","start":"2026-10-04T13:36:28.530026Z","end":"2026-10-04T13:36:28.645499Z","steps":["trace[863556575] 'process raft request' (duration: 115.143183ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:28.682216Z","caller":"traceutil/trace.go:171","msg":"trace[1578615058] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"126.363775ms","start":"2026-10-04T13:36:28.555794Z","end":"2026-10-04T13:36:28.682158Z","steps":["trace[1578615058] 'process raft request' (duration: 126.275653ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:28.942433Z","caller":"traceutil/trace.go:171","msg":"trace[1646051] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"122.566762ms","start":"2026-10-04T13:36:28.819839Z","end":"2026-10-04T13:36:28.942406Z","steps":["trace[1646051] 'process raft request' (duration: 122.41754ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:29.594224Z","caller":"traceutil/trace.go:171","msg":"trace[2112595794] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"106.787674ms","start":"2026-10-04T13:36:29.487369Z","end":"2026-10-04T13:36:29.594156Z","steps":["trace[2112595794] 'process raft request' (duration: 47.906308ms)","trace[2112595794] 'compare' (duration: 58.777034ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:29.664708Z","caller":"traceutil/trace.go:171","msg":"trace[1234752145] linearizableReadLoop","detail":"{readStateIndex:26261; appliedIndex:26259; }","duration":"160.667244ms","start":"2026-10-04T13:36:29.504021Z","end":"2026-10-04T13:36:29.664688Z","steps":["trace[1234752145] 'read index received' (duration: 31.329874ms)","trace[1234752145] 'applied index is now lower than readState.Index' (duration: 129.3357ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:29.664792Z","caller":"traceutil/trace.go:171","msg":"trace[305950183] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"175.937565ms","start":"2026-10-04T13:36:29.488825Z","end":"2026-10-04T13:36:29.664762Z","steps":["trace[305950183] 'process raft request' (duration: 175.763181ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:29.66484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.794587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:36:29.66488Z","caller":"traceutil/trace.go:171","msg":"trace[1377966307] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25435; }","duration":"160.868139ms","start":"2026-10-04T13:36:29.503998Z","end":"2026-10-04T13:36:29.664866Z","steps":["trace[1377966307] 'agreement among raft nodes before linearized reading' (duration: 160.788447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:29.665135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.282226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T13:36:29.665223Z","caller":"traceutil/trace.go:171","msg":"trace[1704739807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25435; }","duration":"142.426309ms","start":"2026-10-04T13:36:29.522778Z","end":"2026-10-04T13:36:29.665205Z","steps":["trace[1704739807] 'agreement among raft nodes before linearized reading' (duration: 142.167274ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:29.979699Z","caller":"traceutil/trace.go:171","msg":"trace[1666606795] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"152.636393ms","start":"2026-10-04T13:36:29.827041Z","end":"2026-10-04T13:36:29.979677Z","steps":["trace[1666606795] 'process raft request' (duration: 80.944243ms)","trace[1666606795] 'compare' (duration: 71.565727ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:30.179706Z","caller":"traceutil/trace.go:171","msg":"trace[2060416455] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"124.428348ms","start":"2026-10-04T13:36:30.055262Z","end":"2026-10-04T13:36:30.17969Z","steps":["trace[2060416455] 'process raft request' (duration: 124.282936ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:52.8726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.955509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:36:52.872715Z","caller":"traceutil/trace.go:171","msg":"trace[2126001329] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25616; }","duration":"124.121082ms","start":"2026-10-04T13:36:52.748566Z","end":"2026-10-04T13:36:52.872687Z","steps":["trace[2126001329] 'count revisions from in-memory index tree' (duration: 123.871497ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:53.141929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.552819ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-04T13:36:53.142222Z","caller":"traceutil/trace.go:171","msg":"trace[360234554] linearizableReadLoop","detail":"{readStateIndex:26449; appliedIndex:26448; }","duration":"144.662722ms","start":"2026-10-04T13:36:52.997507Z","end":"2026-10-04T13:36:53.142169Z","steps":["trace[360234554] 'read index received' (duration: 18.812406ms)","trace[360234554] 'applied index is now lower than readState.Index' (duration: 125.847386ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T13:36:53.142476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.868995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:36:53.142511Z","caller":"traceutil/trace.go:171","msg":"trace[264563431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25618; }","duration":"145.060089ms","start":"2026-10-04T13:36:52.997441Z","end":"2026-10-04T13:36:53.142501Z","steps":["trace[264563431] 'agreement among raft nodes before linearized reading' (duration: 144.849625ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T13:36:53.393416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.918636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T13:36:53.393517Z","caller":"traceutil/trace.go:171","msg":"trace[1946760489] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:25618; }","duration":"155.102549ms","start":"2026-10-04T13:36:53.238389Z","end":"2026-10-04T13:36:53.393492Z","steps":["trace[1946760489] 'count revisions from in-memory index tree' (duration: 154.720992ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:53.674426Z","caller":"traceutil/trace.go:171","msg":"trace[1993489092] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"103.270077ms","start":"2026-10-04T13:36:53.571118Z","end":"2026-10-04T13:36:53.674388Z","steps":["trace[1993489092] 'process raft request' (duration: 88.910085ms)","trace[1993489092] 'compare' (duration: 14.197989ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:53.863268Z","caller":"traceutil/trace.go:171","msg":"trace[734628703] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"150.458153ms","start":"2026-10-04T13:36:53.712781Z","end":"2026-10-04T13:36:53.863239Z","steps":["trace[734628703] 'process raft request' (duration: 74.211488ms)","trace[734628703] 'compare' (duration: 75.581932ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:53.938888Z","caller":"traceutil/trace.go:171","msg":"trace[1270531965] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"120.514974ms","start":"2026-10-04T13:36:53.817297Z","end":"2026-10-04T13:36:53.937812Z","steps":["trace[1270531965] 'process raft request' (duration: 120.377232ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:36:54.043604Z","caller":"traceutil/trace.go:171","msg":"trace[1218437271] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"100.81135ms","start":"2026-10-04T13:36:53.942755Z","end":"2026-10-04T13:36:54.043566Z","steps":["trace[1218437271] 'process raft request' (duration: 35.154106ms)","trace[1218437271] 'compare' (duration: 65.123174ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T13:36:54.068432Z","caller":"traceutil/trace.go:171","msg":"trace[1568518537] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"102.666286ms","start":"2026-10-04T13:36:53.965735Z","end":"2026-10-04T13:36:54.068401Z","steps":["trace[1568518537] 'process raft request' (duration: 102.477582ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T13:37:04.853862Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006813.wal"} {"level":"info","ts":"2026-10-04T13:37:14.075224Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22942} {"level":"info","ts":"2026-10-04T13:37:14.321994Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22942,"took":"242.1194ms","hash":3570057516,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":36945920,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-04T13:37:14.322139Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3570057516,"revision":22942,"compact-revision":20087} {"level":"info","ts":"2026-10-04T13:42:14.083209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25936} {"level":"info","ts":"2026-10-04T13:42:14.263856Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25936,"took":"173.485368ms","hash":225811398,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":34586624,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-04T13:42:14.263941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":225811398,"revision":25936,"compact-revision":22942} {"level":"info","ts":"2026-10-04T13:43:14.605374Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T13:43:14.61127Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-04T13:43:14.611384Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-04T13:47:14.08968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28636} {"level":"info","ts":"2026-10-04T13:47:14.250409Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28636,"took":"156.419157ms","hash":1098926353,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":30244864,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-04T13:47:14.250488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1098926353,"revision":28636,"compact-revision":25936} {"level":"info","ts":"2026-10-04T13:52:14.095753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30858} {"level":"info","ts":"2026-10-04T13:52:14.217715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30858,"took":"117.55558ms","hash":3978732776,"current-db-size-bytes":56037376,"current-db-size":"56 MB","current-db-size-in-use-bytes":31166464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T13:52:14.217786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3978732776,"revision":30858,"compact-revision":28636} {"level":"info","ts":"2026-10-04T13:57:14.102948Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33122} {"level":"info","ts":"2026-10-04T13:57:14.266987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33122,"took":"159.68335ms","hash":2563254287,"current-db-size-bytes":57077760,"current-db-size":"57 MB","current-db-size-in-use-bytes":31272960,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T13:57:14.267052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2563254287,"revision":33122,"compact-revision":30858} {"level":"info","ts":"2026-10-04T14:02:14.110642Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35411} {"level":"info","ts":"2026-10-04T14:02:14.24622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35411,"took":"131.782639ms","hash":1630552859,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":33439744,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-04T14:02:14.246506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1630552859,"revision":35411,"compact-revision":33122} {"level":"info","ts":"2026-10-04T14:04:36.669232Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a5b61ed9120d6b3","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T14:04:36.676092Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-04T14:04:36.676357Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-04T14:07:14.117515Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37704} {"level":"info","ts":"2026-10-04T14:07:14.259079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37704,"took":"136.99002ms","hash":1750809249,"current-db-size-bytes":57970688,"current-db-size":"58 MB","current-db-size-in-use-bytes":31854592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T14:07:14.259156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1750809249,"revision":37704,"compact-revision":35411}