{"level":"warn","ts":"2026-10-03T05:47:26.578704Z","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-03T05:47:26.578801Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.147: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.147:2380","--initial-cluster=instance=https://162.253.55.147:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.147:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.147: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-03T05:47:26.578886Z","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-03T05:47:26.578901Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.147:2380"]} {"level":"info","ts":"2026-10-03T05:47:26.578924Z","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-03T05:47:26.579948Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.147:2379"]} {"level":"info","ts":"2026-10-03T05:47:26.580043Z","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.147:2380"],"listen-peer-urls":["https://162.253.55.147:2380"],"advertise-client-urls":["https://162.253.55.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.147: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-03T05:47:26.587613Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.275809ms"} {"level":"info","ts":"2026-10-03T05:47:26.597692Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4d4c1b1786e4e6dd","cluster-id":"c387147481076682"} {"level":"info","ts":"2026-10-03T05:47:26.597812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd switched to configuration voters=()"} {"level":"info","ts":"2026-10-03T05:47:26.597844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became follower at term 0"} {"level":"info","ts":"2026-10-03T05:47:26.597861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4d4c1b1786e4e6dd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-03T05:47:26.597879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became follower at term 1"} {"level":"info","ts":"2026-10-03T05:47:26.597936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd switched to configuration voters=(5569856627011806941)"} {"level":"warn","ts":"2026-10-03T05:47:26.603132Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-03T05:47:26.605336Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-03T05:47:26.607162Z","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-03T05:47:26.610332Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4d4c1b1786e4e6dd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-03T05:47:26.610559Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4d4c1b1786e4e6dd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-03T05:47:26.610672Z","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-03T05:47:26.610733Z","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-03T05:47:26.610763Z","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-03T05:47:26.611938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd switched to configuration voters=(5569856627011806941)"} {"level":"info","ts":"2026-10-03T05:47:26.612178Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c387147481076682","local-member-id":"4d4c1b1786e4e6dd","added-peer-id":"4d4c1b1786e4e6dd","added-peer-peer-urls":["https://162.253.55.147:2380"]} {"level":"info","ts":"2026-10-03T05:47:26.615365Z","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-03T05:47:26.615489Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.147:2380"} {"level":"info","ts":"2026-10-03T05:47:26.615545Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.147:2380"} {"level":"info","ts":"2026-10-03T05:47:26.615873Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4d4c1b1786e4e6dd","initial-advertise-peer-urls":["https://162.253.55.147:2380"],"listen-peer-urls":["https://162.253.55.147:2380"],"advertise-client-urls":["https://162.253.55.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-03T05:47:26.615927Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-03T05:47:26.898724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd is starting a new election at term 1"} {"level":"info","ts":"2026-10-03T05:47:26.89889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became pre-candidate at term 1"} {"level":"info","ts":"2026-10-03T05:47:26.899001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd received MsgPreVoteResp from 4d4c1b1786e4e6dd at term 1"} {"level":"info","ts":"2026-10-03T05:47:26.899094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became candidate at term 2"} {"level":"info","ts":"2026-10-03T05:47:26.89916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd received MsgVoteResp from 4d4c1b1786e4e6dd at term 2"} {"level":"info","ts":"2026-10-03T05:47:26.89924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became leader at term 2"} {"level":"info","ts":"2026-10-03T05:47:26.899302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4d4c1b1786e4e6dd elected leader 4d4c1b1786e4e6dd at term 2"} {"level":"info","ts":"2026-10-03T05:47:26.900464Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T05:47:26.902038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T05:47:26.90234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T05:47:26.902499Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c387147481076682","local-member-id":"4d4c1b1786e4e6dd","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T05:47:26.902603Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T05:47:26.902633Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T05:47:26.902016Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4d4c1b1786e4e6dd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.147:2379]}","request-path":"/0/members/4d4c1b1786e4e6dd/attributes","cluster-id":"c387147481076682","publish-timeout":"7s"} {"level":"info","ts":"2026-10-03T05:47:26.902793Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-03T05:47:26.902851Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-03T05:47:26.906137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.147:2379"} {"level":"info","ts":"2026-10-03T05:47:26.906283Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-03T05:48:07.043255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.841592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T05:48:07.043826Z","caller":"traceutil/trace.go:171","msg":"trace[62494357] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:604; }","duration":"101.413093ms","start":"2026-10-03T05:48:06.942394Z","end":"2026-10-03T05:48:07.043807Z","steps":["trace[62494357] 'count revisions from in-memory index tree' (duration: 100.799342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:48:07.043278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.630242ms","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-03T05:48:07.044026Z","caller":"traceutil/trace.go:171","msg":"trace[1362198430] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"132.433898ms","start":"2026-10-03T05:48:06.91158Z","end":"2026-10-03T05:48:07.044014Z","steps":["trace[1362198430] 'count revisions from in-memory index tree' (duration: 131.48065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:48:07.043971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.67907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T05:48:07.044166Z","caller":"traceutil/trace.go:171","msg":"trace[979506220] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:604; }","duration":"101.702909ms","start":"2026-10-03T05:48:06.942434Z","end":"2026-10-03T05:48:07.044137Z","steps":["trace[979506220] 'range keys from in-memory index tree' (duration: 100.567087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:48:07.043313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.617643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T05:48:07.04337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.380533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T05:48:07.044326Z","caller":"traceutil/trace.go:171","msg":"trace[171625680] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:604; }","duration":"101.390523ms","start":"2026-10-03T05:48:06.942927Z","end":"2026-10-03T05:48:07.044317Z","steps":["trace[171625680] 'range keys from in-memory index tree' (duration: 100.325023ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:48:07.044338Z","caller":"traceutil/trace.go:171","msg":"trace[1001440940] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:604; }","duration":"132.625422ms","start":"2026-10-03T05:48:06.911677Z","end":"2026-10-03T05:48:07.044303Z","steps":["trace[1001440940] 'range keys from in-memory index tree' (duration: 131.578172ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:48:10.903335Z","caller":"traceutil/trace.go:171","msg":"trace[1134493906] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"144.325925ms","start":"2026-10-03T05:48:10.758981Z","end":"2026-10-03T05:48:10.903307Z","steps":["trace[1134493906] 'process raft request' (duration: 135.539657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:48:11.406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.160054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-03T05:48:11.406184Z","caller":"traceutil/trace.go:171","msg":"trace[1198854757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"250.398459ms","start":"2026-10-03T05:48:11.155761Z","end":"2026-10-03T05:48:11.40616Z","steps":["trace[1198854757] 'range keys from in-memory index tree' (duration: 249.973701ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:48:11.412351Z","caller":"traceutil/trace.go:171","msg":"trace[2039866467] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"245.672228ms","start":"2026-10-03T05:48:11.166653Z","end":"2026-10-03T05:48:11.412325Z","steps":["trace[2039866467] 'process raft request' (duration: 244.863993ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:49:22.940064Z","caller":"traceutil/trace.go:171","msg":"trace[685762799] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"155.479799ms","start":"2026-10-03T05:49:22.78456Z","end":"2026-10-03T05:49:22.94004Z","steps":["trace[685762799] 'process raft request' (duration: 77.656977ms)","trace[685762799] 'compare' (duration: 77.72937ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T05:49:48.443936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.369543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T05:49:48.444041Z","caller":"traceutil/trace.go:171","msg":"trace[1032940539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1889; }","duration":"139.527636ms","start":"2026-10-03T05:49:48.304491Z","end":"2026-10-03T05:49:48.444019Z","steps":["trace[1032940539] 'range keys from in-memory index tree' (duration: 139.266271ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:49:49.088021Z","caller":"traceutil/trace.go:171","msg":"trace[1288102928] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1961; }","duration":"197.292864ms","start":"2026-10-03T05:49:48.890705Z","end":"2026-10-03T05:49:49.087998Z","steps":["trace[1288102928] 'read index received' (duration: 197.0712ms)","trace[1288102928] 'applied index is now lower than readState.Index' (duration: 220.184µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T05:49:49.088136Z","caller":"traceutil/trace.go:171","msg":"trace[778081435] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"198.052778ms","start":"2026-10-03T05:49:48.890029Z","end":"2026-10-03T05:49:49.088081Z","steps":["trace[778081435] 'process raft request' (duration: 197.805434ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:49:49.088251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.522418ms","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-03T05:49:49.088291Z","caller":"traceutil/trace.go:171","msg":"trace[1021975383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1890; }","duration":"197.645701ms","start":"2026-10-03T05:49:48.890633Z","end":"2026-10-03T05:49:49.088279Z","steps":["trace[1021975383] 'agreement among raft nodes before linearized reading' (duration: 197.492478ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:50:03.050193Z","caller":"traceutil/trace.go:171","msg":"trace[1442115926] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"122.622893ms","start":"2026-10-03T05:50:02.927548Z","end":"2026-10-03T05:50:03.050171Z","steps":["trace[1442115926] 'process raft request' (duration: 122.364168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:50:33.686726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.530594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T05:50:33.686838Z","caller":"traceutil/trace.go:171","msg":"trace[1568402473] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"151.043466ms","start":"2026-10-03T05:50:33.535775Z","end":"2026-10-03T05:50:33.686818Z","steps":["trace[1568402473] 'read index received' (duration: 49.805998ms)","trace[1568402473] 'applied index is now lower than readState.Index' (duration: 101.236087ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T05:50:33.686938Z","caller":"traceutil/trace.go:171","msg":"trace[1362053899] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"173.391557ms","start":"2026-10-03T05:50:33.513531Z","end":"2026-10-03T05:50:33.686923Z","steps":["trace[1362053899] 'process raft request' (duration: 72.091148ms)","trace[1362053899] 'compare' (duration: 100.428222ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T05:50:33.687264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.466214ms","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-03T05:50:33.687338Z","caller":"traceutil/trace.go:171","msg":"trace[1273911709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2194; }","duration":"151.552535ms","start":"2026-10-03T05:50:33.535769Z","end":"2026-10-03T05:50:33.687321Z","steps":["trace[1273911709] 'agreement among raft nodes before linearized reading' (duration: 151.354271ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:50:33.872202Z","caller":"traceutil/trace.go:171","msg":"trace[793435779] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"179.341998ms","start":"2026-10-03T05:50:33.692837Z","end":"2026-10-03T05:50:33.872179Z","steps":["trace[793435779] 'process raft request' (duration: 179.289638ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:50:33.872266Z","caller":"traceutil/trace.go:171","msg":"trace[1129821125] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"180.785775ms","start":"2026-10-03T05:50:33.691458Z","end":"2026-10-03T05:50:33.872244Z","steps":["trace[1129821125] 'process raft request' (duration: 117.094246ms)","trace[1129821125] 'compare' (duration: 63.457366ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T05:50:34.118731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.146825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-03T05:50:34.118809Z","caller":"traceutil/trace.go:171","msg":"trace[995259590] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2198; }","duration":"118.253077ms","start":"2026-10-03T05:50:34.000536Z","end":"2026-10-03T05:50:34.118789Z","steps":["trace[995259590] 'range keys from in-memory index tree' (duration: 117.974042ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:50:39.993641Z","caller":"traceutil/trace.go:171","msg":"trace[1836941656] linearizableReadLoop","detail":"{readStateIndex:2310; appliedIndex:2309; }","duration":"100.374291ms","start":"2026-10-03T05:50:39.893244Z","end":"2026-10-03T05:50:39.993619Z","steps":["trace[1836941656] 'read index received' (duration: 100.213338ms)","trace[1836941656] 'applied index is now lower than readState.Index' (duration: 160.083µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T05:50:39.9939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.638276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-03T05:50:39.994008Z","caller":"traceutil/trace.go:171","msg":"trace[1250605311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2226; }","duration":"100.750198ms","start":"2026-10-03T05:50:39.893207Z","end":"2026-10-03T05:50:39.993957Z","steps":["trace[1250605311] 'agreement among raft nodes before linearized reading' (duration: 100.574214ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:50:39.994349Z","caller":"traceutil/trace.go:171","msg":"trace[1529566834] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"285.217864ms","start":"2026-10-03T05:50:39.708534Z","end":"2026-10-03T05:50:39.993752Z","steps":["trace[1529566834] 'process raft request' (duration: 284.951419ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:50:40.205178Z","caller":"traceutil/trace.go:171","msg":"trace[1490033009] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"308.659544ms","start":"2026-10-03T05:50:39.896494Z","end":"2026-10-03T05:50:40.205154Z","steps":["trace[1490033009] 'process raft request' (duration: 308.46587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:50:40.205332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:50:39.896477Z","time spent":"308.773047ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T05:50:40.205388Z","caller":"traceutil/trace.go:171","msg":"trace[1156680596] linearizableReadLoop","detail":"{readStateIndex:2312; appliedIndex:2310; }","duration":"211.547485ms","start":"2026-10-03T05:50:39.993813Z","end":"2026-10-03T05:50:40.205361Z","steps":["trace[1156680596] 'read index received' (duration: 211.162678ms)","trace[1156680596] 'applied index is now lower than readState.Index' (duration: 383.037µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T05:50:40.205413Z","caller":"traceutil/trace.go:171","msg":"trace[153784929] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"217.813223ms","start":"2026-10-03T05:50:39.987571Z","end":"2026-10-03T05:50:40.205384Z","steps":["trace[153784929] 'process raft request' (duration: 217.535968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:50:40.205718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.439037ms","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-03T05:50:40.20598Z","caller":"traceutil/trace.go:171","msg":"trace[196212515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2228; }","duration":"311.583191ms","start":"2026-10-03T05:50:39.894186Z","end":"2026-10-03T05:50:40.205769Z","steps":["trace[196212515] 'agreement among raft nodes before linearized reading' (duration: 311.278005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:50:40.20607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:50:39.894176Z","time spent":"311.880676ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-03T05:50:40.228923Z","caller":"traceutil/trace.go:171","msg":"trace[1891852935] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"230.721537ms","start":"2026-10-03T05:50:39.998176Z","end":"2026-10-03T05:50:40.228897Z","steps":["trace[1891852935] 'process raft request' (duration: 230.622915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:50:40.509757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.942215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-03T05:50:40.509851Z","caller":"traceutil/trace.go:171","msg":"trace[317245207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2231; }","duration":"211.069577ms","start":"2026-10-03T05:50:40.298759Z","end":"2026-10-03T05:50:40.509828Z","steps":["trace[317245207] 'range keys from in-memory index tree' (duration: 210.768101ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:50:40.695343Z","caller":"traceutil/trace.go:171","msg":"trace[1040574486] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"112.804505ms","start":"2026-10-03T05:50:40.582517Z","end":"2026-10-03T05:50:40.695321Z","steps":["trace[1040574486] 'process raft request' (duration: 106.830792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:54:44.341151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.777786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T05:54:44.341243Z","caller":"traceutil/trace.go:171","msg":"trace[887784852] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:3875; }","duration":"121.929559ms","start":"2026-10-03T05:54:44.219294Z","end":"2026-10-03T05:54:44.341224Z","steps":["trace[887784852] 'count revisions from in-memory index tree' (duration: 121.695325ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:56:45.550989Z","caller":"traceutil/trace.go:171","msg":"trace[1342516303] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"110.061646ms","start":"2026-10-03T05:56:45.440895Z","end":"2026-10-03T05:56:45.550956Z","steps":["trace[1342516303] 'process raft request' (duration: 109.851802ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:56:49.498718Z","caller":"traceutil/trace.go:171","msg":"trace[364771191] linearizableReadLoop","detail":"{readStateIndex:5497; appliedIndex:5496; }","duration":"115.970849ms","start":"2026-10-03T05:56:49.382723Z","end":"2026-10-03T05:56:49.498694Z","steps":["trace[364771191] 'read index received' (duration: 115.749354ms)","trace[364771191] 'applied index is now lower than readState.Index' (duration: 220.215µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T05:56:49.498807Z","caller":"traceutil/trace.go:171","msg":"trace[1007789707] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"128.890685ms","start":"2026-10-03T05:56:49.369883Z","end":"2026-10-03T05:56:49.498773Z","steps":["trace[1007789707] 'process raft request' (duration: 128.578999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:56:49.498874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.126082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T05:56:49.498927Z","caller":"traceutil/trace.go:171","msg":"trace[641277416] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5293; }","duration":"116.220314ms","start":"2026-10-03T05:56:49.382692Z","end":"2026-10-03T05:56:49.498913Z","steps":["trace[641277416] 'agreement among raft nodes before linearized reading' (duration: 116.111592ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:56:49.554059Z","caller":"traceutil/trace.go:171","msg":"trace[187998547] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"148.126723ms","start":"2026-10-03T05:56:49.405896Z","end":"2026-10-03T05:56:49.554022Z","steps":["trace[187998547] 'process raft request' (duration: 132.008665ms)","trace[187998547] 'compare' (duration: 15.950025ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T05:56:49.554238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.77873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fpbf5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-03T05:56:49.554305Z","caller":"traceutil/trace.go:171","msg":"trace[1107140622] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fpbf5; range_end:; response_count:1; response_revision:5294; }","duration":"167.877211ms","start":"2026-10-03T05:56:49.386411Z","end":"2026-10-03T05:56:49.554288Z","steps":["trace[1107140622] 'agreement among raft nodes before linearized reading' (duration: 167.693417ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:56:49.875573Z","caller":"traceutil/trace.go:171","msg":"trace[646104252] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"145.408741ms","start":"2026-10-03T05:56:49.730142Z","end":"2026-10-03T05:56:49.875551Z","steps":["trace[646104252] 'process raft request' (duration: 145.270419ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:57:05.594622Z","caller":"traceutil/trace.go:171","msg":"trace[1208674831] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"353.313937ms","start":"2026-10-03T05:57:05.241281Z","end":"2026-10-03T05:57:05.594594Z","steps":["trace[1208674831] 'process raft request' (duration: 352.997062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:57:05.594837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:57:05.241265Z","time spent":"353.473081ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T05:57:05.594966Z","caller":"traceutil/trace.go:171","msg":"trace[2066175607] linearizableReadLoop","detail":"{readStateIndex:5791; appliedIndex:5790; }","duration":"318.635966ms","start":"2026-10-03T05:57:05.275835Z","end":"2026-10-03T05:57:05.594471Z","steps":["trace[2066175607] 'read index received' (duration: 318.536784ms)","trace[2066175607] 'applied index is now lower than readState.Index' (duration: 98.192µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T05:57:05.595081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.183165ms","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-03T05:57:05.595229Z","caller":"traceutil/trace.go:171","msg":"trace[1150053591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5579; }","duration":"319.40088ms","start":"2026-10-03T05:57:05.275793Z","end":"2026-10-03T05:57:05.595194Z","steps":["trace[1150053591] 'agreement among raft nodes before linearized reading' (duration: 318.932871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:57:05.595307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:57:05.275775Z","time spent":"319.516092ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-03T05:57:05.610626Z","caller":"traceutil/trace.go:171","msg":"trace[1540282862] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"321.290826ms","start":"2026-10-03T05:57:05.289306Z","end":"2026-10-03T05:57:05.610596Z","steps":["trace[1540282862] 'process raft request' (duration: 320.44913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T05:57:05.610867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T05:57:05.289268Z","time spent":"321.480389ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T05:57:05.61103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.524716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-03T05:57:05.61112Z","caller":"traceutil/trace.go:171","msg":"trace[620072054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5586; }","duration":"280.665079ms","start":"2026-10-03T05:57:05.330433Z","end":"2026-10-03T05:57:05.611098Z","steps":["trace[620072054] 'agreement among raft nodes before linearized reading' (duration: 280.442855ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T05:57:20.594856Z","caller":"traceutil/trace.go:171","msg":"trace[661071520] transaction","detail":"{read_only:false; response_revision:5850; number_of_response:1; }","duration":"153.299582ms","start":"2026-10-03T05:57:20.441504Z","end":"2026-10-03T05:57:20.594804Z","steps":["trace[661071520] 'process raft request' (duration: 106.858634ms)","trace[661071520] 'compare' (duration: 46.023131ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T05:57:27.02574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-10-03T05:57:27.112179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"84.947094ms","hash":700679649,"current-db-size-bytes":55390208,"current-db-size":"55 MB","current-db-size-in-use-bytes":30044160,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T05:57:27.112259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":700679649,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-10-03T06:02:27.032559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5909} {"level":"info","ts":"2026-10-03T06:02:27.120161Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5909,"took":"85.459087ms","hash":1502657118,"current-db-size-bytes":55390208,"current-db-size":"55 MB","current-db-size-in-use-bytes":26726400,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-03T06:02:27.120404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1502657118,"revision":5909,"compact-revision":3001} {"level":"info","ts":"2026-10-03T06:03:39.927747Z","caller":"traceutil/trace.go:171","msg":"trace[1051496626] linearizableReadLoop","detail":"{readStateIndex:8987; appliedIndex:8986; }","duration":"266.591325ms","start":"2026-10-03T06:03:39.66113Z","end":"2026-10-03T06:03:39.927722Z","steps":["trace[1051496626] 'read index received' (duration: 266.184658ms)","trace[1051496626] 'applied index is now lower than readState.Index' (duration: 405.567µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:03:39.927889Z","caller":"traceutil/trace.go:171","msg":"trace[1316192792] transaction","detail":"{read_only:false; response_revision:8676; number_of_response:1; }","duration":"269.858507ms","start":"2026-10-03T06:03:39.658003Z","end":"2026-10-03T06:03:39.927862Z","steps":["trace[1316192792] 'process raft request' (duration: 269.398778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:39.928016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.142949ms","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:5"} {"level":"info","ts":"2026-10-03T06:03:39.928079Z","caller":"traceutil/trace.go:171","msg":"trace[660093867] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8676; }","duration":"253.295612ms","start":"2026-10-03T06:03:39.674769Z","end":"2026-10-03T06:03:39.928065Z","steps":["trace[660093867] 'agreement among raft nodes before linearized reading' (duration: 253.149609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:39.928087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.521951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T06:03:39.928071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.368132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:03:39.92814Z","caller":"traceutil/trace.go:171","msg":"trace[1222233454] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8676; }","duration":"228.624063ms","start":"2026-10-03T06:03:39.699499Z","end":"2026-10-03T06:03:39.928124Z","steps":["trace[1222233454] 'agreement among raft nodes before linearized reading' (duration: 228.522231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:39.928233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.774566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T06:03:39.928216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.947868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T06:03:39.92823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.588496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:03:39.928344Z","caller":"traceutil/trace.go:171","msg":"trace[38940114] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8676; }","duration":"188.123131ms","start":"2026-10-03T06:03:39.740188Z","end":"2026-10-03T06:03:39.928311Z","steps":["trace[38940114] 'agreement among raft nodes before linearized reading' (duration: 187.867216ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:03:39.928358Z","caller":"traceutil/trace.go:171","msg":"trace[2020375904] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8676; }","duration":"203.77106ms","start":"2026-10-03T06:03:39.724559Z","end":"2026-10-03T06:03:39.92833Z","steps":["trace[2020375904] 'agreement among raft nodes before linearized reading' (duration: 203.523825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:39.928369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.483266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:03:39.928275Z","caller":"traceutil/trace.go:171","msg":"trace[1442774798] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8676; }","duration":"227.837468ms","start":"2026-10-03T06:03:39.700422Z","end":"2026-10-03T06:03:39.928259Z","steps":["trace[1442774798] 'agreement among raft nodes before linearized reading' (duration: 227.766436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:39.928422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.876758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T06:03:39.928433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.966508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:03:39.928436Z","caller":"traceutil/trace.go:171","msg":"trace[460599150] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8676; }","duration":"253.551777ms","start":"2026-10-03T06:03:39.674857Z","end":"2026-10-03T06:03:39.928409Z","steps":["trace[460599150] 'agreement among raft nodes before linearized reading' (duration: 253.466185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:39.928427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.082536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T06:03:39.928259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.04852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:03:39.92847Z","caller":"traceutil/trace.go:171","msg":"trace[421255762] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8676; }","duration":"188.02457ms","start":"2026-10-03T06:03:39.740435Z","end":"2026-10-03T06:03:39.92846Z","steps":["trace[421255762] 'agreement among raft nodes before linearized reading' (duration: 187.937958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:39.928469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.249921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:03:39.928526Z","caller":"traceutil/trace.go:171","msg":"trace[598319607] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8676; }","duration":"203.23307ms","start":"2026-10-03T06:03:39.725264Z","end":"2026-10-03T06:03:39.928497Z","steps":["trace[598319607] 'agreement among raft nodes before linearized reading' (duration: 202.996405ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:03:39.928568Z","caller":"traceutil/trace.go:171","msg":"trace[1077342383] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8676; }","duration":"253.394564ms","start":"2026-10-03T06:03:39.675147Z","end":"2026-10-03T06:03:39.928542Z","steps":["trace[1077342383] 'agreement among raft nodes before linearized reading' (duration: 253.17062ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:03:39.928586Z","caller":"traceutil/trace.go:171","msg":"trace[1271382169] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8676; }","duration":"188.434487ms","start":"2026-10-03T06:03:39.740091Z","end":"2026-10-03T06:03:39.928525Z","steps":["trace[1271382169] 'agreement among raft nodes before linearized reading' (duration: 188.06866ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:03:39.928471Z","caller":"traceutil/trace.go:171","msg":"trace[1427749740] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8676; }","duration":"228.922979ms","start":"2026-10-03T06:03:39.699529Z","end":"2026-10-03T06:03:39.928452Z","steps":["trace[1427749740] 'agreement among raft nodes before linearized reading' (duration: 228.822947ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:39.929005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.861259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-03T06:03:39.929038Z","caller":"traceutil/trace.go:171","msg":"trace[130901553] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8676; }","duration":"204.35346ms","start":"2026-10-03T06:03:39.724656Z","end":"2026-10-03T06:03:39.929009Z","steps":["trace[130901553] 'agreement among raft nodes before linearized reading' (duration: 203.300871ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:03:39.929069Z","caller":"traceutil/trace.go:171","msg":"trace[1621492659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8676; }","duration":"267.9486ms","start":"2026-10-03T06:03:39.661103Z","end":"2026-10-03T06:03:39.929052Z","steps":["trace[1621492659] 'agreement among raft nodes before linearized reading' (duration: 266.798229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:40.172887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.003951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T06:03:40.173092Z","caller":"traceutil/trace.go:171","msg":"trace[1192027809] transaction","detail":"{read_only:false; response_revision:8677; number_of_response:1; }","duration":"240.347675ms","start":"2026-10-03T06:03:39.932699Z","end":"2026-10-03T06:03:40.173047Z","steps":["trace[1192027809] 'process raft request' (duration: 44.067509ms)","trace[1192027809] 'compare' (duration: 195.469471ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:03:52.138892Z","caller":"traceutil/trace.go:171","msg":"trace[16676556] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"161.554515ms","start":"2026-10-03T06:03:51.977313Z","end":"2026-10-03T06:03:52.138868Z","steps":["trace[16676556] 'process raft request' (duration: 161.32489ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:03:52.73287Z","caller":"traceutil/trace.go:171","msg":"trace[1721828394] linearizableReadLoop","detail":"{readStateIndex:9058; appliedIndex:9057; }","duration":"143.93239ms","start":"2026-10-03T06:03:52.588919Z","end":"2026-10-03T06:03:52.732851Z","steps":["trace[1721828394] 'read index received' (duration: 143.723496ms)","trace[1721828394] 'applied index is now lower than readState.Index' (duration: 208.164µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:03:52.732974Z","caller":"traceutil/trace.go:171","msg":"trace[348098182] transaction","detail":"{read_only:false; response_revision:8744; number_of_response:1; }","duration":"178.61905ms","start":"2026-10-03T06:03:52.554344Z","end":"2026-10-03T06:03:52.732963Z","steps":["trace[348098182] 'process raft request' (duration: 178.374816ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:52.733181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.250546ms","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-03T06:03:52.733216Z","caller":"traceutil/trace.go:171","msg":"trace[253081582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8744; }","duration":"144.337568ms","start":"2026-10-03T06:03:52.58887Z","end":"2026-10-03T06:03:52.733208Z","steps":["trace[253081582] 'agreement among raft nodes before linearized reading' (duration: 144.212166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:03:52.733259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.147244ms","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-03T06:03:52.733324Z","caller":"traceutil/trace.go:171","msg":"trace[176674295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8744; }","duration":"103.281406ms","start":"2026-10-03T06:03:52.630027Z","end":"2026-10-03T06:03:52.733308Z","steps":["trace[176674295] 'agreement among raft nodes before linearized reading' (duration: 103.059152ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:03:53.299216Z","caller":"traceutil/trace.go:171","msg":"trace[1317021153] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"115.02619ms","start":"2026-10-03T06:03:53.184167Z","end":"2026-10-03T06:03:53.299193Z","steps":["trace[1317021153] 'process raft request' (duration: 114.864287ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:03:53.299409Z","caller":"traceutil/trace.go:171","msg":"trace[1582603670] transaction","detail":"{read_only:false; response_revision:8749; number_of_response:1; }","duration":"105.467288ms","start":"2026-10-03T06:03:53.193907Z","end":"2026-10-03T06:03:53.299375Z","steps":["trace[1582603670] 'process raft request' (duration: 105.252474ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:04:17.360348Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002408.wal"} {"level":"info","ts":"2026-10-03T06:05:35.585365Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T06:05:35.604619Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-03T06:05:35.604719Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-03T06:06:03.896099Z","caller":"traceutil/trace.go:171","msg":"trace[1511857] transaction","detail":"{read_only:false; response_revision:9876; number_of_response:1; }","duration":"118.472666ms","start":"2026-10-03T06:06:03.777548Z","end":"2026-10-03T06:06:03.89602Z","steps":["trace[1511857] 'process raft request' (duration: 118.316323ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:06:03.922718Z","caller":"traceutil/trace.go:171","msg":"trace[1262845105] transaction","detail":"{read_only:false; response_revision:9877; number_of_response:1; }","duration":"125.328747ms","start":"2026-10-03T06:06:03.797328Z","end":"2026-10-03T06:06:03.922656Z","steps":["trace[1262845105] 'process raft request' (duration: 125.092922ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:07:27.039397Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8061} {"level":"info","ts":"2026-10-03T06:07:27.108548Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8061,"took":"67.004456ms","hash":1071482504,"current-db-size-bytes":55390208,"current-db-size":"55 MB","current-db-size-in-use-bytes":27697152,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-03T06:07:27.108631Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1071482504,"revision":8061,"compact-revision":5909} {"level":"info","ts":"2026-10-03T06:11:44.90907Z","caller":"traceutil/trace.go:171","msg":"trace[809722243] transaction","detail":"{read_only:false; response_revision:12320; number_of_response:1; }","duration":"232.367836ms","start":"2026-10-03T06:11:44.676641Z","end":"2026-10-03T06:11:44.909009Z","steps":["trace[809722243] 'process raft request' (duration: 232.150712ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:11:44.911245Z","caller":"traceutil/trace.go:171","msg":"trace[218527910] linearizableReadLoop","detail":"{readStateIndex:12753; appliedIndex:12751; }","duration":"191.753075ms","start":"2026-10-03T06:11:44.71946Z","end":"2026-10-03T06:11:44.911213Z","steps":["trace[218527910] 'read index received' (duration: 189.403411ms)","trace[218527910] 'applied index is now lower than readState.Index' (duration: 2.348294ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:11:44.911339Z","caller":"traceutil/trace.go:171","msg":"trace[457919615] transaction","detail":"{read_only:false; response_revision:12321; number_of_response:1; }","duration":"229.856778ms","start":"2026-10-03T06:11:44.681437Z","end":"2026-10-03T06:11:44.911293Z","steps":["trace[457919615] 'process raft request' (duration: 229.626784ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:11:44.9115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.00954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-03T06:11:44.91155Z","caller":"traceutil/trace.go:171","msg":"trace[942607574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12321; }","duration":"192.173953ms","start":"2026-10-03T06:11:44.719362Z","end":"2026-10-03T06:11:44.911536Z","steps":["trace[942607574] 'agreement among raft nodes before linearized reading' (duration: 191.953349ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:12:27.046532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10424} {"level":"info","ts":"2026-10-03T06:12:27.134848Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10424,"took":"85.859022ms","hash":2099737979,"current-db-size-bytes":55390208,"current-db-size":"55 MB","current-db-size-in-use-bytes":26296320,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-03T06:12:27.135021Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2099737979,"revision":10424,"compact-revision":8061} {"level":"info","ts":"2026-10-03T06:17:27.056675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12625} {"level":"info","ts":"2026-10-03T06:17:27.142813Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12625,"took":"81.979564ms","hash":2011804511,"current-db-size-bytes":55390208,"current-db-size":"55 MB","current-db-size-in-use-bytes":27140096,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-03T06:17:27.14289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2011804511,"revision":12625,"compact-revision":10424} {"level":"info","ts":"2026-10-03T06:18:13.439054Z","caller":"traceutil/trace.go:171","msg":"trace[635067624] transaction","detail":"{read_only:false; response_revision:14920; number_of_response:1; }","duration":"111.050524ms","start":"2026-10-03T06:18:13.327975Z","end":"2026-10-03T06:18:13.439026Z","steps":["trace[635067624] 'process raft request' (duration: 96.409226ms)","trace[635067624] 'compare' (duration: 14.534306ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:18:21.728902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.876029ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T06:18:21.729001Z","caller":"traceutil/trace.go:171","msg":"trace[128876] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14962; }","duration":"104.001251ms","start":"2026-10-03T06:18:21.62498Z","end":"2026-10-03T06:18:21.728981Z","steps":["trace[128876] 'range keys from in-memory index tree' (duration: 103.853948ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:18:54.25733Z","caller":"traceutil/trace.go:171","msg":"trace[1154029034] transaction","detail":"{read_only:false; response_revision:15338; number_of_response:1; }","duration":"346.178582ms","start":"2026-10-03T06:18:53.911122Z","end":"2026-10-03T06:18:54.257301Z","steps":["trace[1154029034] 'process raft request' (duration: 345.967958ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:18:54.257705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:18:53.911081Z","time spent":"346.331776ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:18:54.287571Z","caller":"traceutil/trace.go:171","msg":"trace[872551655] transaction","detail":"{read_only:false; response_revision:15339; number_of_response:1; }","duration":"373.596932ms","start":"2026-10-03T06:18:53.913935Z","end":"2026-10-03T06:18:54.287532Z","steps":["trace[872551655] 'process raft request' (duration: 373.363288ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:18:54.287805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:18:53.913896Z","time spent":"373.783026ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:20:21.805387Z","caller":"traceutil/trace.go:171","msg":"trace[638685293] linearizableReadLoop","detail":"{readStateIndex:16722; appliedIndex:16721; }","duration":"153.754115ms","start":"2026-10-03T06:20:21.651607Z","end":"2026-10-03T06:20:21.805361Z","steps":["trace[638685293] 'read index received' (duration: 153.555491ms)","trace[638685293] 'applied index is now lower than readState.Index' (duration: 195.824µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:20:21.80543Z","caller":"traceutil/trace.go:171","msg":"trace[447767513] transaction","detail":"{read_only:false; response_revision:16155; number_of_response:1; }","duration":"168.666518ms","start":"2026-10-03T06:20:21.636741Z","end":"2026-10-03T06:20:21.805408Z","steps":["trace[447767513] 'process raft request' (duration: 168.467904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:20:21.805609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.992449ms","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-03T06:20:21.805647Z","caller":"traceutil/trace.go:171","msg":"trace[613763567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16155; }","duration":"154.068991ms","start":"2026-10-03T06:20:21.651568Z","end":"2026-10-03T06:20:21.805637Z","steps":["trace[613763567] 'agreement among raft nodes before linearized reading' (duration: 153.920638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:20:21.805767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.574788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-03T06:20:21.805847Z","caller":"traceutil/trace.go:171","msg":"trace[863937777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16155; }","duration":"108.753141ms","start":"2026-10-03T06:20:21.697076Z","end":"2026-10-03T06:20:21.805829Z","steps":["trace[863937777] 'agreement among raft nodes before linearized reading' (duration: 108.519336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:20:21.805816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.156088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6swlz\" ","response":"range_response_count:1 size:20644"} {"level":"info","ts":"2026-10-03T06:20:21.805915Z","caller":"traceutil/trace.go:171","msg":"trace[1174154636] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6swlz; range_end:; response_count:1; response_revision:16155; }","duration":"119.358272ms","start":"2026-10-03T06:20:21.686539Z","end":"2026-10-03T06:20:21.805897Z","steps":["trace[1174154636] 'agreement among raft nodes before linearized reading' (duration: 119.019525ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:20:22.696452Z","caller":"traceutil/trace.go:171","msg":"trace[1903742781] transaction","detail":"{read_only:false; response_revision:16160; number_of_response:1; }","duration":"122.554653ms","start":"2026-10-03T06:20:22.573873Z","end":"2026-10-03T06:20:22.696427Z","steps":["trace[1903742781] 'process raft request' (duration: 122.38484ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:20:22.926602Z","caller":"traceutil/trace.go:171","msg":"trace[1753029806] transaction","detail":"{read_only:false; response_revision:16161; number_of_response:1; }","duration":"139.093007ms","start":"2026-10-03T06:20:22.787485Z","end":"2026-10-03T06:20:22.926578Z","steps":["trace[1753029806] 'process raft request' (duration: 138.789731ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:22:27.063618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14620} {"level":"info","ts":"2026-10-03T06:22:27.154712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14620,"took":"87.331285ms","hash":489941579,"current-db-size-bytes":55390208,"current-db-size":"55 MB","current-db-size-in-use-bytes":31014912,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T06:22:27.154829Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":489941579,"revision":14620,"compact-revision":12625} {"level":"warn","ts":"2026-10-03T06:26:36.032671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.27573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:26:36.032756Z","caller":"traceutil/trace.go:171","msg":"trace[23014306] linearizableReadLoop","detail":"{readStateIndex:19528; appliedIndex:19527; }","duration":"301.228636ms","start":"2026-10-03T06:26:35.731515Z","end":"2026-10-03T06:26:36.032743Z","steps":["trace[23014306] 'read index received' (duration: 101.795582ms)","trace[23014306] 'applied index is now lower than readState.Index' (duration: 199.431954ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:26:36.032804Z","caller":"traceutil/trace.go:171","msg":"trace[1344432795] transaction","detail":"{read_only:false; response_revision:18870; number_of_response:1; }","duration":"316.803741ms","start":"2026-10-03T06:26:35.715994Z","end":"2026-10-03T06:26:36.032798Z","steps":["trace[1344432795] 'process raft request' (duration: 117.324397ms)","trace[1344432795] 'compare' (duration: 199.143268ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:26:36.032841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:26:35.715973Z","time spent":"316.852631ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:26:36.033064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.538571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6swlz\" ","response":"range_response_count:1 size:20747"} {"level":"info","ts":"2026-10-03T06:26:36.033129Z","caller":"traceutil/trace.go:171","msg":"trace[324252325] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6swlz; range_end:; response_count:1; response_revision:18870; }","duration":"301.638414ms","start":"2026-10-03T06:26:35.731476Z","end":"2026-10-03T06:26:36.033115Z","steps":["trace[324252325] 'agreement among raft nodes before linearized reading' (duration: 301.327398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:26:36.03316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:26:35.731418Z","time spent":"301.734545ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20770,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6swlz\" "} {"level":"warn","ts":"2026-10-03T06:26:36.064064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.390389ms","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-03T06:26:36.064168Z","caller":"traceutil/trace.go:171","msg":"trace[1268178011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18871; }","duration":"222.572332ms","start":"2026-10-03T06:26:35.84157Z","end":"2026-10-03T06:26:36.064143Z","steps":["trace[1268178011] 'agreement among raft nodes before linearized reading' (duration: 222.323928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:26:36.064198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.825111ms","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-03T06:26:36.064261Z","caller":"traceutil/trace.go:171","msg":"trace[1671998680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18871; }","duration":"131.928023ms","start":"2026-10-03T06:26:35.932316Z","end":"2026-10-03T06:26:36.064244Z","steps":["trace[1671998680] 'agreement among raft nodes before linearized reading' (duration: 131.76202ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:26:36.219769Z","caller":"traceutil/trace.go:171","msg":"trace[915833998] transaction","detail":"{read_only:false; response_revision:18872; number_of_response:1; }","duration":"169.450076ms","start":"2026-10-03T06:26:36.050288Z","end":"2026-10-03T06:26:36.219738Z","steps":["trace[915833998] 'process raft request' (duration: 111.664628ms)","trace[915833998] 'compare' (duration: 57.631895ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:26:36.421055Z","caller":"traceutil/trace.go:171","msg":"trace[1942136490] transaction","detail":"{read_only:false; response_revision:18873; number_of_response:1; }","duration":"352.184932ms","start":"2026-10-03T06:26:36.068837Z","end":"2026-10-03T06:26:36.421022Z","steps":["trace[1942136490] 'process raft request' (duration: 311.563591ms)","trace[1942136490] 'compare' (duration: 40.407847ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:26:36.421186Z","caller":"traceutil/trace.go:171","msg":"trace[376592814] linearizableReadLoop","detail":"{readStateIndex:19532; appliedIndex:19529; }","duration":"347.293129ms","start":"2026-10-03T06:26:36.073869Z","end":"2026-10-03T06:26:36.421162Z","steps":["trace[376592814] 'read index received' (duration: 88.06714ms)","trace[376592814] 'applied index is now lower than readState.Index' (duration: 259.224679ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:26:36.421199Z","caller":"traceutil/trace.go:171","msg":"trace[267045372] transaction","detail":"{read_only:false; response_revision:18874; number_of_response:1; }","duration":"352.302144ms","start":"2026-10-03T06:26:36.068875Z","end":"2026-10-03T06:26:36.421177Z","steps":["trace[267045372] 'process raft request' (duration: 352.09681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:26:36.421255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:26:36.068803Z","time spent":"352.351325ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:26:36.421303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:26:36.068835Z","time spent":"352.412177ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:26:36.421628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.753857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:26:36.421691Z","caller":"traceutil/trace.go:171","msg":"trace[1836648523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18874; }","duration":"115.873959ms","start":"2026-10-03T06:26:36.305803Z","end":"2026-10-03T06:26:36.421677Z","steps":["trace[1836648523] 'agreement among raft nodes before linearized reading' (duration: 115.762617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:26:36.422915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.97037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946777"} {"level":"info","ts":"2026-10-03T06:26:36.42306Z","caller":"traceutil/trace.go:171","msg":"trace[594756567] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:18874; }","duration":"349.166694ms","start":"2026-10-03T06:26:36.073862Z","end":"2026-10-03T06:26:36.423028Z","steps":["trace[594756567] 'agreement among raft nodes before linearized reading' (duration: 347.399821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:26:36.423122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:26:36.073815Z","time spent":"349.293657ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":94,"response size":946800,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-10-03T06:26:36.867939Z","caller":"traceutil/trace.go:171","msg":"trace[1278467699] transaction","detail":"{read_only:false; response_revision:18875; number_of_response:1; }","duration":"436.432961ms","start":"2026-10-03T06:26:36.431486Z","end":"2026-10-03T06:26:36.867919Z","steps":["trace[1278467699] 'process raft request' (duration: 436.300098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:26:36.868055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:26:36.431418Z","time spent":"436.581563ms","remote":"127.0.0.1:42718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1629,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T06:26:36.96803Z","caller":"traceutil/trace.go:171","msg":"trace[236916833] transaction","detail":"{read_only:false; response_revision:18876; number_of_response:1; }","duration":"329.508472ms","start":"2026-10-03T06:26:36.638497Z","end":"2026-10-03T06:26:36.968006Z","steps":["trace[236916833] 'process raft request' (duration: 320.143965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:26:36.968184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:26:36.638478Z","time spent":"329.625354ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:26:36.96846Z","caller":"traceutil/trace.go:171","msg":"trace[818735557] linearizableReadLoop","detail":"{readStateIndex:19535; appliedIndex:19532; }","duration":"273.271015ms","start":"2026-10-03T06:26:36.695161Z","end":"2026-10-03T06:26:36.968432Z","steps":["trace[818735557] 'read index received' (duration: 172.675556ms)","trace[818735557] 'applied index is now lower than readState.Index' (duration: 100.594139ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:26:36.968509Z","caller":"traceutil/trace.go:171","msg":"trace[365328575] transaction","detail":"{read_only:false; response_revision:18878; number_of_response:1; }","duration":"236.554578ms","start":"2026-10-03T06:26:36.731915Z","end":"2026-10-03T06:26:36.96847Z","steps":["trace[365328575] 'process raft request' (duration: 236.318024ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:26:36.968637Z","caller":"traceutil/trace.go:171","msg":"trace[1250026255] transaction","detail":"{read_only:false; response_revision:18879; number_of_response:1; }","duration":"234.706893ms","start":"2026-10-03T06:26:36.733892Z","end":"2026-10-03T06:26:36.968598Z","steps":["trace[1250026255] 'process raft request' (duration: 234.478209ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:26:36.96878Z","caller":"traceutil/trace.go:171","msg":"trace[801743227] transaction","detail":"{read_only:false; response_revision:18877; number_of_response:1; }","duration":"311.204315ms","start":"2026-10-03T06:26:36.657563Z","end":"2026-10-03T06:26:36.968767Z","steps":["trace[801743227] 'process raft request' (duration: 310.39522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:26:36.968811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.642221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-4xnws\" ","response":"range_response_count:1 size:9792"} {"level":"warn","ts":"2026-10-03T06:26:36.968848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:26:36.657538Z","time spent":"311.277636ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:26:36.968853Z","caller":"traceutil/trace.go:171","msg":"trace[1776275531] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-4xnws; range_end:; response_count:1; response_revision:18879; }","duration":"109.731892ms","start":"2026-10-03T06:26:36.859108Z","end":"2026-10-03T06:26:36.96884Z","steps":["trace[1776275531] 'agreement among raft nodes before linearized reading' (duration: 109.563438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:26:36.969107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.940147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:26:36.96917Z","caller":"traceutil/trace.go:171","msg":"trace[834698729] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:18879; }","duration":"274.04591ms","start":"2026-10-03T06:26:36.695105Z","end":"2026-10-03T06:26:36.969151Z","steps":["trace[834698729] 'agreement among raft nodes before linearized reading' (duration: 273.951988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:26:45.312803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.741671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:26:45.31294Z","caller":"traceutil/trace.go:171","msg":"trace[145554754] transaction","detail":"{read_only:false; response_revision:19014; number_of_response:1; }","duration":"147.807736ms","start":"2026-10-03T06:26:45.165087Z","end":"2026-10-03T06:26:45.312894Z","steps":["trace[145554754] 'process raft request' (duration: 45.886051ms)","trace[145554754] 'compare' (duration: 101.596718ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:27:12.003674Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T06:27:12.027834Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-03T06:27:12.02792Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-03T06:27:27.070837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17010} {"level":"info","ts":"2026-10-03T06:27:27.179009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17010,"took":"103.186728ms","hash":2311027815,"current-db-size-bytes":55390208,"current-db-size":"55 MB","current-db-size-in-use-bytes":33271808,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-03T06:27:27.179055Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2311027815,"revision":17010,"compact-revision":14620} {"level":"info","ts":"2026-10-03T06:32:16.974574Z","caller":"traceutil/trace.go:171","msg":"trace[1748327997] transaction","detail":"{read_only:false; response_revision:21523; number_of_response:1; }","duration":"117.899047ms","start":"2026-10-03T06:32:16.856644Z","end":"2026-10-03T06:32:16.974543Z","steps":["trace[1748327997] 'process raft request' (duration: 117.658022ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:32:22.366657Z","caller":"traceutil/trace.go:171","msg":"trace[499205386] linearizableReadLoop","detail":"{readStateIndex:22296; appliedIndex:22295; }","duration":"110.340433ms","start":"2026-10-03T06:32:22.256297Z","end":"2026-10-03T06:32:22.366637Z","steps":["trace[499205386] 'read index received' (duration: 110.194691ms)","trace[499205386] 'applied index is now lower than readState.Index' (duration: 144.912µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:32:22.366698Z","caller":"traceutil/trace.go:171","msg":"trace[431488603] transaction","detail":"{read_only:false; response_revision:21551; number_of_response:1; }","duration":"124.895239ms","start":"2026-10-03T06:32:22.241778Z","end":"2026-10-03T06:32:22.366673Z","steps":["trace[431488603] 'process raft request' (duration: 124.711645ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:32:22.366912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.603008ms","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-03T06:32:22.367086Z","caller":"traceutil/trace.go:171","msg":"trace[1013491988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21551; }","duration":"110.820493ms","start":"2026-10-03T06:32:22.256245Z","end":"2026-10-03T06:32:22.367065Z","steps":["trace[1013491988] 'agreement among raft nodes before linearized reading' (duration: 110.484166ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:32:22.653917Z","caller":"traceutil/trace.go:171","msg":"trace[721179028] transaction","detail":"{read_only:false; response_revision:21552; number_of_response:1; }","duration":"281.704116ms","start":"2026-10-03T06:32:22.372184Z","end":"2026-10-03T06:32:22.653888Z","steps":["trace[721179028] 'process raft request' (duration: 281.38241ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:32:22.654553Z","caller":"traceutil/trace.go:171","msg":"trace[978590364] transaction","detail":"{read_only:false; response_revision:21553; number_of_response:1; }","duration":"276.574768ms","start":"2026-10-03T06:32:22.377966Z","end":"2026-10-03T06:32:22.65454Z","steps":["trace[978590364] 'process raft request' (duration: 275.805174ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:32:22.655221Z","caller":"traceutil/trace.go:171","msg":"trace[1218943784] transaction","detail":"{read_only:false; response_revision:21554; number_of_response:1; }","duration":"270.297398ms","start":"2026-10-03T06:32:22.384911Z","end":"2026-10-03T06:32:22.655208Z","steps":["trace[1218943784] 'process raft request' (duration: 269.571374ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:32:22.655498Z","caller":"traceutil/trace.go:171","msg":"trace[1049972237] transaction","detail":"{read_only:false; response_revision:21555; number_of_response:1; }","duration":"268.423553ms","start":"2026-10-03T06:32:22.387042Z","end":"2026-10-03T06:32:22.655466Z","steps":["trace[1049972237] 'process raft request' (duration: 268.107297ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:32:22.655649Z","caller":"traceutil/trace.go:171","msg":"trace[28033742] transaction","detail":"{read_only:false; response_revision:21556; number_of_response:1; }","duration":"268.542675ms","start":"2026-10-03T06:32:22.387083Z","end":"2026-10-03T06:32:22.655625Z","steps":["trace[28033742] 'process raft request' (duration: 268.2982ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:32:27.080671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19491} {"level":"info","ts":"2026-10-03T06:32:27.199481Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19491,"took":"115.41293ms","hash":2303596611,"current-db-size-bytes":55390208,"current-db-size":"55 MB","current-db-size-in-use-bytes":29728768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T06:32:27.199544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2303596611,"revision":19491,"compact-revision":17010} {"level":"info","ts":"2026-10-03T06:32:38.846226Z","caller":"traceutil/trace.go:171","msg":"trace[1592608573] transaction","detail":"{read_only:false; response_revision:21635; number_of_response:1; }","duration":"124.470143ms","start":"2026-10-03T06:32:38.721731Z","end":"2026-10-03T06:32:38.846201Z","steps":["trace[1592608573] 'process raft request' (duration: 124.295979ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:32:38.84649Z","caller":"traceutil/trace.go:171","msg":"trace[789078570] transaction","detail":"{read_only:false; response_revision:21636; number_of_response:1; }","duration":"124.709477ms","start":"2026-10-03T06:32:38.721748Z","end":"2026-10-03T06:32:38.846458Z","steps":["trace[789078570] 'process raft request' (duration: 124.415911ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:32:39.009064Z","caller":"traceutil/trace.go:171","msg":"trace[1712420117] linearizableReadLoop","detail":"{readStateIndex:22387; appliedIndex:22384; }","duration":"270.046215ms","start":"2026-10-03T06:32:38.738996Z","end":"2026-10-03T06:32:39.009042Z","steps":["trace[1712420117] 'read index received' (duration: 107.135403ms)","trace[1712420117] 'applied index is now lower than readState.Index' (duration: 162.909682ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:32:39.009153Z","caller":"traceutil/trace.go:171","msg":"trace[715549490] transaction","detail":"{read_only:false; response_revision:21637; number_of_response:1; }","duration":"285.63351ms","start":"2026-10-03T06:32:38.723479Z","end":"2026-10-03T06:32:39.009112Z","steps":["trace[715549490] 'process raft request' (duration: 274.911416ms)","trace[715549490] 'compare' (duration: 10.536351ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:32:39.009254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.238108ms","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-03T06:32:39.009284Z","caller":"traceutil/trace.go:171","msg":"trace[569885143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21637; }","duration":"270.310739ms","start":"2026-10-03T06:32:38.738964Z","end":"2026-10-03T06:32:39.009275Z","steps":["trace[569885143] 'agreement among raft nodes before linearized reading' (duration: 270.155387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:32:39.267852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.000152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:32:39.26798Z","caller":"traceutil/trace.go:171","msg":"trace[1709006643] linearizableReadLoop","detail":"{readStateIndex:22388; appliedIndex:22387; }","duration":"244.401758ms","start":"2026-10-03T06:32:39.023561Z","end":"2026-10-03T06:32:39.267963Z","steps":["trace[1709006643] 'read index received' (duration: 100.201603ms)","trace[1709006643] 'applied index is now lower than readState.Index' (duration: 144.198635ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:32:39.268069Z","caller":"traceutil/trace.go:171","msg":"trace[1684397288] transaction","detail":"{read_only:false; response_revision:21638; number_of_response:1; }","duration":"253.819936ms","start":"2026-10-03T06:32:39.014237Z","end":"2026-10-03T06:32:39.268057Z","steps":["trace[1684397288] 'process raft request' (duration: 109.500618ms)","trace[1684397288] 'compare' (duration: 143.88855ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:32:39.268212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.651253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:32:39.268246Z","caller":"traceutil/trace.go:171","msg":"trace[1663890842] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:21638; }","duration":"244.725884ms","start":"2026-10-03T06:32:39.023509Z","end":"2026-10-03T06:32:39.268235Z","steps":["trace[1663890842] 'agreement among raft nodes before linearized reading' (duration: 244.664633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:32:39.268559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.948157ms","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-03T06:32:39.268599Z","caller":"traceutil/trace.go:171","msg":"trace[93646188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21638; }","duration":"130.025128ms","start":"2026-10-03T06:32:39.138562Z","end":"2026-10-03T06:32:39.268587Z","steps":["trace[93646188] 'agreement among raft nodes before linearized reading' (duration: 129.905286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:32:39.268567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.84128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:32:39.268701Z","caller":"traceutil/trace.go:171","msg":"trace[2091078128] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:21638; }","duration":"222.018674ms","start":"2026-10-03T06:32:39.046638Z","end":"2026-10-03T06:32:39.268656Z","steps":["trace[2091078128] 'agreement among raft nodes before linearized reading' (duration: 221.80989ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:32:39.724769Z","caller":"traceutil/trace.go:171","msg":"trace[777967454] linearizableReadLoop","detail":"{readStateIndex:22390; appliedIndex:22389; }","duration":"211.068495ms","start":"2026-10-03T06:32:39.513671Z","end":"2026-10-03T06:32:39.724739Z","steps":["trace[777967454] 'read index received' (duration: 210.80517ms)","trace[777967454] 'applied index is now lower than readState.Index' (duration: 262.425µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:32:39.724867Z","caller":"traceutil/trace.go:171","msg":"trace[233377167] transaction","detail":"{read_only:false; response_revision:21640; number_of_response:1; }","duration":"300.020853ms","start":"2026-10-03T06:32:39.424786Z","end":"2026-10-03T06:32:39.724807Z","steps":["trace[233377167] 'process raft request' (duration: 299.773818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:32:39.72497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.734609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:32:39.725016Z","caller":"traceutil/trace.go:171","msg":"trace[745004693] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:21640; }","duration":"191.82772ms","start":"2026-10-03T06:32:39.533174Z","end":"2026-10-03T06:32:39.725001Z","steps":["trace[745004693] 'agreement among raft nodes before linearized reading' (duration: 191.726928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:32:39.725121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:32:39.424749Z","time spent":"300.184896ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:32:39.725399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.658027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T06:32:39.725694Z","caller":"traceutil/trace.go:171","msg":"trace[397337001] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21640; }","duration":"212.064524ms","start":"2026-10-03T06:32:39.513604Z","end":"2026-10-03T06:32:39.725668Z","steps":["trace[397337001] 'agreement among raft nodes before linearized reading' (duration: 211.256509ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:36:42.78738Z","caller":"traceutil/trace.go:171","msg":"trace[795886875] transaction","detail":"{read_only:false; response_revision:24155; number_of_response:1; }","duration":"255.356045ms","start":"2026-10-03T06:36:42.531997Z","end":"2026-10-03T06:36:42.787353Z","steps":["trace[795886875] 'process raft request' (duration: 255.184863ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:36:42.795262Z","caller":"traceutil/trace.go:171","msg":"trace[2083523509] transaction","detail":"{read_only:false; response_revision:24157; number_of_response:1; }","duration":"183.924911ms","start":"2026-10-03T06:36:42.611311Z","end":"2026-10-03T06:36:42.795236Z","steps":["trace[2083523509] 'process raft request' (duration: 183.844029ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:36:42.795353Z","caller":"traceutil/trace.go:171","msg":"trace[1499389137] linearizableReadLoop","detail":"{readStateIndex:24983; appliedIndex:24979; }","duration":"102.835213ms","start":"2026-10-03T06:36:42.692501Z","end":"2026-10-03T06:36:42.795336Z","steps":["trace[1499389137] 'read index received' (duration: 94.666777ms)","trace[1499389137] 'applied index is now lower than readState.Index' (duration: 8.167286ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:36:42.795512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.001066ms","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-03T06:36:42.795269Z","caller":"traceutil/trace.go:171","msg":"trace[361513224] transaction","detail":"{read_only:false; response_revision:24156; number_of_response:1; }","duration":"249.479954ms","start":"2026-10-03T06:36:42.545759Z","end":"2026-10-03T06:36:42.795239Z","steps":["trace[361513224] 'process raft request' (duration: 249.27027ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:36:42.795554Z","caller":"traceutil/trace.go:171","msg":"trace[428351697] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24158; }","duration":"103.079987ms","start":"2026-10-03T06:36:42.692463Z","end":"2026-10-03T06:36:42.795543Z","steps":["trace[428351697] 'agreement among raft nodes before linearized reading' (duration: 102.929204ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:36:42.795394Z","caller":"traceutil/trace.go:171","msg":"trace[232760151] transaction","detail":"{read_only:false; response_revision:24158; number_of_response:1; }","duration":"108.833316ms","start":"2026-10-03T06:36:42.68654Z","end":"2026-10-03T06:36:42.795374Z","steps":["trace[232760151] 'process raft request' (duration: 108.654893ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:37:27.086783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21577} {"level":"info","ts":"2026-10-03T06:37:27.212383Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21577,"took":"121.863023ms","hash":3763691813,"current-db-size-bytes":56520704,"current-db-size":"56 MB","current-db-size-in-use-bytes":42422272,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-10-03T06:37:27.21246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3763691813,"revision":21577,"compact-revision":19491} {"level":"info","ts":"2026-10-03T06:39:46.577676Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000689a.wal"} {"level":"info","ts":"2026-10-03T06:40:33.388419Z","caller":"traceutil/trace.go:171","msg":"trace[1516215872] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"121.451325ms","start":"2026-10-03T06:40:33.266931Z","end":"2026-10-03T06:40:33.388382Z","steps":["trace[1516215872] 'process raft request' (duration: 121.18659ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:33.511608Z","caller":"traceutil/trace.go:171","msg":"trace[1262755171] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"163.325068ms","start":"2026-10-03T06:40:33.348255Z","end":"2026-10-03T06:40:33.51158Z","steps":["trace[1262755171] 'process raft request' (duration: 161.436703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:34.270264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.075727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-03T06:40:34.27029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.785731ms","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-03T06:40:34.270355Z","caller":"traceutil/trace.go:171","msg":"trace[1262357664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26435; }","duration":"228.897473ms","start":"2026-10-03T06:40:34.041442Z","end":"2026-10-03T06:40:34.27034Z","steps":["trace[1262357664] 'range keys from in-memory index tree' (duration: 228.655919ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:34.270349Z","caller":"traceutil/trace.go:171","msg":"trace[1324701110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26435; }","duration":"169.23744ms","start":"2026-10-03T06:40:34.10109Z","end":"2026-10-03T06:40:34.270327Z","steps":["trace[1324701110] 'range keys from in-memory index tree' (duration: 168.888753ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:34.717606Z","caller":"traceutil/trace.go:171","msg":"trace[749625026] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"442.896554ms","start":"2026-10-03T06:40:34.27468Z","end":"2026-10-03T06:40:34.717576Z","steps":["trace[749625026] 'process raft request' (duration: 442.760332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:34.717762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:34.274659Z","time spent":"443.012076ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:34.961572Z","caller":"traceutil/trace.go:171","msg":"trace[1340531264] transaction","detail":"{read_only:false; response_revision:26439; number_of_response:1; }","duration":"155.084762ms","start":"2026-10-03T06:40:34.806454Z","end":"2026-10-03T06:40:34.961539Z","steps":["trace[1340531264] 'process raft request' (duration: 154.99969ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:34.96162Z","caller":"traceutil/trace.go:171","msg":"trace[1671745936] transaction","detail":"{read_only:false; response_revision:26438; number_of_response:1; }","duration":"686.649348ms","start":"2026-10-03T06:40:34.274934Z","end":"2026-10-03T06:40:34.961583Z","steps":["trace[1671745936] 'process raft request' (duration: 686.392593ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:34.961693Z","caller":"traceutil/trace.go:171","msg":"trace[853619102] transaction","detail":"{read_only:false; response_revision:26437; number_of_response:1; }","duration":"686.775191ms","start":"2026-10-03T06:40:34.274879Z","end":"2026-10-03T06:40:34.961655Z","steps":["trace[853619102] 'process raft request' (duration: 599.931163ms)","trace[853619102] 'compare' (duration: 86.082454ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:34.961754Z","caller":"traceutil/trace.go:171","msg":"trace[1140143044] linearizableReadLoop","detail":"{readStateIndex:27322; appliedIndex:27319; }","duration":"659.788319ms","start":"2026-10-03T06:40:34.301955Z","end":"2026-10-03T06:40:34.961743Z","steps":["trace[1140143044] 'read index received' (duration: 415.469603ms)","trace[1140143044] 'applied index is now lower than readState.Index' (duration: 244.317655ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:34.961768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:34.274928Z","time spent":"686.763631ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:34.961756Z","caller":"traceutil/trace.go:171","msg":"trace[345369458] transaction","detail":"{read_only:false; response_revision:26442; number_of_response:1; }","duration":"103.257999ms","start":"2026-10-03T06:40:34.858477Z","end":"2026-10-03T06:40:34.961735Z","steps":["trace[345369458] 'process raft request' (duration: 103.218829ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:34.961798Z","caller":"traceutil/trace.go:171","msg":"trace[1282219297] transaction","detail":"{read_only:false; response_revision:26440; number_of_response:1; }","duration":"146.243714ms","start":"2026-10-03T06:40:34.81553Z","end":"2026-10-03T06:40:34.961774Z","steps":["trace[1282219297] 'process raft request' (duration: 145.967409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:34.961872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:34.27487Z","time spent":"686.900103ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:34.961953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.380119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:34.961922Z","caller":"traceutil/trace.go:171","msg":"trace[1353779139] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"108.650491ms","start":"2026-10-03T06:40:34.85322Z","end":"2026-10-03T06:40:34.96187Z","steps":["trace[1353779139] 'process raft request' (duration: 108.396236ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:34.961986Z","caller":"traceutil/trace.go:171","msg":"trace[1064225846] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:26442; }","duration":"310.455231ms","start":"2026-10-03T06:40:34.651521Z","end":"2026-10-03T06:40:34.961976Z","steps":["trace[1064225846] 'agreement among raft nodes before linearized reading' (duration: 310.38972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:34.961886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.925062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T06:40:34.962007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:34.651504Z","time spent":"310.497382ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-10-03T06:40:34.962008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.919048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T06:40:34.962039Z","caller":"traceutil/trace.go:171","msg":"trace[178062817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26442; }","duration":"660.115985ms","start":"2026-10-03T06:40:34.301898Z","end":"2026-10-03T06:40:34.962014Z","steps":["trace[178062817] 'agreement among raft nodes before linearized reading' (duration: 659.919941ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:34.962043Z","caller":"traceutil/trace.go:171","msg":"trace[1122083742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26442; }","duration":"499.004089ms","start":"2026-10-03T06:40:34.463027Z","end":"2026-10-03T06:40:34.962032Z","steps":["trace[1122083742] 'agreement among raft nodes before linearized reading' (duration: 498.863156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:34.962093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:34.463009Z","time spent":"499.07833ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-03T06:40:34.962091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.181368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11789"} {"level":"warn","ts":"2026-10-03T06:40:34.962128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:34.301878Z","time spent":"660.227798ms","remote":"127.0.0.1:51942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-03T06:40:34.962152Z","caller":"traceutil/trace.go:171","msg":"trace[1366517290] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26442; }","duration":"180.302521ms","start":"2026-10-03T06:40:34.781829Z","end":"2026-10-03T06:40:34.962131Z","steps":["trace[1366517290] 'agreement among raft nodes before linearized reading' (duration: 180.076537ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:38.644347Z","caller":"traceutil/trace.go:171","msg":"trace[1586998511] transaction","detail":"{read_only:false; response_revision:26473; number_of_response:1; }","duration":"126.799517ms","start":"2026-10-03T06:40:38.517518Z","end":"2026-10-03T06:40:38.644318Z","steps":["trace[1586998511] 'process raft request' (duration: 126.616913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:38.841583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.554925ms","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-03T06:40:38.841676Z","caller":"traceutil/trace.go:171","msg":"trace[479590115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26473; }","duration":"102.701398ms","start":"2026-10-03T06:40:38.738951Z","end":"2026-10-03T06:40:38.841653Z","steps":["trace[479590115] 'range keys from in-memory index tree' (duration: 102.377942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:39.23328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.466855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:39.233375Z","caller":"traceutil/trace.go:171","msg":"trace[1242325836] linearizableReadLoop","detail":"{readStateIndex:27361; appliedIndex:27360; }","duration":"252.071092ms","start":"2026-10-03T06:40:38.981287Z","end":"2026-10-03T06:40:39.233358Z","steps":["trace[1242325836] 'read index received' (duration: 129.490006ms)","trace[1242325836] 'applied index is now lower than readState.Index' (duration: 122.579906ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:39.233581Z","caller":"traceutil/trace.go:171","msg":"trace[409735362] transaction","detail":"{read_only:false; response_revision:26475; number_of_response:1; }","duration":"257.427775ms","start":"2026-10-03T06:40:38.97614Z","end":"2026-10-03T06:40:39.233567Z","steps":["trace[409735362] 'process raft request' (duration: 134.589144ms)","trace[409735362] 'compare' (duration: 122.280361ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:39.233726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.414298ms","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-03T06:40:39.233801Z","caller":"traceutil/trace.go:171","msg":"trace[1181605913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26475; }","duration":"252.539141ms","start":"2026-10-03T06:40:38.981244Z","end":"2026-10-03T06:40:39.233784Z","steps":["trace[1181605913] 'agreement among raft nodes before linearized reading' (duration: 252.308977ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:39.239951Z","caller":"traceutil/trace.go:171","msg":"trace[778702180] transaction","detail":"{read_only:false; response_revision:26476; number_of_response:1; }","duration":"257.621149ms","start":"2026-10-03T06:40:38.982305Z","end":"2026-10-03T06:40:39.239927Z","steps":["trace[778702180] 'process raft request' (duration: 257.436785ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:39.240138Z","caller":"traceutil/trace.go:171","msg":"trace[1067774870] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"257.652228ms","start":"2026-10-03T06:40:38.982469Z","end":"2026-10-03T06:40:39.240121Z","steps":["trace[1067774870] 'process raft request' (duration: 257.406014ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:39.240292Z","caller":"traceutil/trace.go:171","msg":"trace[2052329087] transaction","detail":"{read_only:false; response_revision:26478; number_of_response:1; }","duration":"257.198251ms","start":"2026-10-03T06:40:38.983084Z","end":"2026-10-03T06:40:39.240282Z","steps":["trace[2052329087] 'process raft request' (duration: 256.935875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:39.240441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.861283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T06:40:39.240487Z","caller":"traceutil/trace.go:171","msg":"trace[1761387644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26478; }","duration":"237.952405ms","start":"2026-10-03T06:40:39.002524Z","end":"2026-10-03T06:40:39.240476Z","steps":["trace[1761387644] 'agreement among raft nodes before linearized reading' (duration: 237.751201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:39.24051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.440543ms","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-03T06:40:39.240594Z","caller":"traceutil/trace.go:171","msg":"trace[1351527492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26478; }","duration":"229.574806ms","start":"2026-10-03T06:40:39.010999Z","end":"2026-10-03T06:40:39.240574Z","steps":["trace[1351527492] 'agreement among raft nodes before linearized reading' (duration: 229.322901ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:39.240575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.541018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T06:40:39.240661Z","caller":"traceutil/trace.go:171","msg":"trace[845032401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26478; }","duration":"236.662021ms","start":"2026-10-03T06:40:39.00398Z","end":"2026-10-03T06:40:39.240642Z","steps":["trace[845032401] 'agreement among raft nodes before linearized reading' (duration: 236.381286ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:39.417728Z","caller":"traceutil/trace.go:171","msg":"trace[1832121670] linearizableReadLoop","detail":"{readStateIndex:27368; appliedIndex:27365; }","duration":"114.060765ms","start":"2026-10-03T06:40:39.303635Z","end":"2026-10-03T06:40:39.417696Z","steps":["trace[1832121670] 'read index received' (duration: 107.323887ms)","trace[1832121670] 'applied index is now lower than readState.Index' (duration: 6.735088ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:39.41788Z","caller":"traceutil/trace.go:171","msg":"trace[1278570561] transaction","detail":"{read_only:false; response_revision:26482; number_of_response:1; }","duration":"171.833671ms","start":"2026-10-03T06:40:39.246033Z","end":"2026-10-03T06:40:39.417867Z","steps":["trace[1278570561] 'process raft request' (duration: 171.609996ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:39.417925Z","caller":"traceutil/trace.go:171","msg":"trace[2107234321] transaction","detail":"{read_only:false; response_revision:26481; number_of_response:1; }","duration":"172.668047ms","start":"2026-10-03T06:40:39.245212Z","end":"2026-10-03T06:40:39.41788Z","steps":["trace[2107234321] 'process raft request' (duration: 172.377801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:39.418073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.381462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:39.418155Z","caller":"traceutil/trace.go:171","msg":"trace[1771249382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26482; }","duration":"114.529353ms","start":"2026-10-03T06:40:39.303607Z","end":"2026-10-03T06:40:39.418136Z","steps":["trace[1771249382] 'agreement among raft nodes before linearized reading' (duration: 114.296509ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:39.418417Z","caller":"traceutil/trace.go:171","msg":"trace[48482108] transaction","detail":"{read_only:false; response_revision:26480; number_of_response:1; }","duration":"173.347099ms","start":"2026-10-03T06:40:39.245051Z","end":"2026-10-03T06:40:39.418398Z","steps":["trace[48482108] 'process raft request' (duration: 165.974419ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:39.776206Z","caller":"traceutil/trace.go:171","msg":"trace[1110543520] linearizableReadLoop","detail":"{readStateIndex:27369; appliedIndex:27368; }","duration":"358.359299ms","start":"2026-10-03T06:40:39.41782Z","end":"2026-10-03T06:40:39.776179Z","steps":["trace[1110543520] 'read index received' (duration: 358.156906ms)","trace[1110543520] 'applied index is now lower than readState.Index' (duration: 201.043µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:39.776315Z","caller":"traceutil/trace.go:171","msg":"trace[1401932945] transaction","detail":"{read_only:false; response_revision:26483; number_of_response:1; }","duration":"365.126089ms","start":"2026-10-03T06:40:39.41116Z","end":"2026-10-03T06:40:39.776286Z","steps":["trace[1401932945] 'process raft request' (duration: 364.808562ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:39.776401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.540278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:39.776448Z","caller":"traceutil/trace.go:171","msg":"trace[1181160383] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:26483; }","duration":"422.65546ms","start":"2026-10-03T06:40:39.353778Z","end":"2026-10-03T06:40:39.776433Z","steps":["trace[1181160383] 'agreement among raft nodes before linearized reading' (duration: 422.528678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:39.776472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:39.411135Z","time spent":"365.249551ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:39.776482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:39.353741Z","time spent":"422.730351ms","remote":"127.0.0.1:42390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-10-03T06:40:39.776576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.12536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T06:40:39.776577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.35029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:39.776642Z","caller":"traceutil/trace.go:171","msg":"trace[456347270] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:26483; }","duration":"246.271683ms","start":"2026-10-03T06:40:39.530353Z","end":"2026-10-03T06:40:39.776625Z","steps":["trace[456347270] 'agreement among raft nodes before linearized reading' (duration: 246.11985ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:39.776674Z","caller":"traceutil/trace.go:171","msg":"trace[1941262658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26483; }","duration":"357.495473ms","start":"2026-10-03T06:40:39.419152Z","end":"2026-10-03T06:40:39.776648Z","steps":["trace[1941262658] 'agreement among raft nodes before linearized reading' (duration: 357.315899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:39.776739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:39.419138Z","time spent":"357.588255ms","remote":"127.0.0.1:51926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T06:40:39.777028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.572265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274762"} {"level":"info","ts":"2026-10-03T06:40:39.77713Z","caller":"traceutil/trace.go:171","msg":"trace[1327157566] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26483; }","duration":"278.727958ms","start":"2026-10-03T06:40:39.498384Z","end":"2026-10-03T06:40:39.777111Z","steps":["trace[1327157566] 'agreement among raft nodes before linearized reading' (duration: 278.091026ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:39.934537Z","caller":"traceutil/trace.go:171","msg":"trace[1525050295] transaction","detail":"{read_only:false; response_revision:26484; number_of_response:1; }","duration":"141.306851ms","start":"2026-10-03T06:40:39.793191Z","end":"2026-10-03T06:40:39.934498Z","steps":["trace[1525050295] 'process raft request' (duration: 137.740343ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:39.935026Z","caller":"traceutil/trace.go:171","msg":"trace[1761538168] transaction","detail":"{read_only:false; response_revision:26485; number_of_response:1; }","duration":"129.249373ms","start":"2026-10-03T06:40:39.805716Z","end":"2026-10-03T06:40:39.934966Z","steps":["trace[1761538168] 'process raft request' (duration: 128.721603ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:40.783963Z","caller":"traceutil/trace.go:171","msg":"trace[1359938037] transaction","detail":"{read_only:false; response_revision:26488; number_of_response:1; }","duration":"150.837932ms","start":"2026-10-03T06:40:40.633105Z","end":"2026-10-03T06:40:40.783943Z","steps":["trace[1359938037] 'process raft request' (duration: 132.422682ms)","trace[1359938037] 'compare' (duration: 18.328629ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:41.238823Z","caller":"traceutil/trace.go:171","msg":"trace[2099291259] transaction","detail":"{read_only:false; response_revision:26489; number_of_response:1; }","duration":"190.976864ms","start":"2026-10-03T06:40:41.047806Z","end":"2026-10-03T06:40:41.238783Z","steps":["trace[2099291259] 'process raft request' (duration: 190.714019ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:41.623234Z","caller":"traceutil/trace.go:171","msg":"trace[626399889] linearizableReadLoop","detail":"{readStateIndex:27381; appliedIndex:27380; }","duration":"319.217147ms","start":"2026-10-03T06:40:41.303987Z","end":"2026-10-03T06:40:41.623205Z","steps":["trace[626399889] 'read index received' (duration: 318.87799ms)","trace[626399889] 'applied index is now lower than readState.Index' (duration: 337.877µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:41.623321Z","caller":"traceutil/trace.go:171","msg":"trace[264754268] transaction","detail":"{read_only:false; response_revision:26494; number_of_response:1; }","duration":"341.368297ms","start":"2026-10-03T06:40:41.281913Z","end":"2026-10-03T06:40:41.623281Z","steps":["trace[264754268] 'process raft request' (duration: 341.063631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:41.623546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:41.281884Z","time spent":"341.52096ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:41.623571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.052534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-03T06:40:41.623545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.528532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:41.623616Z","caller":"traceutil/trace.go:171","msg":"trace[889856621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26494; }","duration":"201.160016ms","start":"2026-10-03T06:40:41.422439Z","end":"2026-10-03T06:40:41.623599Z","steps":["trace[889856621] 'agreement among raft nodes before linearized reading' (duration: 200.946162ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:41.623653Z","caller":"traceutil/trace.go:171","msg":"trace[1867312535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26494; }","duration":"319.675466ms","start":"2026-10-03T06:40:41.303954Z","end":"2026-10-03T06:40:41.623629Z","steps":["trace[1867312535] 'agreement among raft nodes before linearized reading' (duration: 319.428411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:41.623624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.873496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T06:40:41.623675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.831811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-03T06:40:41.623708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:41.303939Z","time spent":"319.759517ms","remote":"127.0.0.1:51926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-03T06:40:41.623737Z","caller":"traceutil/trace.go:171","msg":"trace[1291663117] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:26494; }","duration":"177.036259ms","start":"2026-10-03T06:40:41.446677Z","end":"2026-10-03T06:40:41.623713Z","steps":["trace[1291663117] 'agreement among raft nodes before linearized reading' (duration: 176.824175ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:41.623762Z","caller":"traceutil/trace.go:171","msg":"trace[565070286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26494; }","duration":"200.959544ms","start":"2026-10-03T06:40:41.422777Z","end":"2026-10-03T06:40:41.623737Z","steps":["trace[565070286] 'agreement among raft nodes before linearized reading' (duration: 200.727929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:41.62409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.26824ms","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-03T06:40:41.624163Z","caller":"traceutil/trace.go:171","msg":"trace[2015169430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26494; }","duration":"201.392042ms","start":"2026-10-03T06:40:41.422756Z","end":"2026-10-03T06:40:41.624148Z","steps":["trace[2015169430] 'agreement among raft nodes before linearized reading' (duration: 201.211298ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:41.945428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.562507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:41.945517Z","caller":"traceutil/trace.go:171","msg":"trace[744150932] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26497; }","duration":"238.664539ms","start":"2026-10-03T06:40:41.706832Z","end":"2026-10-03T06:40:41.945496Z","steps":["trace[744150932] 'range keys from in-memory index tree' (duration: 238.475665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:41.946038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.297213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:41.946121Z","caller":"traceutil/trace.go:171","msg":"trace[1634578073] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"163.532442ms","start":"2026-10-03T06:40:41.782574Z","end":"2026-10-03T06:40:41.946107Z","steps":["trace[1634578073] 'process raft request' (duration: 14.095567ms)","trace[1634578073] 'compare' (duration: 148.636811ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:42.242679Z","caller":"traceutil/trace.go:171","msg":"trace[341412789] linearizableReadLoop","detail":"{readStateIndex:27387; appliedIndex:27386; }","duration":"291.771467ms","start":"2026-10-03T06:40:41.950885Z","end":"2026-10-03T06:40:42.242657Z","steps":["trace[341412789] 'read index received' (duration: 254.704033ms)","trace[341412789] 'applied index is now lower than readState.Index' (duration: 37.066284ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:42.242777Z","caller":"traceutil/trace.go:171","msg":"trace[107773527] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"301.549523ms","start":"2026-10-03T06:40:41.941214Z","end":"2026-10-03T06:40:42.242764Z","steps":["trace[107773527] 'process raft request' (duration: 264.460319ms)","trace[107773527] 'compare' (duration: 36.592774ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:42.242868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:41.941176Z","time spent":"301.621094ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:42.242947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.037161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:42.243016Z","caller":"traceutil/trace.go:171","msg":"trace[1977116536] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26500; }","duration":"292.116703ms","start":"2026-10-03T06:40:41.950881Z","end":"2026-10-03T06:40:42.242998Z","steps":["trace[1977116536] 'agreement among raft nodes before linearized reading' (duration: 291.99683ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:42.245386Z","caller":"traceutil/trace.go:171","msg":"trace[1094784645] transaction","detail":"{read_only:false; response_revision:26501; number_of_response:1; }","duration":"210.451973ms","start":"2026-10-03T06:40:42.034909Z","end":"2026-10-03T06:40:42.245361Z","steps":["trace[1094784645] 'process raft request' (duration: 210.066426ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:43.416919Z","caller":"traceutil/trace.go:171","msg":"trace[984072122] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"168.313164ms","start":"2026-10-03T06:40:43.248579Z","end":"2026-10-03T06:40:43.416893Z","steps":["trace[984072122] 'process raft request' (duration: 168.155671ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:43.843626Z","caller":"traceutil/trace.go:171","msg":"trace[1247350688] transaction","detail":"{read_only:false; response_revision:26504; number_of_response:1; }","duration":"583.137325ms","start":"2026-10-03T06:40:43.26046Z","end":"2026-10-03T06:40:43.843598Z","steps":["trace[1247350688] 'process raft request' (duration: 583.029003ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:43.843968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.260446Z","time spent":"583.439761ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:43.844238Z","caller":"traceutil/trace.go:171","msg":"trace[1918025751] transaction","detail":"{read_only:false; response_revision:26503; number_of_response:1; }","duration":"583.787537ms","start":"2026-10-03T06:40:43.260434Z","end":"2026-10-03T06:40:43.844222Z","steps":["trace[1918025751] 'process raft request' (duration: 582.879571ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:43.8442Z","caller":"traceutil/trace.go:171","msg":"trace[1118919019] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"583.673895ms","start":"2026-10-03T06:40:43.260495Z","end":"2026-10-03T06:40:43.844169Z","steps":["trace[1118919019] 'process raft request' (duration: 583.054243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:43.844314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.260415Z","time spent":"583.852978ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:43.844388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.260474Z","time spent":"583.818907ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:43.844663Z","caller":"traceutil/trace.go:171","msg":"trace[324884745] linearizableReadLoop","detail":"{readStateIndex:27393; appliedIndex:27388; }","duration":"539.893384ms","start":"2026-10-03T06:40:43.304755Z","end":"2026-10-03T06:40:43.844648Z","steps":["trace[324884745] 'read index received' (duration: 112.051016ms)","trace[324884745] 'applied index is now lower than readState.Index' (duration: 427.841078ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:43.844808Z","caller":"traceutil/trace.go:171","msg":"trace[1560209449] transaction","detail":"{read_only:false; response_revision:26506; number_of_response:1; }","duration":"584.022141ms","start":"2026-10-03T06:40:43.260773Z","end":"2026-10-03T06:40:43.844795Z","steps":["trace[1560209449] 'process raft request' (duration: 583.305388ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:43.844902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.260748Z","time spent":"584.103473ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:43.845048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.296232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:43.845128Z","caller":"traceutil/trace.go:171","msg":"trace[461819306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26506; }","duration":"540.412404ms","start":"2026-10-03T06:40:43.304703Z","end":"2026-10-03T06:40:43.845115Z","steps":["trace[461819306] 'agreement among raft nodes before linearized reading' (duration: 540.298412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:43.845163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.304685Z","time spent":"540.468065ms","remote":"127.0.0.1:51942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T06:40:44.18665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.129081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:44.187139Z","caller":"traceutil/trace.go:171","msg":"trace[686753687] linearizableReadLoop","detail":"{readStateIndex:27394; appliedIndex:27393; }","duration":"342.386947ms","start":"2026-10-03T06:40:43.844706Z","end":"2026-10-03T06:40:44.187093Z","steps":["trace[686753687] 'read index received' (duration: 96.735206ms)","trace[686753687] 'applied index is now lower than readState.Index' (duration: 245.649121ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:44.187298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.514376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-03T06:40:44.187335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.324415ms","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-03T06:40:44.187362Z","caller":"traceutil/trace.go:171","msg":"trace[67259946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26507; }","duration":"540.614058ms","start":"2026-10-03T06:40:43.646731Z","end":"2026-10-03T06:40:44.187345Z","steps":["trace[67259946] 'agreement among raft nodes before linearized reading' (duration: 540.421744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.187334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.560031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T06:40:44.187377Z","caller":"traceutil/trace.go:171","msg":"trace[282545450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26507; }","duration":"557.406506ms","start":"2026-10-03T06:40:43.629957Z","end":"2026-10-03T06:40:44.187363Z","steps":["trace[282545450] 'agreement among raft nodes before linearized reading' (duration: 557.281734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.187405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.646713Z","time spent":"540.683349ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-03T06:40:44.187293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.605041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T06:40:44.187481Z","caller":"traceutil/trace.go:171","msg":"trace[165050008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26507; }","duration":"487.810406ms","start":"2026-10-03T06:40:43.699656Z","end":"2026-10-03T06:40:44.187467Z","steps":["trace[165050008] 'agreement among raft nodes before linearized reading' (duration: 487.47609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.18749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.629932Z","time spent":"557.485097ms","remote":"127.0.0.1:52220","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-03T06:40:44.187414Z","caller":"traceutil/trace.go:171","msg":"trace[1521509817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26507; }","duration":"487.685313ms","start":"2026-10-03T06:40:43.699712Z","end":"2026-10-03T06:40:44.187397Z","steps":["trace[1521509817] 'agreement among raft nodes before linearized reading' (duration: 487.457559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.187527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.699595Z","time spent":"487.916158ms","remote":"127.0.0.1:52220","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-03T06:40:44.187609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.699695Z","time spent":"487.865857ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-03T06:40:44.187388Z","caller":"traceutil/trace.go:171","msg":"trace[381889942] transaction","detail":"{read_only:false; response_revision:26507; number_of_response:1; }","duration":"537.087311ms","start":"2026-10-03T06:40:43.64987Z","end":"2026-10-03T06:40:44.186957Z","steps":["trace[381889942] 'process raft request' (duration: 291.559062ms)","trace[381889942] 'compare' (duration: 244.740013ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:44.187778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.649848Z","time spent":"537.849706ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:44.187988Z","caller":"traceutil/trace.go:171","msg":"trace[92361745] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"237.494406ms","start":"2026-10-03T06:40:43.950414Z","end":"2026-10-03T06:40:44.187908Z","steps":["trace[92361745] 'process raft request' (duration: 236.345564ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:44.529935Z","caller":"traceutil/trace.go:171","msg":"trace[1682465620] linearizableReadLoop","detail":"{readStateIndex:27397; appliedIndex:27395; }","duration":"342.714164ms","start":"2026-10-03T06:40:44.187207Z","end":"2026-10-03T06:40:44.529921Z","steps":["trace[1682465620] 'read index received' (duration: 342.52211ms)","trace[1682465620] 'applied index is now lower than readState.Index' (duration: 191.334µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:44.530041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.476739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:44.530075Z","caller":"traceutil/trace.go:171","msg":"trace[1210087847] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:26510; }","duration":"498.52515ms","start":"2026-10-03T06:40:44.031544Z","end":"2026-10-03T06:40:44.53007Z","steps":["trace[1210087847] 'agreement among raft nodes before linearized reading' (duration: 498.446748ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:44.530012Z","caller":"traceutil/trace.go:171","msg":"trace[1532423904] transaction","detail":"{read_only:false; response_revision:26510; number_of_response:1; }","duration":"510.943465ms","start":"2026-10-03T06:40:44.019044Z","end":"2026-10-03T06:40:44.529987Z","steps":["trace[1532423904] 'process raft request' (duration: 510.852533ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.530096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.031495Z","time spent":"498.59567ms","remote":"127.0.0.1:52336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-10-03T06:40:44.530089Z","caller":"traceutil/trace.go:171","msg":"trace[440663165] transaction","detail":"{read_only:false; response_revision:26509; number_of_response:1; }","duration":"577.320514ms","start":"2026-10-03T06:40:43.952731Z","end":"2026-10-03T06:40:44.530052Z","steps":["trace[440663165] 'process raft request' (duration: 577.056139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.530187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.01902Z","time spent":"511.091487ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:44.530201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.952705Z","time spent":"577.427826ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:44.53023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.809415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T06:40:44.530274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.843028ms","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-03T06:40:44.530278Z","caller":"traceutil/trace.go:171","msg":"trace[1044590572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26510; }","duration":"683.890445ms","start":"2026-10-03T06:40:43.846374Z","end":"2026-10-03T06:40:44.530264Z","steps":["trace[1044590572] 'agreement among raft nodes before linearized reading' (duration: 683.820054ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:44.530294Z","caller":"traceutil/trace.go:171","msg":"trace[809210155] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26510; }","duration":"583.912179ms","start":"2026-10-03T06:40:43.946376Z","end":"2026-10-03T06:40:44.530288Z","steps":["trace[809210155] 'agreement among raft nodes before linearized reading' (duration: 583.879108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.530306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.946357Z","time spent":"583.94625ms","remote":"127.0.0.1:42556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"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-03T06:40:44.530305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:43.846356Z","time spent":"683.942317ms","remote":"127.0.0.1:51926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-03T06:40:44.833807Z","caller":"traceutil/trace.go:171","msg":"trace[1206694741] transaction","detail":"{read_only:false; response_revision:26511; number_of_response:1; }","duration":"642.152335ms","start":"2026-10-03T06:40:44.191629Z","end":"2026-10-03T06:40:44.833782Z","steps":["trace[1206694741] 'process raft request' (duration: 574.933219ms)","trace[1206694741] 'compare' (duration: 66.89456ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:44.833943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.191605Z","time spent":"642.270147ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:44.834112Z","caller":"traceutil/trace.go:171","msg":"trace[1300975807] transaction","detail":"{read_only:false; response_revision:26513; number_of_response:1; }","duration":"641.641625ms","start":"2026-10-03T06:40:44.192455Z","end":"2026-10-03T06:40:44.834097Z","steps":["trace[1300975807] 'process raft request' (duration: 641.467032ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:44.834144Z","caller":"traceutil/trace.go:171","msg":"trace[955689182] transaction","detail":"{read_only:false; response_revision:26514; number_of_response:1; }","duration":"641.025464ms","start":"2026-10-03T06:40:44.193091Z","end":"2026-10-03T06:40:44.834117Z","steps":["trace[955689182] 'process raft request' (duration: 640.934702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.834261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.192426Z","time spent":"641.752347ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:44.834289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.193079Z","time spent":"641.124955ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:44.834523Z","caller":"traceutil/trace.go:171","msg":"trace[1574608333] transaction","detail":"{read_only:false; response_revision:26512; number_of_response:1; }","duration":"641.787768ms","start":"2026-10-03T06:40:44.192243Z","end":"2026-10-03T06:40:44.834031Z","steps":["trace[1574608333] 'process raft request' (duration: 641.480532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.834611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.19223Z","time spent":"642.339038ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:44.834633Z","caller":"traceutil/trace.go:171","msg":"trace[402032933] transaction","detail":"{read_only:false; response_revision:26515; number_of_response:1; }","duration":"587.09816ms","start":"2026-10-03T06:40:44.247522Z","end":"2026-10-03T06:40:44.83462Z","steps":["trace[402032933] 'process raft request' (duration: 586.55604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.834699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.247492Z","time spent":"587.170682ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:44.834874Z","caller":"traceutil/trace.go:171","msg":"trace[848704441] linearizableReadLoop","detail":"{readStateIndex:27403; appliedIndex:27397; }","duration":"304.866384ms","start":"2026-10-03T06:40:44.529975Z","end":"2026-10-03T06:40:44.834841Z","steps":["trace[848704441] 'read index received' (duration: 236.592589ms)","trace[848704441] 'applied index is now lower than readState.Index' (duration: 68.270905ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:44.834937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.34719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:44.834986Z","caller":"traceutil/trace.go:171","msg":"trace[1939961853] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:26516; }","duration":"329.424581ms","start":"2026-10-03T06:40:44.505549Z","end":"2026-10-03T06:40:44.834973Z","steps":["trace[1939961853] 'agreement among raft nodes before linearized reading' (duration: 329.333329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.835024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.505524Z","time spent":"329.491663ms","remote":"127.0.0.1:54170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-10-03T06:40:44.835128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.004576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T06:40:44.835196Z","caller":"traceutil/trace.go:171","msg":"trace[364949384] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:26516; }","duration":"531.15838ms","start":"2026-10-03T06:40:44.304011Z","end":"2026-10-03T06:40:44.835169Z","steps":["trace[364949384] 'agreement among raft nodes before linearized reading' (duration: 530.989447ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.835213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.352692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:44.835271Z","caller":"traceutil/trace.go:171","msg":"trace[451661085] transaction","detail":"{read_only:false; response_revision:26516; number_of_response:1; }","duration":"585.706012ms","start":"2026-10-03T06:40:44.24955Z","end":"2026-10-03T06:40:44.835256Z","steps":["trace[451661085] 'process raft request' (duration: 585.01674ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:44.835287Z","caller":"traceutil/trace.go:171","msg":"trace[298035162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26516; }","duration":"298.467203ms","start":"2026-10-03T06:40:44.5368Z","end":"2026-10-03T06:40:44.835267Z","steps":["trace[298035162] 'agreement among raft nodes before linearized reading' (duration: 298.329411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.835252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.303972Z","time spent":"531.26482ms","remote":"127.0.0.1:38424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-10-03T06:40:44.835347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.249521Z","time spent":"585.788965ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:44.83552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.852248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T06:40:44.83558Z","caller":"traceutil/trace.go:171","msg":"trace[1321215250] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:26516; }","duration":"583.96296ms","start":"2026-10-03T06:40:44.251603Z","end":"2026-10-03T06:40:44.835566Z","steps":["trace[1321215250] 'agreement among raft nodes before linearized reading' (duration: 583.860699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.835588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.522309ms","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":"warn","ts":"2026-10-03T06:40:44.835623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:44.251585Z","time spent":"584.025861ms","remote":"127.0.0.1:55302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":23,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"info","ts":"2026-10-03T06:40:44.835625Z","caller":"traceutil/trace.go:171","msg":"trace[1857374743] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26516; }","duration":"118.586069ms","start":"2026-10-03T06:40:44.717027Z","end":"2026-10-03T06:40:44.835613Z","steps":["trace[1857374743] 'agreement among raft nodes before linearized reading' (duration: 118.524398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:44.836541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.204026ms","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-03T06:40:44.836636Z","caller":"traceutil/trace.go:171","msg":"trace[344744114] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26516; }","duration":"252.324368ms","start":"2026-10-03T06:40:44.584297Z","end":"2026-10-03T06:40:44.836622Z","steps":["trace[344744114] 'agreement among raft nodes before linearized reading' (duration: 252.118144ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:45.857699Z","caller":"traceutil/trace.go:171","msg":"trace[1140526565] transaction","detail":"{read_only:false; response_revision:26517; number_of_response:1; }","duration":"430.839135ms","start":"2026-10-03T06:40:45.42684Z","end":"2026-10-03T06:40:45.857679Z","steps":["trace[1140526565] 'process raft request' (duration: 430.717112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:45.857842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:45.426813Z","time spent":"430.963747ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:45.879395Z","caller":"traceutil/trace.go:171","msg":"trace[1170601818] linearizableReadLoop","detail":"{readStateIndex:27405; appliedIndex:27404; }","duration":"235.113861ms","start":"2026-10-03T06:40:45.64426Z","end":"2026-10-03T06:40:45.879374Z","steps":["trace[1170601818] 'read index received' (duration: 213.618682ms)","trace[1170601818] 'applied index is now lower than readState.Index' (duration: 21.494159ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:45.879544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.265613ms","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-03T06:40:45.879583Z","caller":"traceutil/trace.go:171","msg":"trace[1723625144] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26520; }","duration":"235.357155ms","start":"2026-10-03T06:40:45.644214Z","end":"2026-10-03T06:40:45.879571Z","steps":["trace[1723625144] 'agreement among raft nodes before linearized reading' (duration: 235.267673ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:51.543985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.207384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:40:51.544129Z","caller":"traceutil/trace.go:171","msg":"trace[74948112] transaction","detail":"{read_only:false; response_revision:26561; number_of_response:1; }","duration":"533.056216ms","start":"2026-10-03T06:40:51.01105Z","end":"2026-10-03T06:40:51.544107Z","steps":["trace[74948112] 'process raft request' (duration: 259.623057ms)","trace[74948112] 'compare' (duration: 273.026291ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:51.544189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:51.011036Z","time spent":"533.122757ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:52.044999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.966561ms","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-03T06:40:52.0451Z","caller":"traceutil/trace.go:171","msg":"trace[750271764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26561; }","duration":"164.116624ms","start":"2026-10-03T06:40:51.880963Z","end":"2026-10-03T06:40:52.045057Z","steps":["trace[750271764] 'range keys from in-memory index tree' (duration: 163.648234ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:52.057094Z","caller":"traceutil/trace.go:171","msg":"trace[1497336523] transaction","detail":"{read_only:false; response_revision:26562; number_of_response:1; }","duration":"156.597712ms","start":"2026-10-03T06:40:51.900448Z","end":"2026-10-03T06:40:52.057046Z","steps":["trace[1497336523] 'process raft request' (duration: 156.426698ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:52.375808Z","caller":"traceutil/trace.go:171","msg":"trace[870091149] transaction","detail":"{read_only:false; response_revision:26563; number_of_response:1; }","duration":"474.593545ms","start":"2026-10-03T06:40:51.901185Z","end":"2026-10-03T06:40:52.375779Z","steps":["trace[870091149] 'process raft request' (duration: 450.689052ms)","trace[870091149] 'compare' (duration: 23.519096ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:40:52.375948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:51.901171Z","time spent":"474.704627ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:52.376141Z","caller":"traceutil/trace.go:171","msg":"trace[1174810363] transaction","detail":"{read_only:false; response_revision:26564; number_of_response:1; }","duration":"474.938652ms","start":"2026-10-03T06:40:51.901173Z","end":"2026-10-03T06:40:52.376111Z","steps":["trace[1174810363] 'process raft request' (duration: 474.562434ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:52.376259Z","caller":"traceutil/trace.go:171","msg":"trace[939575852] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"474.965143ms","start":"2026-10-03T06:40:51.901262Z","end":"2026-10-03T06:40:52.376228Z","steps":["trace[939575852] 'process raft request' (duration: 474.740488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:52.376318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:51.901156Z","time spent":"475.086914ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:52.376441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:51.901234Z","time spent":"475.106305ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:52.376487Z","caller":"traceutil/trace.go:171","msg":"trace[2119519142] transaction","detail":"{read_only:false; response_revision:26566; number_of_response:1; }","duration":"339.131914ms","start":"2026-10-03T06:40:52.037334Z","end":"2026-10-03T06:40:52.376466Z","steps":["trace[2119519142] 'process raft request' (duration: 338.827329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:52.376622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:52.037311Z","time spent":"339.242847ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:52.37674Z","caller":"traceutil/trace.go:171","msg":"trace[2143799683] linearizableReadLoop","detail":"{readStateIndex:27456; appliedIndex:27451; }","duration":"276.010828ms","start":"2026-10-03T06:40:52.100717Z","end":"2026-10-03T06:40:52.376728Z","steps":["trace[2143799683] 'read index received' (duration: 251.233007ms)","trace[2143799683] 'applied index is now lower than readState.Index' (duration: 24.776871ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:40:52.376715Z","caller":"traceutil/trace.go:171","msg":"trace[1575317319] transaction","detail":"{read_only:false; response_revision:26567; number_of_response:1; }","duration":"327.751819ms","start":"2026-10-03T06:40:52.048945Z","end":"2026-10-03T06:40:52.376697Z","steps":["trace[1575317319] 'process raft request' (duration: 327.425003ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:52.376745Z","caller":"traceutil/trace.go:171","msg":"trace[841007431] transaction","detail":"{read_only:false; response_revision:26568; number_of_response:1; }","duration":"161.531855ms","start":"2026-10-03T06:40:52.215184Z","end":"2026-10-03T06:40:52.376716Z","steps":["trace[841007431] 'process raft request' (duration: 161.420233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:52.376841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:52.048925Z","time spent":"327.863271ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:40:52.376903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.264724ms","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":"warn","ts":"2026-10-03T06:40:52.376852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.14044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:40:52.376975Z","caller":"traceutil/trace.go:171","msg":"trace[1278684574] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:26568; }","duration":"276.292673ms","start":"2026-10-03T06:40:52.100667Z","end":"2026-10-03T06:40:52.37696Z","steps":["trace[1278684574] 'agreement among raft nodes before linearized reading' (duration: 276.12471ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:52.376938Z","caller":"traceutil/trace.go:171","msg":"trace[1796993650] 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:26568; }","duration":"119.335245ms","start":"2026-10-03T06:40:52.257594Z","end":"2026-10-03T06:40:52.376929Z","steps":["trace[1796993650] 'agreement among raft nodes before linearized reading' (duration: 119.283354ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:40:52.883096Z","caller":"traceutil/trace.go:171","msg":"trace[2095350768] transaction","detail":"{read_only:false; response_revision:26569; number_of_response:1; }","duration":"448.578912ms","start":"2026-10-03T06:40:52.434459Z","end":"2026-10-03T06:40:52.883038Z","steps":["trace[2095350768] 'process raft request' (duration: 448.359437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:52.883296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:52.434443Z","time spent":"448.749676ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:40:52.884102Z","caller":"traceutil/trace.go:171","msg":"trace[1000640282] transaction","detail":"{read_only:false; response_revision:26570; number_of_response:1; }","duration":"300.218196ms","start":"2026-10-03T06:40:52.58382Z","end":"2026-10-03T06:40:52.884039Z","steps":["trace[1000640282] 'process raft request' (duration: 299.932661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:40:52.884267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:40:52.58379Z","time spent":"300.3903ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:01.936352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.591638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-159d83e7-0e8b-4258-803d-884d6e7fa95e\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-10-03T06:41:01.936377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.087878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-159d83e7-0e8b-4258-803d-884d6e7fa95e\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-03T06:41:01.936439Z","caller":"traceutil/trace.go:171","msg":"trace[1386690611] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-159d83e7-0e8b-4258-803d-884d6e7fa95e; range_end:; response_count:1; response_revision:26692; }","duration":"124.72353ms","start":"2026-10-03T06:41:01.811695Z","end":"2026-10-03T06:41:01.936419Z","steps":["trace[1386690611] 'range keys from in-memory index tree' (duration: 124.422604ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:01.936457Z","caller":"traceutil/trace.go:171","msg":"trace[1063269108] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-159d83e7-0e8b-4258-803d-884d6e7fa95e; range_end:; response_count:1; response_revision:26692; }","duration":"193.217551ms","start":"2026-10-03T06:41:01.743218Z","end":"2026-10-03T06:41:01.936435Z","steps":["trace[1063269108] 'range keys from in-memory index tree' (duration: 192.895424ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:02.260655Z","caller":"traceutil/trace.go:171","msg":"trace[826773470] transaction","detail":"{read_only:false; response_revision:26693; number_of_response:1; }","duration":"316.185276ms","start":"2026-10-03T06:41:01.944444Z","end":"2026-10-03T06:41:02.260629Z","steps":["trace[826773470] 'process raft request' (duration: 316.017093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:02.260799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:01.94442Z","time spent":"316.311559ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3740,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:02.544981Z","caller":"traceutil/trace.go:171","msg":"trace[166089769] linearizableReadLoop","detail":"{readStateIndex:27585; appliedIndex:27584; }","duration":"269.577041ms","start":"2026-10-03T06:41:02.27538Z","end":"2026-10-03T06:41:02.544957Z","steps":["trace[166089769] 'read index received' (duration: 269.497799ms)","trace[166089769] 'applied index is now lower than readState.Index' (duration: 77.942µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:02.545122Z","caller":"traceutil/trace.go:171","msg":"trace[340656149] transaction","detail":"{read_only:false; response_revision:26694; number_of_response:1; }","duration":"416.063533ms","start":"2026-10-03T06:41:02.129024Z","end":"2026-10-03T06:41:02.545087Z","steps":["trace[340656149] 'process raft request' (duration: 415.790739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:02.545282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:02.129004Z","time spent":"416.198516ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:02.545411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.955058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-159d83e7-0e8b-4258-803d-884d6e7fa95e\" ","response":"range_response_count:1 size:3758"} {"level":"info","ts":"2026-10-03T06:41:02.545462Z","caller":"traceutil/trace.go:171","msg":"trace[2027153160] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-159d83e7-0e8b-4258-803d-884d6e7fa95e; range_end:; response_count:1; response_revision:26694; }","duration":"270.10298ms","start":"2026-10-03T06:41:02.275345Z","end":"2026-10-03T06:41:02.545448Z","steps":["trace[2027153160] 'agreement among raft nodes before linearized reading' (duration: 269.741004ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:02.593607Z","caller":"traceutil/trace.go:171","msg":"trace[412056244] transaction","detail":"{read_only:false; response_revision:26696; number_of_response:1; }","duration":"179.662353ms","start":"2026-10-03T06:41:02.41392Z","end":"2026-10-03T06:41:02.593582Z","steps":["trace[412056244] 'process raft request' (duration: 179.579441ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:02.593615Z","caller":"traceutil/trace.go:171","msg":"trace[2100909589] transaction","detail":"{read_only:false; response_revision:26695; number_of_response:1; }","duration":"185.473143ms","start":"2026-10-03T06:41:02.408118Z","end":"2026-10-03T06:41:02.593591Z","steps":["trace[2100909589] 'process raft request' (duration: 185.245149ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:02.593847Z","caller":"traceutil/trace.go:171","msg":"trace[1005133398] transaction","detail":"{read_only:false; response_revision:26697; number_of_response:1; }","duration":"161.753863ms","start":"2026-10-03T06:41:02.432082Z","end":"2026-10-03T06:41:02.593836Z","steps":["trace[1005133398] 'process raft request' (duration: 161.456627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:02.594019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.485741ms","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-03T06:41:02.594013Z","caller":"traceutil/trace.go:171","msg":"trace[1550821424] transaction","detail":"{read_only:false; response_revision:26698; number_of_response:1; }","duration":"160.607732ms","start":"2026-10-03T06:41:02.433371Z","end":"2026-10-03T06:41:02.593979Z","steps":["trace[1550821424] 'process raft request' (duration: 160.403408ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:02.594055Z","caller":"traceutil/trace.go:171","msg":"trace[1687555573] 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:26698; }","duration":"188.569553ms","start":"2026-10-03T06:41:02.405473Z","end":"2026-10-03T06:41:02.594043Z","steps":["trace[1687555573] 'agreement among raft nodes before linearized reading' (duration: 188.494781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:02.594126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.188916ms","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-03T06:41:02.594155Z","caller":"traceutil/trace.go:171","msg":"trace[1160678032] 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:26698; }","duration":"137.221187ms","start":"2026-10-03T06:41:02.456924Z","end":"2026-10-03T06:41:02.594145Z","steps":["trace[1160678032] 'agreement among raft nodes before linearized reading' (duration: 137.174096ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:02.899635Z","caller":"traceutil/trace.go:171","msg":"trace[1864739453] transaction","detail":"{read_only:false; response_revision:26699; number_of_response:1; }","duration":"303.247871ms","start":"2026-10-03T06:41:02.596364Z","end":"2026-10-03T06:41:02.899611Z","steps":["trace[1864739453] 'process raft request' (duration: 303.083908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:02.899845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:02.596345Z","time spent":"303.424275ms","remote":"127.0.0.1:52010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1377,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:02.909627Z","caller":"traceutil/trace.go:171","msg":"trace[72912075] linearizableReadLoop","detail":"{readStateIndex:27591; appliedIndex:27589; }","duration":"310.120421ms","start":"2026-10-03T06:41:02.599491Z","end":"2026-10-03T06:41:02.909611Z","steps":["trace[72912075] 'read index received' (duration: 300.02898ms)","trace[72912075] 'applied index is now lower than readState.Index' (duration: 10.089861ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:02.909722Z","caller":"traceutil/trace.go:171","msg":"trace[1424069576] transaction","detail":"{read_only:false; response_revision:26700; number_of_response:1; }","duration":"310.417437ms","start":"2026-10-03T06:41:02.599281Z","end":"2026-10-03T06:41:02.909698Z","steps":["trace[1424069576] 'process raft request' (duration: 310.239964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:02.90982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:02.599252Z","time spent":"310.522698ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:02.909892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.396306ms","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-03T06:41:02.909987Z","caller":"traceutil/trace.go:171","msg":"trace[1768885734] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26700; }","duration":"310.515858ms","start":"2026-10-03T06:41:02.599455Z","end":"2026-10-03T06:41:02.909971Z","steps":["trace[1768885734] 'agreement among raft nodes before linearized reading' (duration: 310.279284ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:02.910119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:02.599417Z","time spent":"310.686742ms","remote":"127.0.0.1:52134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-10-03T06:41:03.532418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.776714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:41:03.532819Z","caller":"traceutil/trace.go:171","msg":"trace[1094530847] transaction","detail":"{read_only:false; response_revision:26703; number_of_response:1; }","duration":"542.482906ms","start":"2026-10-03T06:41:02.990313Z","end":"2026-10-03T06:41:03.532796Z","steps":["trace[1094530847] 'process raft request' (duration: 542.395895ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:03.532854Z","caller":"traceutil/trace.go:171","msg":"trace[180872415] linearizableReadLoop","detail":"{readStateIndex:27592; appliedIndex:27591; }","duration":"611.408955ms","start":"2026-10-03T06:41:02.921415Z","end":"2026-10-03T06:41:03.532824Z","steps":["trace[180872415] 'read index received' (duration: 301.192601ms)","trace[180872415] 'applied index is now lower than readState.Index' (duration: 310.213164ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:03.532925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:02.990296Z","time spent":"542.577778ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:03.532959Z","caller":"traceutil/trace.go:171","msg":"trace[350541546] transaction","detail":"{read_only:false; response_revision:26702; number_of_response:1; }","duration":"593.163898ms","start":"2026-10-03T06:41:02.93976Z","end":"2026-10-03T06:41:03.532923Z","steps":["trace[350541546] 'process raft request' (duration: 592.829721ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:03.53294Z","caller":"traceutil/trace.go:171","msg":"trace[254773618] transaction","detail":"{read_only:false; response_revision:26701; number_of_response:1; }","duration":"615.665966ms","start":"2026-10-03T06:41:02.9172Z","end":"2026-10-03T06:41:03.532866Z","steps":["trace[254773618] 'process raft request' (duration: 305.337151ms)","trace[254773618] 'compare' (duration: 309.57369ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:03.533005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.565178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:03.533089Z","caller":"traceutil/trace.go:171","msg":"trace[264737564] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26703; }","duration":"611.66479ms","start":"2026-10-03T06:41:02.921389Z","end":"2026-10-03T06:41:03.533054Z","steps":["trace[264737564] 'agreement among raft nodes before linearized reading' (duration: 611.553098ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.533092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.977441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T06:41:03.533114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:02.939734Z","time spent":"593.286171ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:03.533127Z","caller":"traceutil/trace.go:171","msg":"trace[638335433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26703; }","duration":"229.018851ms","start":"2026-10-03T06:41:03.304099Z","end":"2026-10-03T06:41:03.533118Z","steps":["trace[638335433] 'agreement among raft nodes before linearized reading' (duration: 228.93789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.533136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:02.921378Z","time spent":"611.751272ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-10-03T06:41:03.533166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:02.917184Z","time spent":"615.868169ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:03.53318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.428621ms","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-03T06:41:03.533223Z","caller":"traceutil/trace.go:171","msg":"trace[650044951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26703; }","duration":"486.502752ms","start":"2026-10-03T06:41:03.046707Z","end":"2026-10-03T06:41:03.53321Z","steps":["trace[650044951] 'agreement among raft nodes before linearized reading' (duration: 486.328159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.533232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.982345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-159d83e7-0e8b-4258-803d-884d6e7fa95e\" ","response":"range_response_count:1 size:3758"} {"level":"warn","ts":"2026-10-03T06:41:03.533237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.929662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-10-03T06:41:03.533265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:03.046694Z","time spent":"486.563164ms","remote":"127.0.0.1:52220","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-03T06:41:03.533295Z","caller":"traceutil/trace.go:171","msg":"trace[1332292298] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-159d83e7-0e8b-4258-803d-884d6e7fa95e; range_end:; response_count:1; response_revision:26703; }","duration":"583.090816ms","start":"2026-10-03T06:41:02.950185Z","end":"2026-10-03T06:41:03.533275Z","steps":["trace[1332292298] 'agreement among raft nodes before linearized reading' (duration: 582.854762ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:03.533307Z","caller":"traceutil/trace.go:171","msg":"trace[119187732] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26703; }","duration":"325.031495ms","start":"2026-10-03T06:41:03.208258Z","end":"2026-10-03T06:41:03.53329Z","steps":["trace[119187732] 'agreement among raft nodes before linearized reading' (duration: 324.840171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.533295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.311114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-03T06:41:03.533355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:03.208245Z","time spent":"325.101275ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-03T06:41:03.533356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:02.950162Z","time spent":"583.178458ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3781,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-159d83e7-0e8b-4258-803d-884d6e7fa95e\" "} {"level":"info","ts":"2026-10-03T06:41:03.533375Z","caller":"traceutil/trace.go:171","msg":"trace[1356981335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26703; }","duration":"490.409755ms","start":"2026-10-03T06:41:03.042945Z","end":"2026-10-03T06:41:03.533354Z","steps":["trace[1356981335] 'agreement among raft nodes before linearized reading' (duration: 490.179592ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.533357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.031952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-03T06:41:03.533309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.727077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-03T06:41:03.533423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:03.042937Z","time spent":"490.477156ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-03T06:41:03.53343Z","caller":"traceutil/trace.go:171","msg":"trace[579505581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26703; }","duration":"516.141474ms","start":"2026-10-03T06:41:03.017275Z","end":"2026-10-03T06:41:03.533416Z","steps":["trace[579505581] 'agreement among raft nodes before linearized reading' (duration: 515.961622ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.533461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:03.017262Z","time spent":"516.191265ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-03T06:41:03.533463Z","caller":"traceutil/trace.go:171","msg":"trace[1913348995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26703; }","duration":"486.875419ms","start":"2026-10-03T06:41:03.046567Z","end":"2026-10-03T06:41:03.533442Z","steps":["trace[1913348995] 'agreement among raft nodes before linearized reading' (duration: 486.599664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.533494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:03.046527Z","time spent":"486.95829ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-03T06:41:03.533657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.6356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:03.534508Z","caller":"traceutil/trace.go:171","msg":"trace[1533030865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26703; }","duration":"501.718531ms","start":"2026-10-03T06:41:03.032769Z","end":"2026-10-03T06:41:03.534487Z","steps":["trace[1533030865] 'agreement among raft nodes before linearized reading' (duration: 500.408827ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.534642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:03.032756Z","time spent":"501.872264ms","remote":"127.0.0.1:51942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-03T06:41:03.6955Z","caller":"traceutil/trace.go:171","msg":"trace[843878491] transaction","detail":"{read_only:false; response_revision:26704; number_of_response:1; }","duration":"158.596393ms","start":"2026-10-03T06:41:03.536878Z","end":"2026-10-03T06:41:03.695475Z","steps":["trace[843878491] 'process raft request' (duration: 158.39317ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:03.695517Z","caller":"traceutil/trace.go:171","msg":"trace[344302183] linearizableReadLoop","detail":"{readStateIndex:27595; appliedIndex:27594; }","duration":"158.056663ms","start":"2026-10-03T06:41:03.537426Z","end":"2026-10-03T06:41:03.695483Z","steps":["trace[344302183] 'read index received' (duration: 157.996271ms)","trace[344302183] 'applied index is now lower than readState.Index' (duration: 57.472µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:03.69589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.435249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-03T06:41:03.695941Z","caller":"traceutil/trace.go:171","msg":"trace[1769272422] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26704; }","duration":"158.534261ms","start":"2026-10-03T06:41:03.537397Z","end":"2026-10-03T06:41:03.695931Z","steps":["trace[1769272422] 'agreement among raft nodes before linearized reading' (duration: 158.177565ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:03.706494Z","caller":"traceutil/trace.go:171","msg":"trace[166136805] transaction","detail":"{read_only:false; response_revision:26705; number_of_response:1; }","duration":"168.932059ms","start":"2026-10-03T06:41:03.537536Z","end":"2026-10-03T06:41:03.706469Z","steps":["trace[166136805] 'process raft request' (duration: 168.760076ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:03.706891Z","caller":"traceutil/trace.go:171","msg":"trace[1638942117] transaction","detail":"{read_only:false; response_revision:26706; number_of_response:1; }","duration":"169.222435ms","start":"2026-10-03T06:41:03.537652Z","end":"2026-10-03T06:41:03.706874Z","steps":["trace[1638942117] 'process raft request' (duration: 168.778226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:03.706909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.247536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:03.706943Z","caller":"traceutil/trace.go:171","msg":"trace[1872306392] transaction","detail":"{read_only:false; response_revision:26707; number_of_response:1; }","duration":"168.821456ms","start":"2026-10-03T06:41:03.538109Z","end":"2026-10-03T06:41:03.706931Z","steps":["trace[1872306392] 'process raft request' (duration: 168.570782ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:03.70696Z","caller":"traceutil/trace.go:171","msg":"trace[841946287] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:26707; }","duration":"158.317976ms","start":"2026-10-03T06:41:03.548628Z","end":"2026-10-03T06:41:03.706946Z","steps":["trace[841946287] 'agreement among raft nodes before linearized reading' (duration: 158.206485ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:07.783911Z","caller":"traceutil/trace.go:171","msg":"trace[1689091208] transaction","detail":"{read_only:false; response_revision:26746; number_of_response:1; }","duration":"236.779117ms","start":"2026-10-03T06:41:07.547112Z","end":"2026-10-03T06:41:07.783891Z","steps":["trace[1689091208] 'process raft request' (duration: 236.635924ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:07.785286Z","caller":"traceutil/trace.go:171","msg":"trace[1097264726] transaction","detail":"{read_only:false; response_revision:26747; number_of_response:1; }","duration":"237.680985ms","start":"2026-10-03T06:41:07.547561Z","end":"2026-10-03T06:41:07.785242Z","steps":["trace[1097264726] 'process raft request' (duration: 237.470081ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:07.785313Z","caller":"traceutil/trace.go:171","msg":"trace[1840648050] transaction","detail":"{read_only:false; response_revision:26748; number_of_response:1; }","duration":"237.254338ms","start":"2026-10-03T06:41:07.548033Z","end":"2026-10-03T06:41:07.785287Z","steps":["trace[1840648050] 'process raft request' (duration: 237.152726ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:33.174229Z","caller":"traceutil/trace.go:171","msg":"trace[63294411] transaction","detail":"{read_only:false; response_revision:26966; number_of_response:1; }","duration":"107.198826ms","start":"2026-10-03T06:41:33.067005Z","end":"2026-10-03T06:41:33.174204Z","steps":["trace[63294411] 'process raft request' (duration: 107.071174ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:33.451829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.364691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-03T06:41:33.451827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.078815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:33.451892Z","caller":"traceutil/trace.go:171","msg":"trace[1144971945] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:26966; }","duration":"117.488683ms","start":"2026-10-03T06:41:33.334389Z","end":"2026-10-03T06:41:33.451878Z","steps":["trace[1144971945] 'count revisions from in-memory index tree' (duration: 117.281479ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:33.451927Z","caller":"traceutil/trace.go:171","msg":"trace[985795805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26966; }","duration":"147.265238ms","start":"2026-10-03T06:41:33.304642Z","end":"2026-10-03T06:41:33.451907Z","steps":["trace[985795805] 'range keys from in-memory index tree' (duration: 147.010053ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:34.010527Z","caller":"traceutil/trace.go:171","msg":"trace[1791595440] transaction","detail":"{read_only:false; response_revision:26967; number_of_response:1; }","duration":"130.089791ms","start":"2026-10-03T06:41:33.88041Z","end":"2026-10-03T06:41:34.0105Z","steps":["trace[1791595440] 'process raft request' (duration: 129.948879ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:34.277772Z","caller":"traceutil/trace.go:171","msg":"trace[309252189] linearizableReadLoop","detail":"{readStateIndex:27867; appliedIndex:27864; }","duration":"375.164767ms","start":"2026-10-03T06:41:33.902585Z","end":"2026-10-03T06:41:34.27775Z","steps":["trace[309252189] 'read index received' (duration: 107.734426ms)","trace[309252189] 'applied index is now lower than readState.Index' (duration: 267.429341ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:34.27787Z","caller":"traceutil/trace.go:171","msg":"trace[1697058272] transaction","detail":"{read_only:false; response_revision:26968; number_of_response:1; }","duration":"395.435841ms","start":"2026-10-03T06:41:33.882422Z","end":"2026-10-03T06:41:34.277858Z","steps":["trace[1697058272] 'process raft request' (duration: 370.283843ms)","trace[1697058272] 'compare' (duration: 24.803682ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:34.277918Z","caller":"traceutil/trace.go:171","msg":"trace[1429984700] transaction","detail":"{read_only:false; response_revision:26969; number_of_response:1; }","duration":"389.493739ms","start":"2026-10-03T06:41:33.888409Z","end":"2026-10-03T06:41:34.277903Z","steps":["trace[1429984700] 'process raft request' (duration: 389.253415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:34.277966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:33.8824Z","time spent":"395.492063ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:34.278013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:33.888394Z","time spent":"389.56441ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:34.278095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.460271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T06:41:34.278172Z","caller":"traceutil/trace.go:171","msg":"trace[689956137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26969; }","duration":"375.573884ms","start":"2026-10-03T06:41:33.902579Z","end":"2026-10-03T06:41:34.278153Z","steps":["trace[689956137] 'agreement among raft nodes before linearized reading' (duration: 375.322229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:34.278217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:33.902534Z","time spent":"375.673956ms","remote":"127.0.0.1:52220","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-03T06:41:34.312652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.494193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T06:41:34.312706Z","caller":"traceutil/trace.go:171","msg":"trace[97913232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26970; }","duration":"395.576525ms","start":"2026-10-03T06:41:33.917116Z","end":"2026-10-03T06:41:34.312693Z","steps":["trace[97913232] 'agreement among raft nodes before linearized reading' (duration: 395.396571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:34.3127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.376068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-03T06:41:34.312738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:33.917105Z","time spent":"395.625756ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-03T06:41:34.312758Z","caller":"traceutil/trace.go:171","msg":"trace[1546525315] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:26970; }","duration":"118.472921ms","start":"2026-10-03T06:41:34.194271Z","end":"2026-10-03T06:41:34.312744Z","steps":["trace[1546525315] 'agreement among raft nodes before linearized reading' (duration: 118.320208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:34.31275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.308749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-03T06:41:34.312815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.654964ms","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-03T06:41:34.312871Z","caller":"traceutil/trace.go:171","msg":"trace[1962646706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26970; }","duration":"397.749146ms","start":"2026-10-03T06:41:33.915108Z","end":"2026-10-03T06:41:34.312857Z","steps":["trace[1962646706] 'agreement among raft nodes before linearized reading' (duration: 397.399839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:34.312907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:33.915097Z","time spent":"397.801487ms","remote":"127.0.0.1:52220","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-03T06:41:34.312824Z","caller":"traceutil/trace.go:171","msg":"trace[1440258711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26970; }","duration":"405.418761ms","start":"2026-10-03T06:41:33.907385Z","end":"2026-10-03T06:41:34.312804Z","steps":["trace[1440258711] 'agreement among raft nodes before linearized reading' (duration: 405.151856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:34.312978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:33.907376Z","time spent":"405.589144ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-03T06:41:34.458512Z","caller":"traceutil/trace.go:171","msg":"trace[1693408950] linearizableReadLoop","detail":"{readStateIndex:27869; appliedIndex:27868; }","duration":"146.001194ms","start":"2026-10-03T06:41:34.312482Z","end":"2026-10-03T06:41:34.458483Z","steps":["trace[1693408950] 'read index received' (duration: 119.523ms)","trace[1693408950] 'applied index is now lower than readState.Index' (duration: 26.476474ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:34.458554Z","caller":"traceutil/trace.go:171","msg":"trace[278161239] transaction","detail":"{read_only:false; response_revision:26971; number_of_response:1; }","duration":"176.663837ms","start":"2026-10-03T06:41:34.281865Z","end":"2026-10-03T06:41:34.458529Z","steps":["trace[278161239] 'process raft request' (duration: 150.152722ms)","trace[278161239] 'compare' (duration: 26.28146ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:34.458677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.451772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:34.458717Z","caller":"traceutil/trace.go:171","msg":"trace[1250002493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26971; }","duration":"156.527414ms","start":"2026-10-03T06:41:34.302178Z","end":"2026-10-03T06:41:34.458705Z","steps":["trace[1250002493] 'agreement among raft nodes before linearized reading' (duration: 156.435382ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:34.607758Z","caller":"traceutil/trace.go:171","msg":"trace[2013795419] transaction","detail":"{read_only:false; response_revision:26973; number_of_response:1; }","duration":"291.271133ms","start":"2026-10-03T06:41:34.316463Z","end":"2026-10-03T06:41:34.607735Z","steps":["trace[2013795419] 'process raft request' (duration: 291.192272ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:34.607781Z","caller":"traceutil/trace.go:171","msg":"trace[1218611565] transaction","detail":"{read_only:false; response_revision:26972; number_of_response:1; }","duration":"291.396516ms","start":"2026-10-03T06:41:34.316352Z","end":"2026-10-03T06:41:34.607749Z","steps":["trace[1218611565] 'process raft request' (duration: 276.90439ms)","trace[1218611565] 'compare' (duration: 14.288802ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:34.607888Z","caller":"traceutil/trace.go:171","msg":"trace[1545893949] transaction","detail":"{read_only:false; response_revision:26974; number_of_response:1; }","duration":"291.303194ms","start":"2026-10-03T06:41:34.31656Z","end":"2026-10-03T06:41:34.607863Z","steps":["trace[1545893949] 'process raft request' (duration: 291.140781ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:34.607849Z","caller":"traceutil/trace.go:171","msg":"trace[1456919279] linearizableReadLoop","detail":"{readStateIndex:27872; appliedIndex:27869; }","duration":"149.233865ms","start":"2026-10-03T06:41:34.458585Z","end":"2026-10-03T06:41:34.607819Z","steps":["trace[1456919279] 'read index received' (duration: 134.604337ms)","trace[1456919279] 'applied index is now lower than readState.Index' (duration: 14.627798ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:34.608145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.393366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-03T06:41:34.608185Z","caller":"traceutil/trace.go:171","msg":"trace[23147698] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26974; }","duration":"282.473807ms","start":"2026-10-03T06:41:34.325701Z","end":"2026-10-03T06:41:34.608175Z","steps":["trace[23147698] 'agreement among raft nodes before linearized reading' (duration: 282.287203ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:34.608212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.465269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:34.608255Z","caller":"traceutil/trace.go:171","msg":"trace[2137777355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26974; }","duration":"148.547461ms","start":"2026-10-03T06:41:34.459696Z","end":"2026-10-03T06:41:34.608244Z","steps":["trace[2137777355] 'agreement among raft nodes before linearized reading' (duration: 148.45536ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:34.839705Z","caller":"traceutil/trace.go:171","msg":"trace[520189356] transaction","detail":"{read_only:false; response_revision:26978; number_of_response:1; }","duration":"117.3923ms","start":"2026-10-03T06:41:34.722284Z","end":"2026-10-03T06:41:34.839676Z","steps":["trace[520189356] 'process raft request' (duration: 117.115455ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:34.83971Z","caller":"traceutil/trace.go:171","msg":"trace[773529897] transaction","detail":"{read_only:false; response_revision:26977; number_of_response:1; }","duration":"118.110674ms","start":"2026-10-03T06:41:34.721553Z","end":"2026-10-03T06:41:34.839664Z","steps":["trace[773529897] 'process raft request' (duration: 117.646215ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:34.839894Z","caller":"traceutil/trace.go:171","msg":"trace[688701162] transaction","detail":"{read_only:false; response_revision:26979; number_of_response:1; }","duration":"114.027287ms","start":"2026-10-03T06:41:34.725856Z","end":"2026-10-03T06:41:34.839884Z","steps":["trace[688701162] 'process raft request' (duration: 113.735351ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:35.4268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.6052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:35.426916Z","caller":"traceutil/trace.go:171","msg":"trace[2111477253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26980; }","duration":"121.790963ms","start":"2026-10-03T06:41:35.305098Z","end":"2026-10-03T06:41:35.426889Z","steps":["trace[2111477253] 'range keys from in-memory index tree' (duration: 121.519788ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:36.203548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.966169ms","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-03T06:41:36.203665Z","caller":"traceutil/trace.go:171","msg":"trace[131946341] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26981; }","duration":"111.152572ms","start":"2026-10-03T06:41:36.09248Z","end":"2026-10-03T06:41:36.203632Z","steps":["trace[131946341] 'count revisions from in-memory index tree' (duration: 110.861486ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:36.773893Z","caller":"traceutil/trace.go:171","msg":"trace[171510794] transaction","detail":"{read_only:false; response_revision:26989; number_of_response:1; }","duration":"105.542834ms","start":"2026-10-03T06:41:36.668319Z","end":"2026-10-03T06:41:36.773861Z","steps":["trace[171510794] 'process raft request' (duration: 105.492183ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:36.774005Z","caller":"traceutil/trace.go:171","msg":"trace[1993278446] transaction","detail":"{read_only:false; response_revision:26988; number_of_response:1; }","duration":"154.75162ms","start":"2026-10-03T06:41:36.619202Z","end":"2026-10-03T06:41:36.773953Z","steps":["trace[1993278446] 'process raft request' (duration: 154.479315ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:36.774084Z","caller":"traceutil/trace.go:171","msg":"trace[289034746] transaction","detail":"{read_only:false; response_revision:26987; number_of_response:1; }","duration":"156.781589ms","start":"2026-10-03T06:41:36.617247Z","end":"2026-10-03T06:41:36.774029Z","steps":["trace[289034746] 'process raft request' (duration: 95.878322ms)","trace[289034746] 'compare' (duration: 60.201543ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:36.942246Z","caller":"traceutil/trace.go:171","msg":"trace[644783010] transaction","detail":"{read_only:false; response_revision:26990; number_of_response:1; }","duration":"131.874615ms","start":"2026-10-03T06:41:36.810342Z","end":"2026-10-03T06:41:36.942217Z","steps":["trace[644783010] 'process raft request' (duration: 131.666261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:37.178316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.576226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:41:37.178557Z","caller":"traceutil/trace.go:171","msg":"trace[887008889] transaction","detail":"{read_only:false; response_revision:26991; number_of_response:1; }","duration":"333.069137ms","start":"2026-10-03T06:41:36.845461Z","end":"2026-10-03T06:41:37.178531Z","steps":["trace[887008889] 'process raft request' (duration: 209.158994ms)","trace[887008889] 'compare' (duration: 123.311473ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:37.178679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:36.845438Z","time spent":"333.194919ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T06:41:37.179298Z","caller":"traceutil/trace.go:171","msg":"trace[826985526] linearizableReadLoop","detail":"{readStateIndex:27892; appliedIndex:27889; }","duration":"211.461577ms","start":"2026-10-03T06:41:36.967818Z","end":"2026-10-03T06:41:37.17928Z","steps":["trace[826985526] 'read index received' (duration: 86.886791ms)","trace[826985526] 'applied index is now lower than readState.Index' (duration: 124.573076ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:37.179342Z","caller":"traceutil/trace.go:171","msg":"trace[737819623] transaction","detail":"{read_only:false; response_revision:26992; number_of_response:1; }","duration":"333.726159ms","start":"2026-10-03T06:41:36.845583Z","end":"2026-10-03T06:41:37.17931Z","steps":["trace[737819623] 'process raft request' (duration: 332.853242ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:37.17937Z","caller":"traceutil/trace.go:171","msg":"trace[1447788412] transaction","detail":"{read_only:false; response_revision:26993; number_of_response:1; }","duration":"333.550077ms","start":"2026-10-03T06:41:36.845788Z","end":"2026-10-03T06:41:37.179338Z","steps":["trace[1447788412] 'process raft request' (duration: 333.387344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:37.179536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:36.845565Z","time spent":"333.852282ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:37.179536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.710141ms","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-03T06:41:37.179612Z","caller":"traceutil/trace.go:171","msg":"trace[1446469573] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:26993; }","duration":"211.829654ms","start":"2026-10-03T06:41:36.967763Z","end":"2026-10-03T06:41:37.179592Z","steps":["trace[1446469573] 'agreement among raft nodes before linearized reading' (duration: 211.588379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:37.179539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T06:41:36.845775Z","time spent":"333.686289ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T06:41:37.403829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.252661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-10-03T06:41:37.403916Z","caller":"traceutil/trace.go:171","msg":"trace[367370233] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26994; }","duration":"114.399253ms","start":"2026-10-03T06:41:37.289494Z","end":"2026-10-03T06:41:37.403893Z","steps":["trace[367370233] 'range keys from in-memory index tree' (duration: 114.039426ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:37.661136Z","caller":"traceutil/trace.go:171","msg":"trace[530254130] transaction","detail":"{read_only:false; response_revision:26995; number_of_response:1; }","duration":"178.596983ms","start":"2026-10-03T06:41:37.482512Z","end":"2026-10-03T06:41:37.661109Z","steps":["trace[530254130] 'process raft request' (duration: 178.399289ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:38.503329Z","caller":"traceutil/trace.go:171","msg":"trace[37542684] transaction","detail":"{read_only:false; response_revision:26999; number_of_response:1; }","duration":"163.897783ms","start":"2026-10-03T06:41:38.339404Z","end":"2026-10-03T06:41:38.503288Z","steps":["trace[37542684] 'process raft request' (duration: 104.561746ms)","trace[37542684] 'compare' (duration: 59.200915ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:48.809232Z","caller":"traceutil/trace.go:171","msg":"trace[1639201904] transaction","detail":"{read_only:false; response_revision:27073; number_of_response:1; }","duration":"110.712884ms","start":"2026-10-03T06:41:48.69848Z","end":"2026-10-03T06:41:48.809193Z","steps":["trace[1639201904] 'process raft request' (duration: 110.409428ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:49.001713Z","caller":"traceutil/trace.go:171","msg":"trace[1488557906] linearizableReadLoop","detail":"{readStateIndex:27977; appliedIndex:27976; }","duration":"167.567153ms","start":"2026-10-03T06:41:48.834125Z","end":"2026-10-03T06:41:49.001692Z","steps":["trace[1488557906] 'read index received' (duration: 24.22867ms)","trace[1488557906] 'applied index is now lower than readState.Index' (duration: 143.337453ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:49.001711Z","caller":"traceutil/trace.go:171","msg":"trace[1146627256] transaction","detail":"{read_only:false; response_revision:27075; number_of_response:1; }","duration":"187.274027ms","start":"2026-10-03T06:41:48.814401Z","end":"2026-10-03T06:41:49.001675Z","steps":["trace[1146627256] 'process raft request' (duration: 107.794278ms)","trace[1146627256] 'compare' (duration: 79.239965ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:41:49.001998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.426852ms","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-03T06:41:49.002032Z","caller":"traceutil/trace.go:171","msg":"trace[1714433165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27076; }","duration":"166.493303ms","start":"2026-10-03T06:41:48.835528Z","end":"2026-10-03T06:41:49.002022Z","steps":["trace[1714433165] 'agreement among raft nodes before linearized reading' (duration: 166.309649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.002136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.979291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-03T06:41:49.002243Z","caller":"traceutil/trace.go:171","msg":"trace[1541748175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27076; }","duration":"168.128674ms","start":"2026-10-03T06:41:48.834088Z","end":"2026-10-03T06:41:49.002217Z","steps":["trace[1541748175] 'agreement among raft nodes before linearized reading' (duration: 167.686515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.255128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.715972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:41:49.25526Z","caller":"traceutil/trace.go:171","msg":"trace[292610899] transaction","detail":"{read_only:false; response_revision:27078; number_of_response:1; }","duration":"248.205916ms","start":"2026-10-03T06:41:49.007025Z","end":"2026-10-03T06:41:49.255231Z","steps":["trace[292610899] 'process raft request' (duration: 145.266799ms)","trace[292610899] 'compare' (duration: 102.351945ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:49.458818Z","caller":"traceutil/trace.go:171","msg":"trace[170186290] transaction","detail":"{read_only:false; response_revision:27079; number_of_response:1; }","duration":"169.162304ms","start":"2026-10-03T06:41:49.289624Z","end":"2026-10-03T06:41:49.458786Z","steps":["trace[170186290] 'process raft request' (duration: 165.327011ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:49.478413Z","caller":"traceutil/trace.go:171","msg":"trace[2125404598] linearizableReadLoop","detail":"{readStateIndex:27983; appliedIndex:27980; }","duration":"174.269601ms","start":"2026-10-03T06:41:49.304123Z","end":"2026-10-03T06:41:49.478393Z","steps":["trace[2125404598] 'read index received' (duration: 150.699812ms)","trace[2125404598] 'applied index is now lower than readState.Index' (duration: 23.568698ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:41:49.478478Z","caller":"traceutil/trace.go:171","msg":"trace[999665218] transaction","detail":"{read_only:false; response_revision:27081; number_of_response:1; }","duration":"179.080081ms","start":"2026-10-03T06:41:49.299361Z","end":"2026-10-03T06:41:49.478441Z","steps":["trace[999665218] 'process raft request' (duration: 178.951729ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:49.478555Z","caller":"traceutil/trace.go:171","msg":"trace[1891556719] transaction","detail":"{read_only:false; response_revision:27080; number_of_response:1; }","duration":"182.706501ms","start":"2026-10-03T06:41:49.295828Z","end":"2026-10-03T06:41:49.478534Z","steps":["trace[1891556719] 'process raft request' (duration: 182.388755ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:41:49.478612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.438508ms","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-03T06:41:49.478597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.440104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:41:49.478565Z","caller":"traceutil/trace.go:171","msg":"trace[1860029702] transaction","detail":"{read_only:false; response_revision:27082; number_of_response:1; }","duration":"146.536314ms","start":"2026-10-03T06:41:49.331944Z","end":"2026-10-03T06:41:49.47848Z","steps":["trace[1860029702] 'process raft request' (duration: 146.411022ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:49.47871Z","caller":"traceutil/trace.go:171","msg":"trace[1130319096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27082; }","duration":"174.581607ms","start":"2026-10-03T06:41:49.3041Z","end":"2026-10-03T06:41:49.478681Z","steps":["trace[1130319096] 'agreement among raft nodes before linearized reading' (duration: 174.382963ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:49.478652Z","caller":"traceutil/trace.go:171","msg":"trace[84703414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27082; }","duration":"159.53975ms","start":"2026-10-03T06:41:49.319101Z","end":"2026-10-03T06:41:49.478641Z","steps":["trace[84703414] 'agreement among raft nodes before linearized reading' (duration: 159.393407ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:41:54.334327Z","caller":"traceutil/trace.go:171","msg":"trace[1287946750] transaction","detail":"{read_only:false; response_revision:27116; number_of_response:1; }","duration":"162.051829ms","start":"2026-10-03T06:41:54.172246Z","end":"2026-10-03T06:41:54.334298Z","steps":["trace[1287946750] 'process raft request' (duration: 161.321175ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:00.364571Z","caller":"traceutil/trace.go:171","msg":"trace[903581754] transaction","detail":"{read_only:false; response_revision:27159; number_of_response:1; }","duration":"179.794865ms","start":"2026-10-03T06:42:00.184751Z","end":"2026-10-03T06:42:00.364546Z","steps":["trace[903581754] 'process raft request' (duration: 179.432178ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:42:00.586553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.172429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:42:00.586653Z","caller":"traceutil/trace.go:171","msg":"trace[679421472] linearizableReadLoop","detail":"{readStateIndex:28064; appliedIndex:28063; }","duration":"222.287343ms","start":"2026-10-03T06:42:00.36435Z","end":"2026-10-03T06:42:00.586637Z","steps":["trace[679421472] 'read index received' (duration: 88.581863ms)","trace[679421472] 'applied index is now lower than readState.Index' (duration: 133.70433ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:42:00.58672Z","caller":"traceutil/trace.go:171","msg":"trace[89558209] transaction","detail":"{read_only:false; response_revision:27160; number_of_response:1; }","duration":"232.098339ms","start":"2026-10-03T06:42:00.354611Z","end":"2026-10-03T06:42:00.586709Z","steps":["trace[89558209] 'process raft request' (duration: 98.688985ms)","trace[89558209] 'compare' (duration: 133.039487ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:42:00.587025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.21286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-03T06:42:00.587125Z","caller":"traceutil/trace.go:171","msg":"trace[694883188] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27160; }","duration":"244.365543ms","start":"2026-10-03T06:42:00.34274Z","end":"2026-10-03T06:42:00.587106Z","steps":["trace[694883188] 'agreement among raft nodes before linearized reading' (duration: 243.970795ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:42:00.610581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.620515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.147\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-03T06:42:00.610659Z","caller":"traceutil/trace.go:171","msg":"trace[1403159184] range","detail":"{range_begin:/registry/masterleases/162.253.55.147; range_end:; response_count:1; response_revision:27161; }","duration":"196.703986ms","start":"2026-10-03T06:42:00.413935Z","end":"2026-10-03T06:42:00.610639Z","steps":["trace[1403159184] 'agreement among raft nodes before linearized reading' (duration: 196.531393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:42:00.610709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.444983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T06:42:00.610778Z","caller":"traceutil/trace.go:171","msg":"trace[1809426394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27161; }","duration":"244.566225ms","start":"2026-10-03T06:42:00.366194Z","end":"2026-10-03T06:42:00.61076Z","steps":["trace[1809426394] 'agreement among raft nodes before linearized reading' (duration: 244.446863ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:00.826133Z","caller":"traceutil/trace.go:171","msg":"trace[208254810] linearizableReadLoop","detail":"{readStateIndex:28067; appliedIndex:28065; }","duration":"136.813379ms","start":"2026-10-03T06:42:00.689293Z","end":"2026-10-03T06:42:00.826106Z","steps":["trace[208254810] 'read index received' (duration: 72.150832ms)","trace[208254810] 'applied index is now lower than readState.Index' (duration: 64.661147ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:42:00.82633Z","caller":"traceutil/trace.go:171","msg":"trace[453570924] transaction","detail":"{read_only:false; response_revision:27162; number_of_response:1; }","duration":"177.42774ms","start":"2026-10-03T06:42:00.648852Z","end":"2026-10-03T06:42:00.826279Z","steps":["trace[453570924] 'process raft request' (duration: 177.055752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:42:00.82639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.103264ms","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-03T06:42:00.82644Z","caller":"traceutil/trace.go:171","msg":"trace[1027611995] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27162; }","duration":"137.201306ms","start":"2026-10-03T06:42:00.689223Z","end":"2026-10-03T06:42:00.826424Z","steps":["trace[1027611995] 'agreement among raft nodes before linearized reading' (duration: 136.986442ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:01.140103Z","caller":"traceutil/trace.go:171","msg":"trace[956218964] linearizableReadLoop","detail":"{readStateIndex:28069; appliedIndex:28068; }","duration":"267.479521ms","start":"2026-10-03T06:42:00.872591Z","end":"2026-10-03T06:42:01.14007Z","steps":["trace[956218964] 'read index received' (duration: 203.22734ms)","trace[956218964] 'applied index is now lower than readState.Index' (duration: 64.250531ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:42:01.140133Z","caller":"traceutil/trace.go:171","msg":"trace[1201041768] transaction","detail":"{read_only:false; response_revision:27164; number_of_response:1; }","duration":"268.624843ms","start":"2026-10-03T06:42:00.871477Z","end":"2026-10-03T06:42:01.140102Z","steps":["trace[1201041768] 'process raft request' (duration: 204.394283ms)","trace[1201041768] 'compare' (duration: 64.004606ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T06:42:01.140325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.713235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19027"} {"level":"info","ts":"2026-10-03T06:42:01.140416Z","caller":"traceutil/trace.go:171","msg":"trace[487172487] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27164; }","duration":"267.848268ms","start":"2026-10-03T06:42:00.872546Z","end":"2026-10-03T06:42:01.140395Z","steps":["trace[487172487] 'agreement among raft nodes before linearized reading' (duration: 267.628094ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:01.159375Z","caller":"traceutil/trace.go:171","msg":"trace[1194613726] transaction","detail":"{read_only:false; response_revision:27165; number_of_response:1; }","duration":"116.050924ms","start":"2026-10-03T06:42:01.043306Z","end":"2026-10-03T06:42:01.159357Z","steps":["trace[1194613726] 'process raft request' (duration: 115.893951ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:01.159589Z","caller":"traceutil/trace.go:171","msg":"trace[1224376584] transaction","detail":"{read_only:false; response_revision:27166; number_of_response:1; }","duration":"110.809494ms","start":"2026-10-03T06:42:01.04874Z","end":"2026-10-03T06:42:01.159549Z","steps":["trace[1224376584] 'process raft request' (duration: 110.58008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:42:01.159746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.419553ms","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-03T06:42:01.159833Z","caller":"traceutil/trace.go:171","msg":"trace[245844414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27166; }","duration":"234.546965ms","start":"2026-10-03T06:42:00.925265Z","end":"2026-10-03T06:42:01.159812Z","steps":["trace[245844414] 'agreement among raft nodes before linearized reading' (duration: 234.28963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:42:01.160249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.47721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-03T06:42:01.160315Z","caller":"traceutil/trace.go:171","msg":"trace[1726885056] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:27166; }","duration":"287.559863ms","start":"2026-10-03T06:42:00.87273Z","end":"2026-10-03T06:42:01.16029Z","steps":["trace[1726885056] 'agreement among raft nodes before linearized reading' (duration: 286.867349ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:01.341821Z","caller":"traceutil/trace.go:171","msg":"trace[411466575] transaction","detail":"{read_only:false; response_revision:27167; number_of_response:1; }","duration":"177.836178ms","start":"2026-10-03T06:42:01.163955Z","end":"2026-10-03T06:42:01.341791Z","steps":["trace[411466575] 'process raft request' (duration: 115.005225ms)","trace[411466575] 'compare' (duration: 62.71914ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:42:01.411127Z","caller":"traceutil/trace.go:171","msg":"trace[400394628] transaction","detail":"{read_only:false; response_revision:27168; number_of_response:1; }","duration":"247.095133ms","start":"2026-10-03T06:42:01.164002Z","end":"2026-10-03T06:42:01.411097Z","steps":["trace[400394628] 'process raft request' (duration: 246.762698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T06:42:01.649125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.151819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-10-03T06:42:01.649216Z","caller":"traceutil/trace.go:171","msg":"trace[147136797] transaction","detail":"{read_only:false; response_revision:27169; number_of_response:1; }","duration":"234.878712ms","start":"2026-10-03T06:42:01.414322Z","end":"2026-10-03T06:42:01.649201Z","steps":["trace[147136797] 'process raft request' (duration: 32.58519ms)","trace[147136797] 'compare' (duration: 201.747062ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T06:42:01.658814Z","caller":"traceutil/trace.go:171","msg":"trace[1480394139] transaction","detail":"{read_only:false; response_revision:27173; number_of_response:1; }","duration":"134.020025ms","start":"2026-10-03T06:42:01.524773Z","end":"2026-10-03T06:42:01.658793Z","steps":["trace[1480394139] 'process raft request' (duration: 133.987245ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:01.659265Z","caller":"traceutil/trace.go:171","msg":"trace[314794624] transaction","detail":"{read_only:false; response_revision:27170; number_of_response:1; }","duration":"243.906773ms","start":"2026-10-03T06:42:01.415349Z","end":"2026-10-03T06:42:01.659256Z","steps":["trace[314794624] 'process raft request' (duration: 243.22886ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:01.659357Z","caller":"traceutil/trace.go:171","msg":"trace[1728470446] transaction","detail":"{read_only:false; response_revision:27171; number_of_response:1; }","duration":"140.141892ms","start":"2026-10-03T06:42:01.519208Z","end":"2026-10-03T06:42:01.65935Z","steps":["trace[1728470446] 'process raft request' (duration: 139.48298ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:01.659444Z","caller":"traceutil/trace.go:171","msg":"trace[2028706200] transaction","detail":"{read_only:false; response_revision:27172; number_of_response:1; }","duration":"135.311561ms","start":"2026-10-03T06:42:01.524111Z","end":"2026-10-03T06:42:01.659423Z","steps":["trace[2028706200] 'process raft request' (duration: 134.617987ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:22.418145Z","caller":"traceutil/trace.go:171","msg":"trace[888699143] transaction","detail":"{read_only:false; response_revision:27476; number_of_response:1; }","duration":"167.851699ms","start":"2026-10-03T06:42:22.250264Z","end":"2026-10-03T06:42:22.418116Z","steps":["trace[888699143] 'process raft request' (duration: 167.603124ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T06:42:27.094076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24572} {"level":"info","ts":"2026-10-03T06:42:27.245865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24572,"took":"147.378889ms","hash":88096428,"current-db-size-bytes":57786368,"current-db-size":"58 MB","current-db-size-in-use-bytes":36028416,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-03T06:42:27.245941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":88096428,"revision":24572,"compact-revision":21577} {"level":"info","ts":"2026-10-03T06:45:15.943605Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T06:45:15.950689Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-03T06:45:15.950804Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-03T06:47:27.100985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27521} {"level":"info","ts":"2026-10-03T06:47:27.261346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27521,"took":"156.499833ms","hash":1948651757,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":35250176,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-03T06:47:27.261421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1948651757,"revision":27521,"compact-revision":24572} {"level":"info","ts":"2026-10-03T06:52:27.108553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30250} {"level":"info","ts":"2026-10-03T06:52:27.254313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30250,"took":"142.89388ms","hash":2750707538,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":30695424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T06:52:27.254393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2750707538,"revision":30250,"compact-revision":27521} {"level":"info","ts":"2026-10-03T06:57:27.117166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32504} {"level":"info","ts":"2026-10-03T06:57:27.266703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32504,"took":"145.242334ms","hash":1399319130,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":30281728,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T06:57:27.266772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1399319130,"revision":32504,"compact-revision":30250} {"level":"info","ts":"2026-10-03T07:02:27.124423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34729} {"level":"info","ts":"2026-10-03T07:02:27.260842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34729,"took":"132.325189ms","hash":2713642515,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":31563776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T07:02:27.260908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2713642515,"revision":34729,"compact-revision":32504} {"level":"info","ts":"2026-10-03T07:06:02.145027Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T07:06:02.152226Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-03T07:06:02.152321Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-03T07:07:27.132876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37090} {"level":"info","ts":"2026-10-03T07:07:27.265525Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37090,"took":"127.997823ms","hash":3880380330,"current-db-size-bytes":60755968,"current-db-size":"61 MB","current-db-size-in-use-bytes":33275904,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-03T07:07:27.265615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3880380330,"revision":37090,"compact-revision":34729} {"level":"info","ts":"2026-10-03T07:12:27.141053Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39399} {"level":"info","ts":"2026-10-03T07:12:27.278324Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39399,"took":"131.159713ms","hash":1980437107,"current-db-size-bytes":60755968,"current-db-size":"61 MB","current-db-size-in-use-bytes":30019584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-03T07:12:27.278448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1980437107,"revision":39399,"compact-revision":37090} {"level":"info","ts":"2026-10-03T07:17:27.152206Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41629} {"level":"info","ts":"2026-10-03T07:17:27.2846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41629,"took":"126.084187ms","hash":230427113,"current-db-size-bytes":60755968,"current-db-size":"61 MB","current-db-size-in-use-bytes":29175808,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-03T07:17:27.284662Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":230427113,"revision":41629,"compact-revision":39399} {"level":"info","ts":"2026-10-03T07:22:27.161162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43855} {"level":"info","ts":"2026-10-03T07:22:27.286913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43855,"took":"121.959029ms","hash":504521098,"current-db-size-bytes":60755968,"current-db-size":"61 MB","current-db-size-in-use-bytes":28921856,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-03T07:22:27.286996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":504521098,"revision":43855,"compact-revision":41629}