{"level":"warn","ts":"2026-04-09T05:57:54.37474Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T05:57:54.374874Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110: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.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110: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-04-09T05:57:54.374992Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T05:57:54.375008Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-04-09T05:57:54.37503Z","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-04-09T05:57:54.375799Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-04-09T05:57:54.37589Z","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.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110: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-04-09T05:57:54.380018Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.803301ms"} {"level":"info","ts":"2026-04-09T05:57:54.389116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-04-09T05:57:54.389249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T05:57:54.389288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-04-09T05:57:54.389309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T05:57:54.389329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-04-09T05:57:54.389384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-04-09T05:57:54.402721Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T05:57:54.404475Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T05:57:54.405884Z","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-04-09T05:57:54.409341Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T05:57:54.409942Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T05:57:54.41013Z","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-04-09T05:57:54.410272Z","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-04-09T05:57:54.410306Z","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-04-09T05:57:54.411192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-04-09T05:57:54.413231Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-04-09T05:57:54.415247Z","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-04-09T05:57:54.415668Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T05:57:54.415738Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T05:57:54.41623Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-04-09T05:57:54.416847Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-04-09T05:57:54.889579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T05:57:54.889687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T05:57:54.889718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-04-09T05:57:54.889744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-04-09T05:57:54.889758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-04-09T05:57:54.889777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-04-09T05:57:54.8898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-04-09T05:57:54.891954Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T05:57:54.892066Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:57:54.892326Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T05:57:54.893197Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T05:57:54.893238Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T05:57:54.895599Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-04-09T05:57:54.896524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T05:57:54.890961Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:54.908835Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:54.909027Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:54.909072Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T05:57:56.625777Z","caller":"traceutil/trace.go:171","msg":"trace[367357356] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"124.572327ms","start":"2026-04-09T05:57:56.501173Z","end":"2026-04-09T05:57:56.625745Z","steps":["trace[367357356] 'process raft request' (duration: 76.807778ms)","trace[367357356] 'compare' (duration: 47.440321ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:57:56.625864Z","caller":"traceutil/trace.go:171","msg":"trace[1284682738] linearizableReadLoop","detail":"{readStateIndex:68; appliedIndex:66; }","duration":"101.690376ms","start":"2026-04-09T05:57:56.524148Z","end":"2026-04-09T05:57:56.625838Z","steps":["trace[1284682738] 'read index received' (duration: 5.248066ms)","trace[1284682738] 'applied index is now lower than readState.Index' (duration: 96.44096ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:57:56.626013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.838899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-09T05:57:56.626156Z","caller":"traceutil/trace.go:171","msg":"trace[65913324] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:65; }","duration":"102.028604ms","start":"2026-04-09T05:57:56.524116Z","end":"2026-04-09T05:57:56.626144Z","steps":["trace[65913324] 'agreement among raft nodes before linearized reading' (duration: 101.84685ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:57:58.770017Z","caller":"traceutil/trace.go:171","msg":"trace[1886109040] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"114.486103ms","start":"2026-04-09T05:57:58.655495Z","end":"2026-04-09T05:57:58.769981Z","steps":["trace[1886109040] 'process raft request' (duration: 24.605022ms)","trace[1886109040] 'compare' (duration: 89.743188ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:09.650357Z","caller":"traceutil/trace.go:171","msg":"trace[418253598] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"113.971822ms","start":"2026-04-09T05:58:09.53636Z","end":"2026-04-09T05:58:09.650332Z","steps":["trace[418253598] 'process raft request' (duration: 113.761027ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:11.529903Z","caller":"traceutil/trace.go:171","msg":"trace[353149543] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"209.134824ms","start":"2026-04-09T05:58:11.32074Z","end":"2026-04-09T05:58:11.529874Z","steps":["trace[353149543] 'read index received' (duration: 208.915029ms)","trace[353149543] 'applied index is now lower than readState.Index' (duration: 218.865µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:11.529943Z","caller":"traceutil/trace.go:171","msg":"trace[1535381120] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"387.877814ms","start":"2026-04-09T05:58:11.14204Z","end":"2026-04-09T05:58:11.529918Z","steps":["trace[1535381120] 'process raft request' (duration: 387.632178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:11.530082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.605424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-09T05:58:11.530094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.325898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:11.530212Z","caller":"traceutil/trace.go:171","msg":"trace[589040136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"209.499343ms","start":"2026-04-09T05:58:11.320687Z","end":"2026-04-09T05:58:11.530186Z","steps":["trace[589040136] 'agreement among raft nodes before linearized reading' (duration: 209.298268ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:11.530139Z","caller":"traceutil/trace.go:171","msg":"trace[72184892] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:374; }","duration":"127.704787ms","start":"2026-04-09T05:58:11.402418Z","end":"2026-04-09T05:58:11.530123Z","steps":["trace[72184892] 'agreement among raft nodes before linearized reading' (duration: 127.593324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:11.530589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:11.142006Z","time spent":"387.990307ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:58:12.451315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.162533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:58:12.451571Z","caller":"traceutil/trace.go:171","msg":"trace[1863725229] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"916.75737ms","start":"2026-04-09T05:58:11.534733Z","end":"2026-04-09T05:58:12.451491Z","steps":["trace[1863725229] 'process raft request' (duration: 671.104585ms)","trace[1863725229] 'compare' (duration: 244.651741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:12.451804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:11.534707Z","time spent":"916.969975ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:58:26.73757Z","caller":"traceutil/trace.go:171","msg":"trace[633980873] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"114.720849ms","start":"2026-04-09T05:58:26.622827Z","end":"2026-04-09T05:58:26.737548Z","steps":["trace[633980873] 'process raft request' (duration: 48.313539ms)","trace[633980873] 'compare' (duration: 66.309888ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:28.906206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.967041ms","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-04-09T05:58:28.906325Z","caller":"traceutil/trace.go:171","msg":"trace[1386018154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"164.132686ms","start":"2026-04-09T05:58:28.742165Z","end":"2026-04-09T05:58:28.906298Z","steps":["trace[1386018154] 'range keys from in-memory index tree' (duration: 163.840998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:28.906935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.216144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:28.907047Z","caller":"traceutil/trace.go:171","msg":"trace[2134113552] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:476; }","duration":"177.415069ms","start":"2026-04-09T05:58:28.729609Z","end":"2026-04-09T05:58:28.907024Z","steps":["trace[2134113552] 'range keys from in-memory index tree' (duration: 177.106011ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:29.097606Z","caller":"traceutil/trace.go:171","msg":"trace[2049792173] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"185.822274ms","start":"2026-04-09T05:58:28.911749Z","end":"2026-04-09T05:58:29.097571Z","steps":["trace[2049792173] 'read index received' (duration: 185.634779ms)","trace[2049792173] 'applied index is now lower than readState.Index' (duration: 185.985µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:29.097723Z","caller":"traceutil/trace.go:171","msg":"trace[1666224486] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"186.395588ms","start":"2026-04-09T05:58:28.911291Z","end":"2026-04-09T05:58:29.097686Z","steps":["trace[1666224486] 'process raft request' (duration: 186.08322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:29.097862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.080261ms","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-04-09T05:58:29.097915Z","caller":"traceutil/trace.go:171","msg":"trace[625522139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"186.198814ms","start":"2026-04-09T05:58:28.911703Z","end":"2026-04-09T05:58:29.097902Z","steps":["trace[625522139] 'agreement among raft nodes before linearized reading' (duration: 186.06126ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:29.859978Z","caller":"traceutil/trace.go:171","msg":"trace[386665037] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"182.929443ms","start":"2026-04-09T05:58:29.677018Z","end":"2026-04-09T05:58:29.859948Z","steps":["trace[386665037] 'process raft request' (duration: 174.846546ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:29.864024Z","caller":"traceutil/trace.go:171","msg":"trace[1159612331] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"182.713989ms","start":"2026-04-09T05:58:29.68128Z","end":"2026-04-09T05:58:29.863993Z","steps":["trace[1159612331] 'process raft request' (duration: 182.315148ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:29.864257Z","caller":"traceutil/trace.go:171","msg":"trace[1482337795] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"163.724286ms","start":"2026-04-09T05:58:29.700472Z","end":"2026-04-09T05:58:29.864196Z","steps":["trace[1482337795] 'process raft request' (duration: 163.262655ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:29.86442Z","caller":"traceutil/trace.go:171","msg":"trace[340045288] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:545; }","duration":"147.715345ms","start":"2026-04-09T05:58:29.71669Z","end":"2026-04-09T05:58:29.864405Z","steps":["trace[340045288] 'read index received' (duration: 135.096906ms)","trace[340045288] 'applied index is now lower than readState.Index' (duration: 12.617959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:29.864593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.887319ms","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-04-09T05:58:29.864623Z","caller":"traceutil/trace.go:171","msg":"trace[858615006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"147.969031ms","start":"2026-04-09T05:58:29.716646Z","end":"2026-04-09T05:58:29.864615Z","steps":["trace[858615006] 'agreement among raft nodes before linearized reading' (duration: 147.812427ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:29.864757Z","caller":"traceutil/trace.go:171","msg":"trace[985750147] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"114.370641ms","start":"2026-04-09T05:58:29.750378Z","end":"2026-04-09T05:58:29.864749Z","steps":["trace[985750147] 'process raft request' (duration: 113.89654ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:35.224693Z","caller":"traceutil/trace.go:171","msg":"trace[886527732] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"379.397907ms","start":"2026-04-09T05:58:34.845256Z","end":"2026-04-09T05:58:35.224654Z","steps":["trace[886527732] 'read index received' (duration: 379.06824ms)","trace[886527732] 'applied index is now lower than readState.Index' (duration: 328.097µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:35.224775Z","caller":"traceutil/trace.go:171","msg":"trace[41345893] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"393.223294ms","start":"2026-04-09T05:58:34.831509Z","end":"2026-04-09T05:58:35.224732Z","steps":["trace[41345893] 'process raft request' (duration: 392.905707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:35.224925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:34.831472Z","time spent":"393.379308ms","remote":"127.0.0.1:56956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T05:58:35.225068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.712945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:35.225088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.429596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:35.225136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.208424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:35.225142Z","caller":"traceutil/trace.go:171","msg":"trace[670542711] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:576; }","duration":"379.821628ms","start":"2026-04-09T05:58:34.845302Z","end":"2026-04-09T05:58:35.225124Z","steps":["trace[670542711] 'agreement among raft nodes before linearized reading' (duration: 379.585762ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:35.225146Z","caller":"traceutil/trace.go:171","msg":"trace[1808853349] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:576; }","duration":"340.515398ms","start":"2026-04-09T05:58:34.884617Z","end":"2026-04-09T05:58:35.225132Z","steps":["trace[1808853349] 'agreement among raft nodes before linearized reading' (duration: 340.411446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:35.225213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:34.884609Z","time spent":"340.593041ms","remote":"127.0.0.1:46988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-09T05:58:35.225219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.180493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:35.225252Z","caller":"traceutil/trace.go:171","msg":"trace[1230154537] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:576; }","duration":"268.266655ms","start":"2026-04-09T05:58:34.956978Z","end":"2026-04-09T05:58:35.225245Z","steps":["trace[1230154537] 'agreement among raft nodes before linearized reading' (duration: 268.216083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:35.225273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.440511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:35.225324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.489842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:35.225383Z","caller":"traceutil/trace.go:171","msg":"trace[1921367023] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:576; }","duration":"341.583104ms","start":"2026-04-09T05:58:34.883782Z","end":"2026-04-09T05:58:35.225365Z","steps":["trace[1921367023] 'agreement among raft nodes before linearized reading' (duration: 341.462371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:35.225323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.339353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:35.225392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.247764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:35.225175Z","caller":"traceutil/trace.go:171","msg":"trace[1700100300] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:576; }","duration":"268.271186ms","start":"2026-04-09T05:58:34.956892Z","end":"2026-04-09T05:58:35.225163Z","steps":["trace[1700100300] 'agreement among raft nodes before linearized reading' (duration: 268.187274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:35.225455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.873343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:35.225327Z","caller":"traceutil/trace.go:171","msg":"trace[1803957574] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:576; }","duration":"341.509702ms","start":"2026-04-09T05:58:34.883803Z","end":"2026-04-09T05:58:35.225312Z","steps":["trace[1803957574] 'agreement among raft nodes before linearized reading' (duration: 341.435331ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:35.225482Z","caller":"traceutil/trace.go:171","msg":"trace[389701020] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:576; }","duration":"316.909784ms","start":"2026-04-09T05:58:34.908563Z","end":"2026-04-09T05:58:35.225473Z","steps":["trace[389701020] 'agreement among raft nodes before linearized reading' (duration: 316.874012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:35.225499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:34.908555Z","time spent":"316.938315ms","remote":"127.0.0.1:46992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:58:35.225174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.525831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:35.22552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.141965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:35.225533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:35.225575Z","caller":"traceutil/trace.go:171","msg":"trace[1826357695] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:576; }","duration":"316.849632ms","start":"2026-04-09T05:58:34.908717Z","end":"2026-04-09T05:58:35.225566Z","steps":["trace[1826357695] 'agreement among raft nodes before linearized reading' (duration: 316.788711ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:35.225574Z","caller":"traceutil/trace.go:171","msg":"trace[1872382905] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:576; }","duration":"316.207406ms","start":"2026-04-09T05:58:34.909355Z","end":"2026-04-09T05:58:35.225563Z","steps":["trace[1872382905] 'agreement among raft nodes before linearized reading' (duration: 316.127554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:35.225597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:34.908687Z","time spent":"316.903784ms","remote":"127.0.0.1:46992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-09T05:58:35.225449Z","caller":"traceutil/trace.go:171","msg":"trace[1354282340] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:576; }","duration":"379.350586ms","start":"2026-04-09T05:58:34.846086Z","end":"2026-04-09T05:58:35.225437Z","steps":["trace[1354282340] 'agreement among raft nodes before linearized reading' (duration: 379.267974ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:35.22561Z","caller":"traceutil/trace.go:171","msg":"trace[1476853970] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:576; }","duration":"288.959662ms","start":"2026-04-09T05:58:34.936575Z","end":"2026-04-09T05:58:35.225534Z","steps":["trace[1476853970] 'agreement among raft nodes before linearized reading' (duration: 288.333986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:35.225628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:34.909349Z","time spent":"316.256217ms","remote":"127.0.0.1:46992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-09T05:58:35.225665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:34.846077Z","time spent":"379.575013ms","remote":"127.0.0.1:46982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-09T05:58:35.225716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:34.88379Z","time spent":"341.728688ms","remote":"127.0.0.1:46988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-09T05:58:35.225412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:34.883765Z","time spent":"341.639636ms","remote":"127.0.0.1:46988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:58:35.225207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:34.845221Z","time spent":"379.964901ms","remote":"127.0.0.1:46982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:58:35.225809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.850961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T05:58:35.225952Z","caller":"traceutil/trace.go:171","msg":"trace[1641339198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"101.162668ms","start":"2026-04-09T05:58:35.124758Z","end":"2026-04-09T05:58:35.22592Z","steps":["trace[1641339198] 'agreement among raft nodes before linearized reading' (duration: 100.607764ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:35.225958Z","caller":"traceutil/trace.go:171","msg":"trace[388279290] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:576; }","duration":"267.562848ms","start":"2026-04-09T05:58:34.957873Z","end":"2026-04-09T05:58:35.225436Z","steps":["trace[388279290] 'agreement among raft nodes before linearized reading' (duration: 267.22167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:35.226108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.848323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:58:35.226174Z","caller":"traceutil/trace.go:171","msg":"trace[1222707087] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:576; }","duration":"380.951376ms","start":"2026-04-09T05:58:34.845205Z","end":"2026-04-09T05:58:35.226157Z","steps":["trace[1222707087] 'agreement among raft nodes before linearized reading' (duration: 379.653783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:35.225992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.087505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:35.226223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:34.845175Z","time spent":"381.036408ms","remote":"127.0.0.1:46982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"info","ts":"2026-04-09T05:58:35.229308Z","caller":"traceutil/trace.go:171","msg":"trace[1945550896] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:576; }","duration":"289.575317ms","start":"2026-04-09T05:58:34.936659Z","end":"2026-04-09T05:58:35.226235Z","steps":["trace[1945550896] 'agreement among raft nodes before linearized reading' (duration: 288.640053ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:36.067213Z","caller":"traceutil/trace.go:171","msg":"trace[1493846138] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"543.987144ms","start":"2026-04-09T05:58:35.523181Z","end":"2026-04-09T05:58:36.067168Z","steps":["trace[1493846138] 'process raft request' (duration: 510.189559ms)","trace[1493846138] 'compare' (duration: 33.489158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:36.067451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:35.523153Z","time spent":"544.161678ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:58:36.602909Z","caller":"traceutil/trace.go:171","msg":"trace[1500004664] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"707.504623ms","start":"2026-04-09T05:58:35.895379Z","end":"2026-04-09T05:58:36.602884Z","steps":["trace[1500004664] 'read index received' (duration: 137.939915ms)","trace[1500004664] 'applied index is now lower than readState.Index' (duration: 569.563588ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:36.602974Z","caller":"traceutil/trace.go:171","msg":"trace[2000633659] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"923.238738ms","start":"2026-04-09T05:58:35.679695Z","end":"2026-04-09T05:58:36.602934Z","steps":["trace[2000633659] 'process raft request' (duration: 922.50087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:36.603228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:35.679669Z","time spent":"923.390302ms","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:58:36.603257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.827292ms","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-04-09T05:58:36.603403Z","caller":"traceutil/trace.go:171","msg":"trace[1945919302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"708.046347ms","start":"2026-04-09T05:58:35.895321Z","end":"2026-04-09T05:58:36.603367Z","steps":["trace[1945919302] 'agreement among raft nodes before linearized reading' (duration: 707.677998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:36.603473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:35.895297Z","time spent":"708.165349ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T05:58:36.835934Z","caller":"traceutil/trace.go:171","msg":"trace[1259108402] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"163.810676ms","start":"2026-04-09T05:58:36.672089Z","end":"2026-04-09T05:58:36.835899Z","steps":["trace[1259108402] 'process raft request' (duration: 104.620442ms)","trace[1259108402] 'compare' (duration: 59.01994ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:36.83803Z","caller":"traceutil/trace.go:171","msg":"trace[334509253] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"161.883881ms","start":"2026-04-09T05:58:36.67598Z","end":"2026-04-09T05:58:36.837864Z","steps":["trace[334509253] 'process raft request' (duration: 161.763378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:37.743478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645624258255404,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T05:58:38.190397Z","caller":"traceutil/trace.go:171","msg":"trace[734093997] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"955.412383ms","start":"2026-04-09T05:58:37.234948Z","end":"2026-04-09T05:58:38.19036Z","steps":["trace[734093997] 'process raft request' (duration: 954.684246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:38.190625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:37.2349Z","time spent":"955.611798ms","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:58:38.193706Z","caller":"traceutil/trace.go:171","msg":"trace[1021035787] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"950.862381ms","start":"2026-04-09T05:58:37.242804Z","end":"2026-04-09T05:58:38.193666Z","steps":["trace[1021035787] 'read index received' (duration: 946.532987ms)","trace[1021035787] 'applied index is now lower than readState.Index' (duration: 4.326944ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:38.193833Z","caller":"traceutil/trace.go:171","msg":"trace[1283518340] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"772.619273ms","start":"2026-04-09T05:58:37.421165Z","end":"2026-04-09T05:58:38.193784Z","steps":["trace[1283518340] 'process raft request' (duration: 772.295695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:38.194056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.65291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T05:58:38.194052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.22226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T05:58:38.194121Z","caller":"traceutil/trace.go:171","msg":"trace[1905468538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"878.786113ms","start":"2026-04-09T05:58:37.315318Z","end":"2026-04-09T05:58:38.194105Z","steps":["trace[1905468538] 'agreement among raft nodes before linearized reading' (duration: 878.64398ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:38.19414Z","caller":"traceutil/trace.go:171","msg":"trace[467905466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"951.348343ms","start":"2026-04-09T05:58:37.242771Z","end":"2026-04-09T05:58:38.194119Z","steps":["trace[467905466] 'agreement among raft nodes before linearized reading' (duration: 951.071477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:38.194144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:37.421141Z","time spent":"772.802517ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T05:58:38.19415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.311762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-09T05:58:38.194191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:37.242749Z","time spent":"951.431435ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T05:58:38.194257Z","caller":"traceutil/trace.go:171","msg":"trace[308450997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"951.458706ms","start":"2026-04-09T05:58:37.242771Z","end":"2026-04-09T05:58:38.194229Z","steps":["trace[308450997] 'agreement among raft nodes before linearized reading' (duration: 951.126088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:38.194187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:37.315305Z","time spent":"878.870765ms","remote":"127.0.0.1:56644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T05:58:38.194317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:37.242749Z","time spent":"951.558088ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T05:58:38.63191Z","caller":"traceutil/trace.go:171","msg":"trace[404847061] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"112.005742ms","start":"2026-04-09T05:58:38.519877Z","end":"2026-04-09T05:58:38.631883Z","steps":["trace[404847061] 'process raft request' (duration: 111.858519ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:38.717368Z","caller":"traceutil/trace.go:171","msg":"trace[2090838338] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"182.399711ms","start":"2026-04-09T05:58:38.534931Z","end":"2026-04-09T05:58:38.71733Z","steps":["trace[2090838338] 'process raft request' (duration: 182.100814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:38.992949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.123932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T05:58:38.993083Z","caller":"traceutil/trace.go:171","msg":"trace[1038831635] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"151.723263ms","start":"2026-04-09T05:58:38.841345Z","end":"2026-04-09T05:58:38.993068Z","steps":["trace[1038831635] 'read index received' (duration: 24.367006ms)","trace[1038831635] 'applied index is now lower than readState.Index' (duration: 127.355287ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:38.993246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.905337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T05:58:38.993272Z","caller":"traceutil/trace.go:171","msg":"trace[906521036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"151.993888ms","start":"2026-04-09T05:58:38.84127Z","end":"2026-04-09T05:58:38.993264Z","steps":["trace[906521036] 'agreement among raft nodes before linearized reading' (duration: 151.834695ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:38.993295Z","caller":"traceutil/trace.go:171","msg":"trace[1497662247] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"169.102796ms","start":"2026-04-09T05:58:38.824016Z","end":"2026-04-09T05:58:38.993119Z","steps":["trace[1497662247] 'process raft request' (duration: 41.727348ms)","trace[1497662247] 'compare' (duration: 126.855185ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:39.183036Z","caller":"traceutil/trace.go:171","msg":"trace[649234105] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"181.980131ms","start":"2026-04-09T05:58:39.001023Z","end":"2026-04-09T05:58:39.183003Z","steps":["trace[649234105] 'read index received' (duration: 95.672524ms)","trace[649234105] 'applied index is now lower than readState.Index' (duration: 86.306467ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T05:58:39.183216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.158505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-09T05:58:39.183273Z","caller":"traceutil/trace.go:171","msg":"trace[2144580990] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:598; }","duration":"182.253298ms","start":"2026-04-09T05:58:39.000998Z","end":"2026-04-09T05:58:39.183251Z","steps":["trace[2144580990] 'agreement among raft nodes before linearized reading' (duration: 182.114844ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:39.183333Z","caller":"traceutil/trace.go:171","msg":"trace[136052780] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"186.391848ms","start":"2026-04-09T05:58:38.996907Z","end":"2026-04-09T05:58:39.183299Z","steps":["trace[136052780] 'process raft request' (duration: 99.863996ms)","trace[136052780] 'compare' (duration: 86.072981ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:41.016431Z","caller":"traceutil/trace.go:171","msg":"trace[273376213] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"209.646146ms","start":"2026-04-09T05:58:40.806763Z","end":"2026-04-09T05:58:41.016409Z","steps":["trace[273376213] 'process raft request' (duration: 209.538163ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:41.016479Z","caller":"traceutil/trace.go:171","msg":"trace[435692846] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"215.053488ms","start":"2026-04-09T05:58:40.80139Z","end":"2026-04-09T05:58:41.016443Z","steps":["trace[435692846] 'process raft request' (duration: 184.225666ms)","trace[435692846] 'compare' (duration: 30.369341ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:58:50.38989Z","caller":"traceutil/trace.go:171","msg":"trace[1964271892] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"113.474719ms","start":"2026-04-09T05:58:50.276388Z","end":"2026-04-09T05:58:50.389862Z","steps":["trace[1964271892] 'process raft request' (duration: 113.050819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:53.45522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.556989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-09T05:58:53.455368Z","caller":"traceutil/trace.go:171","msg":"trace[80331832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"183.789464ms","start":"2026-04-09T05:58:53.271552Z","end":"2026-04-09T05:58:53.455341Z","steps":["trace[80331832] 'range keys from in-memory index tree' (duration: 183.220881ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:58:53.886714Z","caller":"traceutil/trace.go:171","msg":"trace[1445167637] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"426.018624ms","start":"2026-04-09T05:58:53.460666Z","end":"2026-04-09T05:58:53.886684Z","steps":["trace[1445167637] 'process raft request' (duration: 425.763998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:53.886894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:53.460451Z","time spent":"426.344873ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:58:54.03273Z","caller":"traceutil/trace.go:171","msg":"trace[1069027087] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"338.182533ms","start":"2026-04-09T05:58:53.694523Z","end":"2026-04-09T05:58:54.032706Z","steps":["trace[1069027087] 'process raft request' (duration: 338.049979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:58:54.033019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:58:53.69449Z","time spent":"338.321735ms","remote":"127.0.0.1:56860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:59:11.117815Z","caller":"traceutil/trace.go:171","msg":"trace[84915138] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"108.731555ms","start":"2026-04-09T05:59:11.009024Z","end":"2026-04-09T05:59:11.117755Z","steps":["trace[84915138] 'process raft request' (duration: 70.45075ms)","trace[84915138] 'compare' (duration: 37.820374ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:11.451885Z","caller":"traceutil/trace.go:171","msg":"trace[1741149042] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1077; }","duration":"131.933671ms","start":"2026-04-09T05:59:11.319924Z","end":"2026-04-09T05:59:11.451858Z","steps":["trace[1741149042] 'read index received' (duration: 131.577653ms)","trace[1741149042] 'applied index is now lower than readState.Index' (duration: 354.968µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:11.452011Z","caller":"traceutil/trace.go:171","msg":"trace[1481073323] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"138.283266ms","start":"2026-04-09T05:59:11.313659Z","end":"2026-04-09T05:59:11.451942Z","steps":["trace[1481073323] 'process raft request' (duration: 138.140673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:11.452118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.964646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-qnsrv\" ","response":"range_response_count:1 size:2702"} {"level":"info","ts":"2026-04-09T05:59:11.452168Z","caller":"traceutil/trace.go:171","msg":"trace[1533380432] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-cainjector-64b59ddb75-qnsrv; range_end:; response_count:1; response_revision:1047; }","duration":"142.083178ms","start":"2026-04-09T05:59:11.310069Z","end":"2026-04-09T05:59:11.452152Z","steps":["trace[1533380432] 'agreement among raft nodes before linearized reading' (duration: 141.891124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:11.45228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.234801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T05:59:11.452372Z","caller":"traceutil/trace.go:171","msg":"trace[434707620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"137.330973ms","start":"2026-04-09T05:59:11.315015Z","end":"2026-04-09T05:59:11.452346Z","steps":["trace[434707620] 'agreement among raft nodes before linearized reading' (duration: 137.18897ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:11.452043Z","caller":"traceutil/trace.go:171","msg":"trace[1187982353] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"141.174677ms","start":"2026-04-09T05:59:11.310759Z","end":"2026-04-09T05:59:11.451934Z","steps":["trace[1187982353] 'process raft request' (duration: 140.688085ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:11.488397Z","caller":"traceutil/trace.go:171","msg":"trace[1959367060] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"161.964215ms","start":"2026-04-09T05:59:11.326388Z","end":"2026-04-09T05:59:11.488352Z","steps":["trace[1959367060] 'process raft request' (duration: 161.750859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:19.025784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.552657ms","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-04-09T05:59:19.025912Z","caller":"traceutil/trace.go:171","msg":"trace[2128199570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1126; }","duration":"434.747072ms","start":"2026-04-09T05:59:18.591133Z","end":"2026-04-09T05:59:19.02588Z","steps":["trace[2128199570] 'range keys from in-memory index tree' (duration: 434.282201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:19.025994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.139272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T05:59:19.025989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.89245ms","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-04-09T05:59:19.026129Z","caller":"traceutil/trace.go:171","msg":"trace[453452698] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1126; }","duration":"385.345296ms","start":"2026-04-09T05:59:18.640758Z","end":"2026-04-09T05:59:19.026103Z","steps":["trace[453452698] 'count revisions from in-memory index tree' (duration: 385.036189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:19.026196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:18.640747Z","time spent":"385.432118ms","remote":"127.0.0.1:56834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":50,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-09T05:59:19.026198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:18.591101Z","time spent":"435.07626ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T05:59:19.026281Z","caller":"traceutil/trace.go:171","msg":"trace[638483776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1126; }","duration":"418.157897ms","start":"2026-04-09T05:59:18.608003Z","end":"2026-04-09T05:59:19.02616Z","steps":["trace[638483776] 'range keys from in-memory index tree' (duration: 417.570523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:19.026414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:18.607977Z","time spent":"418.418093ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T05:59:19.375802Z","caller":"traceutil/trace.go:171","msg":"trace[2138131472] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"299.836001ms","start":"2026-04-09T05:59:19.07592Z","end":"2026-04-09T05:59:19.375756Z","steps":["trace[2138131472] 'process raft request' (duration: 297.300699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:19.376278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T05:59:19.075869Z","time spent":"300.259921ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T05:59:44.405384Z","caller":"traceutil/trace.go:171","msg":"trace[955905593] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"146.874356ms","start":"2026-04-09T05:59:44.258482Z","end":"2026-04-09T05:59:44.405357Z","steps":["trace[955905593] 'read index received' (duration: 146.581189ms)","trace[955905593] 'applied index is now lower than readState.Index' (duration: 291.947µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:44.405562Z","caller":"traceutil/trace.go:171","msg":"trace[1724086180] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"207.842005ms","start":"2026-04-09T05:59:44.197662Z","end":"2026-04-09T05:59:44.405504Z","steps":["trace[1724086180] 'process raft request' (duration: 207.506826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T05:59:44.406034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.517852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-09T05:59:44.406141Z","caller":"traceutil/trace.go:171","msg":"trace[559597189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1308; }","duration":"147.706996ms","start":"2026-04-09T05:59:44.25841Z","end":"2026-04-09T05:59:44.406117Z","steps":["trace[559597189] 'agreement among raft nodes before linearized reading' (duration: 147.263885ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T05:59:46.593686Z","caller":"traceutil/trace.go:171","msg":"trace[802196587] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"143.047083ms","start":"2026-04-09T05:59:46.450606Z","end":"2026-04-09T05:59:46.593653Z","steps":["trace[802196587] 'process raft request' (duration: 61.149352ms)","trace[802196587] 'compare' (duration: 81.662345ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T05:59:52.810511Z","caller":"traceutil/trace.go:171","msg":"trace[1971867512] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"183.581122ms","start":"2026-04-09T05:59:52.626894Z","end":"2026-04-09T05:59:52.810475Z","steps":["trace[1971867512] 'process raft request' (duration: 183.362597ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:20.395507Z","caller":"traceutil/trace.go:171","msg":"trace[1285447799] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"196.932899ms","start":"2026-04-09T06:00:20.198506Z","end":"2026-04-09T06:00:20.395439Z","steps":["trace[1285447799] 'process raft request' (duration: 196.677523ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:23.574067Z","caller":"traceutil/trace.go:171","msg":"trace[270186936] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"200.298811ms","start":"2026-04-09T06:00:23.37373Z","end":"2026-04-09T06:00:23.574029Z","steps":["trace[270186936] 'process raft request' (duration: 200.059085ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:23.576639Z","caller":"traceutil/trace.go:171","msg":"trace[2067210971] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"194.17297ms","start":"2026-04-09T06:00:23.382424Z","end":"2026-04-09T06:00:23.576597Z","steps":["trace[2067210971] 'process raft request' (duration: 193.930105ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:24.431295Z","caller":"traceutil/trace.go:171","msg":"trace[1990664556] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"468.229702ms","start":"2026-04-09T06:00:23.963037Z","end":"2026-04-09T06:00:24.431267Z","steps":["trace[1990664556] 'process raft request' (duration: 468.067768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:24.431712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:00:23.963017Z","time spent":"468.378826ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:00:24.431739Z","caller":"traceutil/trace.go:171","msg":"trace[1791212337] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1961; }","duration":"273.413316ms","start":"2026-04-09T06:00:24.158306Z","end":"2026-04-09T06:00:24.43172Z","steps":["trace[1791212337] 'read index received' (duration: 273.403676ms)","trace[1791212337] 'applied index is now lower than readState.Index' (duration: 7.68µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:00:24.431889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.56807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T06:00:24.431937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.6575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:00:24.431966Z","caller":"traceutil/trace.go:171","msg":"trace[1267973004] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1888; }","duration":"273.710453ms","start":"2026-04-09T06:00:24.158237Z","end":"2026-04-09T06:00:24.431948Z","steps":["trace[1267973004] 'agreement among raft nodes before linearized reading' (duration: 273.58624ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:24.431979Z","caller":"traceutil/trace.go:171","msg":"trace[1058289328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1888; }","duration":"114.704521ms","start":"2026-04-09T06:00:24.317263Z","end":"2026-04-09T06:00:24.431968Z","steps":["trace[1058289328] 'agreement among raft nodes before linearized reading' (duration: 114.595478ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:24.571406Z","caller":"traceutil/trace.go:171","msg":"trace[475198461] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"131.495461ms","start":"2026-04-09T06:00:24.439872Z","end":"2026-04-09T06:00:24.571368Z","steps":["trace[475198461] 'process raft request' (duration: 97.514831ms)","trace[475198461] 'compare' (duration: 33.816996ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:00:25.164814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.988761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a76219ab-0381-4e41-8778-e0cbcacaedb5\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-04-09T06:00:25.16485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.537159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:00:25.165004Z","caller":"traceutil/trace.go:171","msg":"trace[1226168237] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1889; }","duration":"479.717403ms","start":"2026-04-09T06:00:24.685227Z","end":"2026-04-09T06:00:25.164945Z","steps":["trace[1226168237] 'count revisions from in-memory index tree' (duration: 479.410066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:25.165066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:00:24.685196Z","time spent":"479.852656ms","remote":"127.0.0.1:56780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-09T06:00:25.165156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.992951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"info","ts":"2026-04-09T06:00:25.164954Z","caller":"traceutil/trace.go:171","msg":"trace[1550065517] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a76219ab-0381-4e41-8778-e0cbcacaedb5; range_end:; response_count:1; response_revision:1889; }","duration":"367.196825ms","start":"2026-04-09T06:00:24.797723Z","end":"2026-04-09T06:00:25.16492Z","steps":["trace[1550065517] 'range keys from in-memory index tree' (duration: 366.841867ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:25.165284Z","caller":"traceutil/trace.go:171","msg":"trace[2110218811] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1889; }","duration":"186.184595ms","start":"2026-04-09T06:00:24.979068Z","end":"2026-04-09T06:00:25.165253Z","steps":["trace[2110218811] 'range keys from in-memory index tree' (duration: 185.712085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:25.165184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.545628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-09T06:00:25.165333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:00:24.79769Z","time spent":"367.629846ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a76219ab-0381-4e41-8778-e0cbcacaedb5\" "} {"level":"warn","ts":"2026-04-09T06:00:25.164755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.100212ms","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-04-09T06:00:25.165376Z","caller":"traceutil/trace.go:171","msg":"trace[1653605200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1889; }","duration":"137.756133ms","start":"2026-04-09T06:00:25.027587Z","end":"2026-04-09T06:00:25.165343Z","steps":["trace[1653605200] 'range keys from in-memory index tree' (duration: 137.377235ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:25.165462Z","caller":"traceutil/trace.go:171","msg":"trace[1611058843] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1889; }","duration":"265.86389ms","start":"2026-04-09T06:00:24.899557Z","end":"2026-04-09T06:00:25.165421Z","steps":["trace[1611058843] 'range keys from in-memory index tree' (duration: 264.844396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:37.380311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.915013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:00:37.380542Z","caller":"traceutil/trace.go:171","msg":"trace[1572763093] linearizableReadLoop","detail":"{readStateIndex:2075; appliedIndex:2074; }","duration":"123.769932ms","start":"2026-04-09T06:00:37.256741Z","end":"2026-04-09T06:00:37.380511Z","steps":["trace[1572763093] 'read index received' (duration: 11.414398ms)","trace[1572763093] 'applied index is now lower than readState.Index' (duration: 112.352924ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:37.380687Z","caller":"traceutil/trace.go:171","msg":"trace[2073783376] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"133.535301ms","start":"2026-04-09T06:00:37.247069Z","end":"2026-04-09T06:00:37.380604Z","steps":["trace[2073783376] 'process raft request' (duration: 21.208718ms)","trace[2073783376] 'compare' (duration: 111.726339ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:00:37.381162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.384287ms","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-04-09T06:00:37.381287Z","caller":"traceutil/trace.go:171","msg":"trace[451038651] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1999; }","duration":"124.590902ms","start":"2026-04-09T06:00:37.256661Z","end":"2026-04-09T06:00:37.381252Z","steps":["trace[451038651] 'agreement among raft nodes before linearized reading' (duration: 123.934996ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:37.743628Z","caller":"traceutil/trace.go:171","msg":"trace[826852632] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"112.934787ms","start":"2026-04-09T06:00:37.630656Z","end":"2026-04-09T06:00:37.74359Z","steps":["trace[826852632] 'process raft request' (duration: 86.780079ms)","trace[826852632] 'compare' (duration: 25.8264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:00:38.037914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.341137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:00:38.03825Z","caller":"traceutil/trace.go:171","msg":"trace[696667787] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"289.180192ms","start":"2026-04-09T06:00:37.749032Z","end":"2026-04-09T06:00:38.038212Z","steps":["trace[696667787] 'process raft request' (duration: 107.419263ms)","trace[696667787] 'compare' (duration: 180.860646ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:38.038292Z","caller":"traceutil/trace.go:171","msg":"trace[1030678028] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"288.197477ms","start":"2026-04-09T06:00:37.750071Z","end":"2026-04-09T06:00:38.038268Z","steps":["trace[1030678028] 'process raft request' (duration: 288.006422ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:38.038628Z","caller":"traceutil/trace.go:171","msg":"trace[1312358656] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"288.428572ms","start":"2026-04-09T06:00:37.750146Z","end":"2026-04-09T06:00:38.038575Z","steps":["trace[1312358656] 'process raft request' (duration: 288.056993ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:38.039083Z","caller":"traceutil/trace.go:171","msg":"trace[1437687740] linearizableReadLoop","detail":"{readStateIndex:2081; appliedIndex:2077; }","duration":"116.842903ms","start":"2026-04-09T06:00:37.922218Z","end":"2026-04-09T06:00:38.039061Z","steps":["trace[1437687740] 'read index received' (duration: 3.265999ms)","trace[1437687740] 'applied index is now lower than readState.Index' (duration: 113.575714ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:00:38.039339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.047998ms","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:5"} {"level":"info","ts":"2026-04-09T06:00:38.039382Z","caller":"traceutil/trace.go:171","msg":"trace[2105305691] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"288.895804ms","start":"2026-04-09T06:00:37.750451Z","end":"2026-04-09T06:00:38.039347Z","steps":["trace[2105305691] 'process raft request' (duration: 288.017303ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:38.039417Z","caller":"traceutil/trace.go:171","msg":"trace[1080265500] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2005; }","duration":"117.265233ms","start":"2026-04-09T06:00:37.922129Z","end":"2026-04-09T06:00:38.039394Z","steps":["trace[1080265500] 'agreement among raft nodes before linearized reading' (duration: 117.032047ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:43.760715Z","caller":"traceutil/trace.go:171","msg":"trace[1382265425] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2110; }","duration":"441.807496ms","start":"2026-04-09T06:00:43.31887Z","end":"2026-04-09T06:00:43.760678Z","steps":["trace[1382265425] 'read index received' (duration: 441.620262ms)","trace[1382265425] 'applied index is now lower than readState.Index' (duration: 185.684µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:00:43.760751Z","caller":"traceutil/trace.go:171","msg":"trace[929904448] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"496.575884ms","start":"2026-04-09T06:00:43.264129Z","end":"2026-04-09T06:00:43.760705Z","steps":["trace[929904448] 'process raft request' (duration: 496.282817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:43.760934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:00:43.264094Z","time spent":"496.744688ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:00:43.760931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.034682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:00:43.761055Z","caller":"traceutil/trace.go:171","msg":"trace[710106602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2034; }","duration":"442.230667ms","start":"2026-04-09T06:00:43.318806Z","end":"2026-04-09T06:00:43.761036Z","steps":["trace[710106602] 'agreement among raft nodes before linearized reading' (duration: 442.014852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:43.761097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:00:43.318777Z","time spent":"442.31288ms","remote":"127.0.0.1:56644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T06:00:43.909372Z","caller":"traceutil/trace.go:171","msg":"trace[1344808888] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"461.656151ms","start":"2026-04-09T06:00:43.447661Z","end":"2026-04-09T06:00:43.909317Z","steps":["trace[1344808888] 'process raft request' (duration: 461.238431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:43.909597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:00:43.447642Z","time spent":"461.843236ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:00:43.943705Z","caller":"traceutil/trace.go:171","msg":"trace[2022532298] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"220.022123ms","start":"2026-04-09T06:00:43.723651Z","end":"2026-04-09T06:00:43.943673Z","steps":["trace[2022532298] 'process raft request' (duration: 219.759576ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:43.943821Z","caller":"traceutil/trace.go:171","msg":"trace[598378548] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"179.364979ms","start":"2026-04-09T06:00:43.764436Z","end":"2026-04-09T06:00:43.943801Z","steps":["trace[598378548] 'process raft request' (duration: 179.189855ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:43.943702Z","caller":"traceutil/trace.go:171","msg":"trace[727438646] linearizableReadLoop","detail":"{readStateIndex:2114; appliedIndex:2111; }","duration":"182.856215ms","start":"2026-04-09T06:00:43.760814Z","end":"2026-04-09T06:00:43.94367Z","steps":["trace[727438646] 'read index received' (duration: 148.188628ms)","trace[727438646] 'applied index is now lower than readState.Index' (duration: 34.665637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:00:43.944051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.484024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-09T06:00:43.944069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.897131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:00:43.944109Z","caller":"traceutil/trace.go:171","msg":"trace[1964775815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2037; }","duration":"535.639908ms","start":"2026-04-09T06:00:43.408456Z","end":"2026-04-09T06:00:43.944096Z","steps":["trace[1964775815] 'agreement among raft nodes before linearized reading' (duration: 535.31386ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:00:43.944132Z","caller":"traceutil/trace.go:171","msg":"trace[2012568481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2037; }","duration":"182.000633ms","start":"2026-04-09T06:00:43.762113Z","end":"2026-04-09T06:00:43.944114Z","steps":["trace[2012568481] 'agreement among raft nodes before linearized reading' (duration: 181.890121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:43.944155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:00:43.40843Z","time spent":"535.71534ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T06:00:43.94424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.834075ms","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-04-09T06:00:43.94435Z","caller":"traceutil/trace.go:171","msg":"trace[1916310116] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2037; }","duration":"516.067451ms","start":"2026-04-09T06:00:43.428258Z","end":"2026-04-09T06:00:43.944325Z","steps":["trace[1916310116] 'agreement among raft nodes before linearized reading' (duration: 515.480026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:00:43.944416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:00:43.428226Z","time spent":"516.180632ms","remote":"127.0.0.1:49368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-09T06:01:20.453513Z","caller":"traceutil/trace.go:171","msg":"trace[647086265] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"341.832315ms","start":"2026-04-09T06:01:20.111639Z","end":"2026-04-09T06:01:20.453472Z","steps":["trace[647086265] 'process raft request' (duration: 341.534078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:20.45376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:20.111605Z","time spent":"342.027501ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:01:20.475904Z","caller":"traceutil/trace.go:171","msg":"trace[560635018] linearizableReadLoop","detail":"{readStateIndex:2323; appliedIndex:2321; }","duration":"270.236966ms","start":"2026-04-09T06:01:20.205645Z","end":"2026-04-09T06:01:20.475882Z","steps":["trace[560635018] 'read index received' (duration: 247.607256ms)","trace[560635018] 'applied index is now lower than readState.Index' (duration: 22.62829ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:01:20.476012Z","caller":"traceutil/trace.go:171","msg":"trace[2006278632] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"308.5252ms","start":"2026-04-09T06:01:20.167438Z","end":"2026-04-09T06:01:20.475964Z","steps":["trace[2006278632] 'process raft request' (duration: 308.284143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:20.476221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.546224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-09T06:01:20.476266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.121018ms","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-04-09T06:01:20.476324Z","caller":"traceutil/trace.go:171","msg":"trace[862549110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2237; }","duration":"270.698978ms","start":"2026-04-09T06:01:20.205601Z","end":"2026-04-09T06:01:20.4763Z","steps":["trace[862549110] 'agreement among raft nodes before linearized reading' (duration: 270.404261ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:20.476368Z","caller":"traceutil/trace.go:171","msg":"trace[1347608865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2237; }","duration":"107.307832ms","start":"2026-04-09T06:01:20.369041Z","end":"2026-04-09T06:01:20.476349Z","steps":["trace[1347608865] 'agreement among raft nodes before linearized reading' (duration: 107.004775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:20.476296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.289723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T06:01:20.476389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.765808ms","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-04-09T06:01:20.476474Z","caller":"traceutil/trace.go:171","msg":"trace[721370480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2237; }","duration":"105.55258ms","start":"2026-04-09T06:01:20.370896Z","end":"2026-04-09T06:01:20.476449Z","steps":["trace[721370480] 'agreement among raft nodes before linearized reading' (duration: 105.173551ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:20.476488Z","caller":"traceutil/trace.go:171","msg":"trace[1423179407] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2237; }","duration":"189.922862ms","start":"2026-04-09T06:01:20.28654Z","end":"2026-04-09T06:01:20.476463Z","steps":["trace[1423179407] 'agreement among raft nodes before linearized reading' (duration: 189.569654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:20.476539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.25775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-09T06:01:20.476634Z","caller":"traceutil/trace.go:171","msg":"trace[1927707479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2237; }","duration":"206.606928ms","start":"2026-04-09T06:01:20.269989Z","end":"2026-04-09T06:01:20.476596Z","steps":["trace[1927707479] 'agreement among raft nodes before linearized reading' (duration: 206.133417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:20.476637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:20.167404Z","time spent":"308.93311ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:01:20.47701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.094581ms","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-04-09T06:01:20.477241Z","caller":"traceutil/trace.go:171","msg":"trace[1607577681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2237; }","duration":"207.300185ms","start":"2026-04-09T06:01:20.269785Z","end":"2026-04-09T06:01:20.477085Z","steps":["trace[1607577681] 'agreement among raft nodes before linearized reading' (duration: 206.232319ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:24.786703Z","caller":"traceutil/trace.go:171","msg":"trace[724837253] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"267.545941ms","start":"2026-04-09T06:01:24.519113Z","end":"2026-04-09T06:01:24.786659Z","steps":["trace[724837253] 'process raft request' (duration: 267.425928ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:24.786675Z","caller":"traceutil/trace.go:171","msg":"trace[1380360014] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"267.871029ms","start":"2026-04-09T06:01:24.518763Z","end":"2026-04-09T06:01:24.786634Z","steps":["trace[1380360014] 'process raft request' (duration: 266.579528ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:26.079996Z","caller":"traceutil/trace.go:171","msg":"trace[480625833] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"131.588982ms","start":"2026-04-09T06:01:25.948373Z","end":"2026-04-09T06:01:26.079962Z","steps":["trace[480625833] 'process raft request' (duration: 131.373127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:26.590566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.87824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T06:01:26.590776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.627636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:01:26.590891Z","caller":"traceutil/trace.go:171","msg":"trace[590696565] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2266; }","duration":"367.79289ms","start":"2026-04-09T06:01:26.223069Z","end":"2026-04-09T06:01:26.590862Z","steps":["trace[590696565] 'count revisions from in-memory index tree' (duration: 367.493383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:26.590938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.22304Z","time spent":"367.887493ms","remote":"127.0.0.1:57032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-04-09T06:01:26.590712Z","caller":"traceutil/trace.go:171","msg":"trace[364153768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2266; }","duration":"273.067245ms","start":"2026-04-09T06:01:26.317614Z","end":"2026-04-09T06:01:26.590681Z","steps":["trace[364153768] 'range keys from in-memory index tree' (duration: 272.774758ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:26.67339Z","caller":"traceutil/trace.go:171","msg":"trace[1504900473] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"198.850299ms","start":"2026-04-09T06:01:26.47429Z","end":"2026-04-09T06:01:26.67314Z","steps":["trace[1504900473] 'process raft request' (duration: 198.595883ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:26.866023Z","caller":"traceutil/trace.go:171","msg":"trace[558388795] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"366.670784ms","start":"2026-04-09T06:01:26.499312Z","end":"2026-04-09T06:01:26.865983Z","steps":["trace[558388795] 'process raft request' (duration: 326.792222ms)","trace[558388795] 'compare' (duration: 39.523602ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:01:26.866117Z","caller":"traceutil/trace.go:171","msg":"trace[718224139] linearizableReadLoop","detail":"{readStateIndex:2355; appliedIndex:2353; }","duration":"352.234372ms","start":"2026-04-09T06:01:26.51382Z","end":"2026-04-09T06:01:26.866054Z","steps":["trace[718224139] 'read index received' (duration: 159.126103ms)","trace[718224139] 'applied index is now lower than readState.Index' (duration: 193.095819ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:01:26.866305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.499066Z","time spent":"367.120344ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:01:26.866584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.134499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T06:01:26.866564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.717083ms","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-04-09T06:01:26.866633Z","caller":"traceutil/trace.go:171","msg":"trace[1529742648] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2269; }","duration":"144.233061ms","start":"2026-04-09T06:01:26.722388Z","end":"2026-04-09T06:01:26.866621Z","steps":["trace[1529742648] 'agreement among raft nodes before linearized reading' (duration: 144.142309ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:26.866656Z","caller":"traceutil/trace.go:171","msg":"trace[1354258285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2269; }","duration":"352.833216ms","start":"2026-04-09T06:01:26.5138Z","end":"2026-04-09T06:01:26.866634Z","steps":["trace[1354258285] 'agreement among raft nodes before linearized reading' (duration: 352.365135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:26.866708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.513721Z","time spent":"352.9781ms","remote":"127.0.0.1:56878","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":"warn","ts":"2026-04-09T06:01:26.866911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.324706ms","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-04-09T06:01:26.867012Z","caller":"traceutil/trace.go:171","msg":"trace[878331611] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2269; }","duration":"341.637934ms","start":"2026-04-09T06:01:26.525345Z","end":"2026-04-09T06:01:26.866983Z","steps":["trace[878331611] 'agreement among raft nodes before linearized reading' (duration: 341.219844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:26.867008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.15316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-09T06:01:26.867065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.525336Z","time spent":"341.715776ms","remote":"127.0.0.1:49368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-09T06:01:26.867033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.841058ms","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-04-09T06:01:26.867079Z","caller":"traceutil/trace.go:171","msg":"trace[75388779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2269; }","duration":"345.294423ms","start":"2026-04-09T06:01:26.521767Z","end":"2026-04-09T06:01:26.867061Z","steps":["trace[75388779] 'agreement among raft nodes before linearized reading' (duration: 344.75393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:26.867326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.521754Z","time spent":"345.557529ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-09T06:01:26.86732Z","caller":"traceutil/trace.go:171","msg":"trace[1174341338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2269; }","duration":"344.136146ms","start":"2026-04-09T06:01:26.523098Z","end":"2026-04-09T06:01:26.867234Z","steps":["trace[1174341338] 'agreement among raft nodes before linearized reading' (duration: 343.47234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:26.867465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.868449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T06:01:26.867652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.523068Z","time spent":"344.480273ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T06:01:26.86778Z","caller":"traceutil/trace.go:171","msg":"trace[2083933637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2269; }","duration":"275.122865ms","start":"2026-04-09T06:01:26.592525Z","end":"2026-04-09T06:01:26.867648Z","steps":["trace[2083933637] 'agreement among raft nodes before linearized reading' (duration: 274.408618ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:27.241376Z","caller":"traceutil/trace.go:171","msg":"trace[1350832089] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"368.796286ms","start":"2026-04-09T06:01:26.87251Z","end":"2026-04-09T06:01:27.241306Z","steps":["trace[1350832089] 'process raft request' (duration: 367.142415ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:27.241448Z","caller":"traceutil/trace.go:171","msg":"trace[797033629] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"367.563016ms","start":"2026-04-09T06:01:26.873852Z","end":"2026-04-09T06:01:27.241415Z","steps":["trace[797033629] 'process raft request' (duration: 367.517015ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:27.241477Z","caller":"traceutil/trace.go:171","msg":"trace[161154492] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"368.573191ms","start":"2026-04-09T06:01:26.872861Z","end":"2026-04-09T06:01:27.241435Z","steps":["trace[161154492] 'process raft request' (duration: 368.115519ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:27.241456Z","caller":"traceutil/trace.go:171","msg":"trace[773958432] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"368.682843ms","start":"2026-04-09T06:01:26.872726Z","end":"2026-04-09T06:01:27.241409Z","steps":["trace[773958432] 'process raft request' (duration: 368.141369ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:01:27.241575Z","caller":"traceutil/trace.go:171","msg":"trace[779755592] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"368.461618ms","start":"2026-04-09T06:01:26.873072Z","end":"2026-04-09T06:01:27.241533Z","steps":["trace[779755592] 'process raft request' (duration: 368.232723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:01:27.241629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.873845Z","time spent":"367.683019ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:01:27.241604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.872487Z","time spent":"369.000881ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:01:27.241815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.873063Z","time spent":"368.614191ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:01:27.241869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.872855Z","time spent":"368.715014ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:01:27.242182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:01:26.872716Z","time spent":"369.00209ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:02:09.952398Z","caller":"traceutil/trace.go:171","msg":"trace[471527241] linearizableReadLoop","detail":"{readStateIndex:2595; appliedIndex:2594; }","duration":"417.330506ms","start":"2026-04-09T06:02:09.53503Z","end":"2026-04-09T06:02:09.95236Z","steps":["trace[471527241] 'read index received' (duration: 417.113811ms)","trace[471527241] 'applied index is now lower than readState.Index' (duration: 214.985µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:02:09.952609Z","caller":"traceutil/trace.go:171","msg":"trace[490944577] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"419.312734ms","start":"2026-04-09T06:02:09.533224Z","end":"2026-04-09T06:02:09.952536Z","steps":["trace[490944577] 'process raft request' (duration: 418.882944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:09.952815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.723375ms","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-04-09T06:02:09.952878Z","caller":"traceutil/trace.go:171","msg":"trace[1403104320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2499; }","duration":"417.859838ms","start":"2026-04-09T06:02:09.535001Z","end":"2026-04-09T06:02:09.952861Z","steps":["trace[1403104320] 'agreement among raft nodes before linearized reading' (duration: 417.577561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:09.952879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.325455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:02:09.952933Z","caller":"traceutil/trace.go:171","msg":"trace[855249803] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2499; }","duration":"223.455878ms","start":"2026-04-09T06:02:09.729463Z","end":"2026-04-09T06:02:09.952919Z","steps":["trace[855249803] 'agreement among raft nodes before linearized reading' (duration: 223.309714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:09.952934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.534989Z","time spent":"417.931251ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T06:02:09.953041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.415087ms","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-04-09T06:02:09.953151Z","caller":"traceutil/trace.go:171","msg":"trace[412572634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2499; }","duration":"350.551181ms","start":"2026-04-09T06:02:09.602577Z","end":"2026-04-09T06:02:09.953128Z","steps":["trace[412572634] 'agreement among raft nodes before linearized reading' (duration: 350.284424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:09.95312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.678895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T06:02:09.953198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.602571Z","time spent":"350.614932ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T06:02:09.953226Z","caller":"traceutil/trace.go:171","msg":"trace[818705734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2499; }","duration":"339.808598ms","start":"2026-04-09T06:02:09.613394Z","end":"2026-04-09T06:02:09.953203Z","steps":["trace[818705734] 'agreement among raft nodes before linearized reading' (duration: 339.430369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:09.953298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.613303Z","time spent":"339.976733ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T06:02:09.953505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.533191Z","time spent":"419.53025ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:02:09.953536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.076995ms","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-04-09T06:02:09.954121Z","caller":"traceutil/trace.go:171","msg":"trace[616006052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2499; }","duration":"356.714161ms","start":"2026-04-09T06:02:09.59738Z","end":"2026-04-09T06:02:09.954094Z","steps":["trace[616006052] 'agreement among raft nodes before linearized reading' (duration: 355.380688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:09.954346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.597367Z","time spent":"356.839943ms","remote":"127.0.0.1:56878","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-04-09T06:02:10.284872Z","caller":"traceutil/trace.go:171","msg":"trace[1695967000] transaction","detail":"{read_only:false; response_revision:2500; number_of_response:1; }","duration":"326.737892ms","start":"2026-04-09T06:02:09.958099Z","end":"2026-04-09T06:02:10.284837Z","steps":["trace[1695967000] 'process raft request' (duration: 322.619892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:10.285175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.958076Z","time spent":"326.914287ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:02:10.288406Z","caller":"traceutil/trace.go:171","msg":"trace[1238861964] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"329.799737ms","start":"2026-04-09T06:02:09.958585Z","end":"2026-04-09T06:02:10.288385Z","steps":["trace[1238861964] 'process raft request' (duration: 329.604433ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:10.288412Z","caller":"traceutil/trace.go:171","msg":"trace[1793371374] transaction","detail":"{read_only:false; response_revision:2502; number_of_response:1; }","duration":"329.724675ms","start":"2026-04-09T06:02:09.958668Z","end":"2026-04-09T06:02:10.288393Z","steps":["trace[1793371374] 'process raft request' (duration: 329.639203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:10.288532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.958639Z","time spent":"329.837297ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:02:10.288651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.958556Z","time spent":"329.898749ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:02:10.288787Z","caller":"traceutil/trace.go:171","msg":"trace[1909973290] transaction","detail":"{read_only:false; response_revision:2503; number_of_response:1; }","duration":"329.668034ms","start":"2026-04-09T06:02:09.959078Z","end":"2026-04-09T06:02:10.288746Z","steps":["trace[1909973290] 'process raft request' (duration: 329.277004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:10.289088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:02:09.959044Z","time spent":"329.872929ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:02:27.943978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.87844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:02:27.944107Z","caller":"traceutil/trace.go:171","msg":"trace[818628955] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2754; }","duration":"136.056644ms","start":"2026-04-09T06:02:27.808019Z","end":"2026-04-09T06:02:27.944075Z","steps":["trace[818628955] 'count revisions from in-memory index tree' (duration: 135.756517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:27.944124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.914085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:02:27.944218Z","caller":"traceutil/trace.go:171","msg":"trace[21086508] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2754; }","duration":"114.078299ms","start":"2026-04-09T06:02:27.830118Z","end":"2026-04-09T06:02:27.944196Z","steps":["trace[21086508] 'range keys from in-memory index tree' (duration: 113.762301ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:02:31.364036Z","caller":"traceutil/trace.go:171","msg":"trace[808149239] linearizableReadLoop","detail":"{readStateIndex:2890; appliedIndex:2889; }","duration":"121.370516ms","start":"2026-04-09T06:02:31.242628Z","end":"2026-04-09T06:02:31.363998Z","steps":["trace[808149239] 'read index received' (duration: 110.86185ms)","trace[808149239] 'applied index is now lower than readState.Index' (duration: 10.507386ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:02:31.364097Z","caller":"traceutil/trace.go:171","msg":"trace[899276937] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"215.943641ms","start":"2026-04-09T06:02:31.148118Z","end":"2026-04-09T06:02:31.364061Z","steps":["trace[899276937] 'process raft request' (duration: 205.405334ms)","trace[899276937] 'compare' (duration: 10.294461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:02:31.364269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.587072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:02:31.364393Z","caller":"traceutil/trace.go:171","msg":"trace[1903278921] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2781; }","duration":"121.808987ms","start":"2026-04-09T06:02:31.242561Z","end":"2026-04-09T06:02:31.36437Z","steps":["trace[1903278921] 'agreement among raft nodes before linearized reading' (duration: 121.550591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:31.364309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.029276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:02:31.364539Z","caller":"traceutil/trace.go:171","msg":"trace[1788757647] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2781; }","duration":"116.321784ms","start":"2026-04-09T06:02:31.248189Z","end":"2026-04-09T06:02:31.36451Z","steps":["trace[1788757647] 'agreement among raft nodes before linearized reading' (duration: 116.016476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:02:37.438729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.112811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:02:37.438849Z","caller":"traceutil/trace.go:171","msg":"trace[1291568924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2822; }","duration":"121.295246ms","start":"2026-04-09T06:02:37.317529Z","end":"2026-04-09T06:02:37.438824Z","steps":["trace[1291568924] 'range keys from in-memory index tree' (duration: 120.978478ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:03:04.768657Z","caller":"traceutil/trace.go:171","msg":"trace[815831202] transaction","detail":"{read_only:false; response_revision:2983; number_of_response:1; }","duration":"174.674486ms","start":"2026-04-09T06:03:04.593945Z","end":"2026-04-09T06:03:04.76862Z","steps":["trace[815831202] 'process raft request' (duration: 155.15887ms)","trace[815831202] 'compare' (duration: 19.336332ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:03:04.789634Z","caller":"traceutil/trace.go:171","msg":"trace[941871084] transaction","detail":"{read_only:false; response_revision:2984; number_of_response:1; }","duration":"195.596895ms","start":"2026-04-09T06:03:04.593991Z","end":"2026-04-09T06:03:04.789588Z","steps":["trace[941871084] 'process raft request' (duration: 195.204876ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:03:04.789877Z","caller":"traceutil/trace.go:171","msg":"trace[1232963213] transaction","detail":"{read_only:false; response_revision:2985; number_of_response:1; }","duration":"193.648917ms","start":"2026-04-09T06:03:04.596198Z","end":"2026-04-09T06:03:04.789847Z","steps":["trace[1232963213] 'process raft request' (duration: 193.164796ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:03:04.790065Z","caller":"traceutil/trace.go:171","msg":"trace[1826401498] linearizableReadLoop","detail":"{readStateIndex:3100; appliedIndex:3098; }","duration":"194.456987ms","start":"2026-04-09T06:03:04.595253Z","end":"2026-04-09T06:03:04.78971Z","steps":["trace[1826401498] 'read index received' (duration: 153.947991ms)","trace[1826401498] 'applied index is now lower than readState.Index' (duration: 40.506966ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:03:04.790173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.889157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T06:03:04.790602Z","caller":"traceutil/trace.go:171","msg":"trace[31419815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2985; }","duration":"195.301727ms","start":"2026-04-09T06:03:04.595225Z","end":"2026-04-09T06:03:04.790527Z","steps":["trace[31419815] 'agreement among raft nodes before linearized reading' (duration: 194.739733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:03:05.456421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.373021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:03:05.456569Z","caller":"traceutil/trace.go:171","msg":"trace[1184369360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2987; }","duration":"138.581666ms","start":"2026-04-09T06:03:05.317972Z","end":"2026-04-09T06:03:05.456554Z","steps":["trace[1184369360] 'range keys from in-memory index tree' (duration: 138.271658ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:03:05.456733Z","caller":"traceutil/trace.go:171","msg":"trace[111251165] transaction","detail":"{read_only:false; response_revision:2988; number_of_response:1; }","duration":"114.261074ms","start":"2026-04-09T06:03:05.342442Z","end":"2026-04-09T06:03:05.456703Z","steps":["trace[111251165] 'process raft request' (duration: 91.628093ms)","trace[111251165] 'compare' (duration: 22.321174ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:05:16.168085Z","caller":"traceutil/trace.go:171","msg":"trace[1703552524] transaction","detail":"{read_only:false; response_revision:3796; number_of_response:1; }","duration":"111.69201ms","start":"2026-04-09T06:05:16.056356Z","end":"2026-04-09T06:05:16.168048Z","steps":["trace[1703552524] 'process raft request' (duration: 109.114087ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:05:18.535887Z","caller":"traceutil/trace.go:171","msg":"trace[1562253743] transaction","detail":"{read_only:false; response_revision:3860; number_of_response:1; }","duration":"145.344712ms","start":"2026-04-09T06:05:18.390488Z","end":"2026-04-09T06:05:18.535832Z","steps":["trace[1562253743] 'process raft request' (duration: 134.62812ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:05:51.891365Z","caller":"traceutil/trace.go:171","msg":"trace[113180074] transaction","detail":"{read_only:false; response_revision:4059; number_of_response:1; }","duration":"131.841089ms","start":"2026-04-09T06:05:51.759482Z","end":"2026-04-09T06:05:51.891324Z","steps":["trace[113180074] 'process raft request' (duration: 131.608533ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:05:51.899291Z","caller":"traceutil/trace.go:171","msg":"trace[740705240] transaction","detail":"{read_only:false; response_revision:4062; number_of_response:1; }","duration":"138.516842ms","start":"2026-04-09T06:05:51.760739Z","end":"2026-04-09T06:05:51.899255Z","steps":["trace[740705240] 'process raft request' (duration: 138.441921ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:05:51.899344Z","caller":"traceutil/trace.go:171","msg":"trace[1838017832] transaction","detail":"{read_only:false; response_revision:4060; number_of_response:1; }","duration":"139.117707ms","start":"2026-04-09T06:05:51.760194Z","end":"2026-04-09T06:05:51.899312Z","steps":["trace[1838017832] 'process raft request' (duration: 138.745218ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:05:51.899415Z","caller":"traceutil/trace.go:171","msg":"trace[357764869] transaction","detail":"{read_only:false; response_revision:4061; number_of_response:1; }","duration":"139.025284ms","start":"2026-04-09T06:05:51.760347Z","end":"2026-04-09T06:05:51.899372Z","steps":["trace[357764869] 'process raft request' (duration: 138.785829ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:09.826265Z","caller":"traceutil/trace.go:171","msg":"trace[319955064] linearizableReadLoop","detail":"{readStateIndex:5147; appliedIndex:5146; }","duration":"142.215927ms","start":"2026-04-09T06:07:09.684019Z","end":"2026-04-09T06:07:09.826235Z","steps":["trace[319955064] 'read index received' (duration: 3.797567ms)","trace[319955064] 'applied index is now lower than readState.Index' (duration: 138.41653ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:09.826376Z","caller":"traceutil/trace.go:171","msg":"trace[1228028182] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"142.199157ms","start":"2026-04-09T06:07:09.684124Z","end":"2026-04-09T06:07:09.826324Z","steps":["trace[1228028182] 'process raft request' (duration: 142.026473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:09.826446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.403172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:07:09.82652Z","caller":"traceutil/trace.go:171","msg":"trace[92255527] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:4954; }","duration":"142.516135ms","start":"2026-04-09T06:07:09.683993Z","end":"2026-04-09T06:07:09.826509Z","steps":["trace[92255527] 'agreement among raft nodes before linearized reading' (duration: 142.398862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:09.826549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.276139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:07:09.826615Z","caller":"traceutil/trace.go:171","msg":"trace[1332276271] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:4954; }","duration":"142.366481ms","start":"2026-04-09T06:07:09.684235Z","end":"2026-04-09T06:07:09.826601Z","steps":["trace[1332276271] 'agreement among raft nodes before linearized reading' (duration: 142.251838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:09.826639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.258818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:07:09.826697Z","caller":"traceutil/trace.go:171","msg":"trace[734179734] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4954; }","duration":"142.353011ms","start":"2026-04-09T06:07:09.68433Z","end":"2026-04-09T06:07:09.826683Z","steps":["trace[734179734] 'agreement among raft nodes before linearized reading' (duration: 142.212798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:09.826892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.519039ms","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-04-09T06:07:09.826949Z","caller":"traceutil/trace.go:171","msg":"trace[178233237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4954; }","duration":"126.608601ms","start":"2026-04-09T06:07:09.700325Z","end":"2026-04-09T06:07:09.826933Z","steps":["trace[178233237] 'agreement among raft nodes before linearized reading' (duration: 126.249512ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:09.827112Z","caller":"traceutil/trace.go:171","msg":"trace[582654812] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"142.527115ms","start":"2026-04-09T06:07:09.683835Z","end":"2026-04-09T06:07:09.826362Z","steps":["trace[582654812] 'process raft request' (duration: 127.260448ms)","trace[582654812] 'compare' (duration: 14.886318ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:07:09.827307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.788201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:61927"} {"level":"info","ts":"2026-04-09T06:07:09.827578Z","caller":"traceutil/trace.go:171","msg":"trace[584720666] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4954; }","duration":"143.083749ms","start":"2026-04-09T06:07:09.684473Z","end":"2026-04-09T06:07:09.827557Z","steps":["trace[584720666] 'agreement among raft nodes before linearized reading' (duration: 142.033322ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:15.355921Z","caller":"traceutil/trace.go:171","msg":"trace[1153748987] linearizableReadLoop","detail":"{readStateIndex:5303; appliedIndex:5302; }","duration":"150.106217ms","start":"2026-04-09T06:07:15.205792Z","end":"2026-04-09T06:07:15.355898Z","steps":["trace[1153748987] 'read index received' (duration: 149.901452ms)","trace[1153748987] 'applied index is now lower than readState.Index' (duration: 203.985µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:15.356033Z","caller":"traceutil/trace.go:171","msg":"trace[1721507041] transaction","detail":"{read_only:false; response_revision:5103; number_of_response:1; }","duration":"189.952158ms","start":"2026-04-09T06:07:15.166072Z","end":"2026-04-09T06:07:15.356025Z","steps":["trace[1721507041] 'process raft request' (duration: 189.674241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:15.356491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.693992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q2pwc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T06:07:15.35652Z","caller":"traceutil/trace.go:171","msg":"trace[1356123628] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q2pwc; range_end:; response_count:1; response_revision:5103; }","duration":"150.787334ms","start":"2026-04-09T06:07:15.205725Z","end":"2026-04-09T06:07:15.356512Z","steps":["trace[1356123628] 'agreement among raft nodes before linearized reading' (duration: 150.643271ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:20.016633Z","caller":"traceutil/trace.go:171","msg":"trace[1006924735] transaction","detail":"{read_only:false; response_revision:5266; number_of_response:1; }","duration":"108.807491ms","start":"2026-04-09T06:07:19.907789Z","end":"2026-04-09T06:07:20.016596Z","steps":["trace[1006924735] 'compare' (duration: 99.153175ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:29.829088Z","caller":"traceutil/trace.go:171","msg":"trace[2043418866] transaction","detail":"{read_only:false; response_revision:5459; number_of_response:1; }","duration":"179.01953ms","start":"2026-04-09T06:07:29.650025Z","end":"2026-04-09T06:07:29.829045Z","steps":["trace[2043418866] 'process raft request' (duration: 96.574839ms)","trace[2043418866] 'compare' (duration: 82.200175ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:30.038898Z","caller":"traceutil/trace.go:171","msg":"trace[2074234233] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"117.48792ms","start":"2026-04-09T06:07:29.921378Z","end":"2026-04-09T06:07:30.038866Z","steps":["trace[2074234233] 'process raft request' (duration: 117.311115ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:30.03896Z","caller":"traceutil/trace.go:171","msg":"trace[865385083] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"117.48882ms","start":"2026-04-09T06:07:29.921433Z","end":"2026-04-09T06:07:30.038922Z","steps":["trace[865385083] 'process raft request' (duration: 117.350366ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:30.039063Z","caller":"traceutil/trace.go:171","msg":"trace[406521623] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"118.185038ms","start":"2026-04-09T06:07:29.920848Z","end":"2026-04-09T06:07:30.039034Z","steps":["trace[406521623] 'process raft request' (duration: 23.972759ms)","trace[406521623] 'compare' (duration: 93.643295ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:30.039403Z","caller":"traceutil/trace.go:171","msg":"trace[1588780139] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"117.85046ms","start":"2026-04-09T06:07:29.921539Z","end":"2026-04-09T06:07:30.039389Z","steps":["trace[1588780139] 'process raft request' (duration: 117.278794ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:30.690178Z","caller":"traceutil/trace.go:171","msg":"trace[746737999] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"292.150029ms","start":"2026-04-09T06:07:30.397995Z","end":"2026-04-09T06:07:30.690145Z","steps":["trace[746737999] 'process raft request' (duration: 291.930984ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:30.705172Z","caller":"traceutil/trace.go:171","msg":"trace[412588449] linearizableReadLoop","detail":"{readStateIndex:5683; appliedIndex:5679; }","duration":"121.935542ms","start":"2026-04-09T06:07:30.583224Z","end":"2026-04-09T06:07:30.705159Z","steps":["trace[412588449] 'read index received' (duration: 106.699096ms)","trace[412588449] 'applied index is now lower than readState.Index' (duration: 15.235566ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:30.705255Z","caller":"traceutil/trace.go:171","msg":"trace[469120205] transaction","detail":"{read_only:false; response_revision:5471; number_of_response:1; }","duration":"295.951916ms","start":"2026-04-09T06:07:30.409291Z","end":"2026-04-09T06:07:30.705243Z","steps":["trace[469120205] 'process raft request' (duration: 295.792782ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:30.705433Z","caller":"traceutil/trace.go:171","msg":"trace[1498056898] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"297.890386ms","start":"2026-04-09T06:07:30.407525Z","end":"2026-04-09T06:07:30.705415Z","steps":["trace[1498056898] 'process raft request' (duration: 297.427544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:30.705679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.424035ms","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-04-09T06:07:30.705782Z","caller":"traceutil/trace.go:171","msg":"trace[1910695663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5472; }","duration":"122.583898ms","start":"2026-04-09T06:07:30.583174Z","end":"2026-04-09T06:07:30.705758Z","steps":["trace[1910695663] 'agreement among raft nodes before linearized reading' (duration: 122.25966ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:30.706062Z","caller":"traceutil/trace.go:171","msg":"trace[1006245193] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"143.009198ms","start":"2026-04-09T06:07:30.562446Z","end":"2026-04-09T06:07:30.705455Z","steps":["trace[1006245193] 'process raft request' (duration: 142.679179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:39.320415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.853707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:07:39.320618Z","caller":"traceutil/trace.go:171","msg":"trace[2136974176] transaction","detail":"{read_only:false; response_revision:5648; number_of_response:1; }","duration":"333.449837ms","start":"2026-04-09T06:07:38.987116Z","end":"2026-04-09T06:07:39.320566Z","steps":["trace[2136974176] 'process raft request' (duration: 193.325283ms)","trace[2136974176] 'compare' (duration: 139.680712ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:39.320597Z","caller":"traceutil/trace.go:171","msg":"trace[1713777221] linearizableReadLoop","detail":"{readStateIndex:5864; appliedIndex:5863; }","duration":"193.472306ms","start":"2026-04-09T06:07:39.127093Z","end":"2026-04-09T06:07:39.320565Z","steps":["trace[1713777221] 'read index received' (duration: 53.300541ms)","trace[1713777221] 'applied index is now lower than readState.Index' (duration: 140.166415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:07:39.320836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:38.987082Z","time spent":"333.649353ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:07:39.320866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.778914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fb24f4b9-7646-4287-a331-d21a80adae4f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-09T06:07:39.320907Z","caller":"traceutil/trace.go:171","msg":"trace[284940204] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fb24f4b9-7646-4287-a331-d21a80adae4f; range_end:; response_count:1; response_revision:5648; }","duration":"193.871517ms","start":"2026-04-09T06:07:39.127022Z","end":"2026-04-09T06:07:39.320893Z","steps":["trace[284940204] 'agreement among raft nodes before linearized reading' (duration: 193.635591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:39.714487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.843765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:07:39.714705Z","caller":"traceutil/trace.go:171","msg":"trace[140579061] transaction","detail":"{read_only:false; response_revision:5649; number_of_response:1; }","duration":"448.452104ms","start":"2026-04-09T06:07:39.266209Z","end":"2026-04-09T06:07:39.714661Z","steps":["trace[140579061] 'process raft request' (duration: 301.305733ms)","trace[140579061] 'compare' (duration: 146.661599ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:07:39.714898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:39.266166Z","time spent":"448.633169ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:07:39.714937Z","caller":"traceutil/trace.go:171","msg":"trace[1602248865] transaction","detail":"{read_only:false; response_revision:5650; number_of_response:1; }","duration":"387.361874ms","start":"2026-04-09T06:07:39.327527Z","end":"2026-04-09T06:07:39.714889Z","steps":["trace[1602248865] 'process raft request' (duration: 387.097937ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:39.714809Z","caller":"traceutil/trace.go:171","msg":"trace[1956842720] linearizableReadLoop","detail":"{readStateIndex:5865; appliedIndex:5864; }","duration":"394.064684ms","start":"2026-04-09T06:07:39.320719Z","end":"2026-04-09T06:07:39.714784Z","steps":["trace[1956842720] 'read index received' (duration: 246.875042ms)","trace[1956842720] 'applied index is now lower than readState.Index' (duration: 147.187242ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:07:39.71507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:39.3275Z","time spent":"387.492348ms","remote":"127.0.0.1:56892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:07:39.715152Z","caller":"traceutil/trace.go:171","msg":"trace[541443133] transaction","detail":"{read_only:false; response_revision:5651; number_of_response:1; }","duration":"387.335113ms","start":"2026-04-09T06:07:39.327766Z","end":"2026-04-09T06:07:39.715101Z","steps":["trace[541443133] 'process raft request' (duration: 387.007405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:39.715482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:39.327757Z","time spent":"387.672284ms","remote":"127.0.0.1:56802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:07:39.715164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.234674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:07:39.715596Z","caller":"traceutil/trace.go:171","msg":"trace[1291854801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5651; }","duration":"397.729767ms","start":"2026-04-09T06:07:39.317851Z","end":"2026-04-09T06:07:39.715581Z","steps":["trace[1291854801] 'agreement among raft nodes before linearized reading' (duration: 397.176163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:39.715635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:39.31782Z","time spent":"397.80701ms","remote":"127.0.0.1:56644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T06:07:39.716097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.350921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fb24f4b9-7646-4287-a331-d21a80adae4f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-09T06:07:39.716176Z","caller":"traceutil/trace.go:171","msg":"trace[292334420] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fb24f4b9-7646-4287-a331-d21a80adae4f; range_end:; response_count:1; response_revision:5651; }","duration":"390.489945ms","start":"2026-04-09T06:07:39.325662Z","end":"2026-04-09T06:07:39.716152Z","steps":["trace[292334420] 'agreement among raft nodes before linearized reading' (duration: 390.276389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:39.716222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:39.325636Z","time spent":"390.576247ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fb24f4b9-7646-4287-a331-d21a80adae4f\" "} {"level":"warn","ts":"2026-04-09T06:07:39.716298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.540936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q2pwc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T06:07:39.716412Z","caller":"traceutil/trace.go:171","msg":"trace[1977837162] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q2pwc; range_end:; response_count:1; response_revision:5651; }","duration":"198.729781ms","start":"2026-04-09T06:07:39.517656Z","end":"2026-04-09T06:07:39.716386Z","steps":["trace[1977837162] 'agreement among raft nodes before linearized reading' (duration: 198.3245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:39.716469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.409096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-09T06:07:39.716578Z","caller":"traceutil/trace.go:171","msg":"trace[1834622278] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5651; }","duration":"320.591891ms","start":"2026-04-09T06:07:39.395961Z","end":"2026-04-09T06:07:39.716552Z","steps":["trace[1834622278] 'agreement among raft nodes before linearized reading' (duration: 320.202991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:39.716633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:39.395931Z","time spent":"320.693014ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-09T06:07:40.286646Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645624258266886,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T06:07:40.366557Z","caller":"traceutil/trace.go:171","msg":"trace[441799597] linearizableReadLoop","detail":"{readStateIndex:5869; appliedIndex:5867; }","duration":"580.940554ms","start":"2026-04-09T06:07:39.785589Z","end":"2026-04-09T06:07:40.36653Z","steps":["trace[441799597] 'read index received' (duration: 567.42237ms)","trace[441799597] 'applied index is now lower than readState.Index' (duration: 13.516574ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:40.366688Z","caller":"traceutil/trace.go:171","msg":"trace[1843725612] transaction","detail":"{read_only:false; response_revision:5653; number_of_response:1; }","duration":"296.909761ms","start":"2026-04-09T06:07:40.069738Z","end":"2026-04-09T06:07:40.366647Z","steps":["trace[1843725612] 'process raft request' (duration: 296.663615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:40.36676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.16375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:07:40.366806Z","caller":"traceutil/trace.go:171","msg":"trace[1092824458] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5653; }","duration":"581.275012ms","start":"2026-04-09T06:07:39.785518Z","end":"2026-04-09T06:07:40.366793Z","steps":["trace[1092824458] 'agreement among raft nodes before linearized reading' (duration: 581.17981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:40.366854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:39.785468Z","time spent":"581.377405ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-04-09T06:07:40.366842Z","caller":"traceutil/trace.go:171","msg":"trace[2004884660] transaction","detail":"{read_only:false; response_revision:5652; number_of_response:1; }","duration":"642.429804ms","start":"2026-04-09T06:07:39.724371Z","end":"2026-04-09T06:07:40.366801Z","steps":["trace[2004884660] 'process raft request' (duration: 629.256809ms)","trace[2004884660] 'compare' (duration: 12.161259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:07:40.367041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:39.724357Z","time spent":"642.574737ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:07:40.367174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.781683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:07:40.367269Z","caller":"traceutil/trace.go:171","msg":"trace[1409143924] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:5653; }","duration":"478.912797ms","start":"2026-04-09T06:07:39.888331Z","end":"2026-04-09T06:07:40.367244Z","steps":["trace[1409143924] 'agreement among raft nodes before linearized reading' (duration: 478.725992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:40.367282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.752709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T06:07:40.367377Z","caller":"traceutil/trace.go:171","msg":"trace[2083956810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5653; }","duration":"155.897254ms","start":"2026-04-09T06:07:40.211427Z","end":"2026-04-09T06:07:40.367324Z","steps":["trace[2083956810] 'agreement among raft nodes before linearized reading' (duration: 155.665568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:40.367383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:07:39.88827Z","time spent":"479.05054ms","remote":"127.0.0.1:49162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"info","ts":"2026-04-09T06:07:42.254014Z","caller":"traceutil/trace.go:171","msg":"trace[1054605810] transaction","detail":"{read_only:false; response_revision:5668; number_of_response:1; }","duration":"191.029095ms","start":"2026-04-09T06:07:42.062938Z","end":"2026-04-09T06:07:42.253967Z","steps":["trace[1054605810] 'process raft request' (duration: 179.272507ms)","trace[1054605810] 'compare' (duration: 11.200464ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:07:42.254168Z","caller":"traceutil/trace.go:171","msg":"trace[624154720] transaction","detail":"{read_only:false; response_revision:5671; number_of_response:1; }","duration":"190.590394ms","start":"2026-04-09T06:07:42.063539Z","end":"2026-04-09T06:07:42.25413Z","steps":["trace[624154720] 'process raft request' (duration: 190.456441ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:42.254189Z","caller":"traceutil/trace.go:171","msg":"trace[219425430] transaction","detail":"{read_only:false; response_revision:5670; number_of_response:1; }","duration":"190.84221ms","start":"2026-04-09T06:07:42.063277Z","end":"2026-04-09T06:07:42.25412Z","steps":["trace[219425430] 'process raft request' (duration: 190.616304ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:42.254727Z","caller":"traceutil/trace.go:171","msg":"trace[496874385] transaction","detail":"{read_only:false; response_revision:5672; number_of_response:1; }","duration":"191.126017ms","start":"2026-04-09T06:07:42.06357Z","end":"2026-04-09T06:07:42.254696Z","steps":["trace[496874385] 'process raft request' (duration: 190.493802ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:42.254881Z","caller":"traceutil/trace.go:171","msg":"trace[1457671497] transaction","detail":"{read_only:false; response_revision:5669; number_of_response:1; }","duration":"191.000495ms","start":"2026-04-09T06:07:42.063226Z","end":"2026-04-09T06:07:42.254226Z","steps":["trace[1457671497] 'process raft request' (duration: 190.524883ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:42.25542Z","caller":"traceutil/trace.go:171","msg":"trace[1967185128] transaction","detail":"{read_only:false; response_revision:5673; number_of_response:1; }","duration":"191.64262ms","start":"2026-04-09T06:07:42.063757Z","end":"2026-04-09T06:07:42.2554Z","steps":["trace[1967185128] 'process raft request' (duration: 190.465721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:07:52.689864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.241678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:07:52.689982Z","caller":"traceutil/trace.go:171","msg":"trace[352767103] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:5811; }","duration":"121.431863ms","start":"2026-04-09T06:07:52.568522Z","end":"2026-04-09T06:07:52.689954Z","steps":["trace[352767103] 'count revisions from in-memory index tree' (duration: 121.072055ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:07:54.92826Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2929} {"level":"info","ts":"2026-04-09T06:07:55.013835Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2929,"took":"83.766242ms","hash":4077661278,"current-db-size-bytes":56557568,"current-db-size":"57 MB","current-db-size-in-use-bytes":32120832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-09T06:07:55.013933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4077661278,"revision":2929,"compact-revision":-1} {"level":"info","ts":"2026-04-09T06:10:39.73192Z","caller":"traceutil/trace.go:171","msg":"trace[1208660889] transaction","detail":"{read_only:false; response_revision:6960; number_of_response:1; }","duration":"192.249036ms","start":"2026-04-09T06:10:39.53963Z","end":"2026-04-09T06:10:39.731879Z","steps":["trace[1208660889] 'process raft request' (duration: 100.310461ms)","trace[1208660889] 'compare' (duration: 91.77137ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:10:39.782064Z","caller":"traceutil/trace.go:171","msg":"trace[1274397740] transaction","detail":"{read_only:false; response_revision:6962; number_of_response:1; }","duration":"182.339172ms","start":"2026-04-09T06:10:39.599689Z","end":"2026-04-09T06:10:39.782028Z","steps":["trace[1274397740] 'process raft request' (duration: 182.26861ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:10:39.782139Z","caller":"traceutil/trace.go:171","msg":"trace[2059161887] transaction","detail":"{read_only:false; response_revision:6961; number_of_response:1; }","duration":"198.382526ms","start":"2026-04-09T06:10:39.583703Z","end":"2026-04-09T06:10:39.782085Z","steps":["trace[2059161887] 'process raft request' (duration: 198.018447ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:10:40.927885Z","caller":"traceutil/trace.go:171","msg":"trace[580846468] linearizableReadLoop","detail":"{readStateIndex:7224; appliedIndex:7223; }","duration":"163.788084ms","start":"2026-04-09T06:10:40.764068Z","end":"2026-04-09T06:10:40.927856Z","steps":["trace[580846468] 'read index received' (duration: 163.581359ms)","trace[580846468] 'applied index is now lower than readState.Index' (duration: 205.275µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:10:40.928079Z","caller":"traceutil/trace.go:171","msg":"trace[1811006993] transaction","detail":"{read_only:false; response_revision:6967; number_of_response:1; }","duration":"185.750946ms","start":"2026-04-09T06:10:40.742268Z","end":"2026-04-09T06:10:40.928019Z","steps":["trace[1811006993] 'process raft request' (duration: 185.394556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:10:40.928358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.270007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q2pwc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T06:10:40.928427Z","caller":"traceutil/trace.go:171","msg":"trace[1180872547] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q2pwc; range_end:; response_count:1; response_revision:6967; }","duration":"164.39634ms","start":"2026-04-09T06:10:40.764014Z","end":"2026-04-09T06:10:40.92841Z","steps":["trace[1180872547] 'agreement among raft nodes before linearized reading' (duration: 163.954458ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:10:41.761623Z","caller":"traceutil/trace.go:171","msg":"trace[1021414210] transaction","detail":"{read_only:false; response_revision:6973; number_of_response:1; }","duration":"182.864125ms","start":"2026-04-09T06:10:41.578735Z","end":"2026-04-09T06:10:41.761599Z","steps":["trace[1021414210] 'process raft request' (duration: 182.6926ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:10:41.909344Z","caller":"traceutil/trace.go:171","msg":"trace[316978232] transaction","detail":"{read_only:false; response_revision:6975; number_of_response:1; }","duration":"121.267178ms","start":"2026-04-09T06:10:41.788039Z","end":"2026-04-09T06:10:41.909306Z","steps":["trace[316978232] 'process raft request' (duration: 89.358141ms)","trace[316978232] 'compare' (duration: 31.709253ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:10:42.032287Z","caller":"traceutil/trace.go:171","msg":"trace[515876734] transaction","detail":"{read_only:false; response_revision:6977; number_of_response:1; }","duration":"115.875855ms","start":"2026-04-09T06:10:41.916363Z","end":"2026-04-09T06:10:42.032239Z","steps":["trace[515876734] 'process raft request' (duration: 110.628035ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:10:42.032479Z","caller":"traceutil/trace.go:171","msg":"trace[127072946] linearizableReadLoop","detail":"{readStateIndex:7234; appliedIndex:7233; }","duration":"104.006512ms","start":"2026-04-09T06:10:41.92842Z","end":"2026-04-09T06:10:42.032427Z","steps":["trace[127072946] 'read index received' (duration: 56.742628ms)","trace[127072946] 'applied index is now lower than readState.Index' (duration: 47.262994ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:10:42.0329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.460313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T06:10:42.033041Z","caller":"traceutil/trace.go:171","msg":"trace[1103467213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6978; }","duration":"104.644128ms","start":"2026-04-09T06:10:41.928378Z","end":"2026-04-09T06:10:42.033022Z","steps":["trace[1103467213] 'agreement among raft nodes before linearized reading' (duration: 104.406952ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:10:42.190328Z","caller":"traceutil/trace.go:171","msg":"trace[1537788007] transaction","detail":"{read_only:false; response_revision:6980; number_of_response:1; }","duration":"152.746613ms","start":"2026-04-09T06:10:42.037548Z","end":"2026-04-09T06:10:42.190295Z","steps":["trace[1537788007] 'process raft request' (duration: 112.504042ms)","trace[1537788007] 'compare' (duration: 39.983724ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:10:42.191073Z","caller":"traceutil/trace.go:171","msg":"trace[211468035] transaction","detail":"{read_only:false; response_revision:6981; number_of_response:1; }","duration":"149.324208ms","start":"2026-04-09T06:10:42.041721Z","end":"2026-04-09T06:10:42.191046Z","steps":["trace[211468035] 'process raft request' (duration: 148.501758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:10:42.191296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.087909ms","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:5"} {"level":"info","ts":"2026-04-09T06:10:42.191426Z","caller":"traceutil/trace.go:171","msg":"trace[2135767837] linearizableReadLoop","detail":"{readStateIndex:7238; appliedIndex:7236; }","duration":"130.832153ms","start":"2026-04-09T06:10:42.060156Z","end":"2026-04-09T06:10:42.190989Z","steps":["trace[2135767837] 'read index received' (duration: 10.898149ms)","trace[2135767837] 'applied index is now lower than readState.Index' (duration: 119.932993ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:10:42.191434Z","caller":"traceutil/trace.go:171","msg":"trace[1897349418] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:6981; }","duration":"131.254762ms","start":"2026-04-09T06:10:42.060131Z","end":"2026-04-09T06:10:42.191386Z","steps":["trace[1897349418] 'agreement among raft nodes before linearized reading' (duration: 131.028207ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:10:42.528227Z","caller":"traceutil/trace.go:171","msg":"trace[1844175807] transaction","detail":"{read_only:false; response_revision:6984; number_of_response:1; }","duration":"126.073955ms","start":"2026-04-09T06:10:42.402103Z","end":"2026-04-09T06:10:42.528177Z","steps":["trace[1844175807] 'process raft request' (duration: 124.436904ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:10:42.732348Z","caller":"traceutil/trace.go:171","msg":"trace[356155268] transaction","detail":"{read_only:false; response_revision:6986; number_of_response:1; }","duration":"111.426516ms","start":"2026-04-09T06:10:42.620872Z","end":"2026-04-09T06:10:42.732299Z","steps":["trace[356155268] 'process raft request' (duration: 111.189529ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:12:54.936101Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5837} {"level":"info","ts":"2026-04-09T06:12:55.019141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5837,"took":"80.941582ms","hash":3835525962,"current-db-size-bytes":56557568,"current-db-size":"57 MB","current-db-size-in-use-bytes":26578944,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-09T06:12:55.019217Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3835525962,"revision":5837,"compact-revision":2929} {"level":"info","ts":"2026-04-09T06:13:42.25299Z","caller":"traceutil/trace.go:171","msg":"trace[366070395] linearizableReadLoop","detail":"{readStateIndex:8766; appliedIndex:8765; }","duration":"299.518044ms","start":"2026-04-09T06:13:41.953426Z","end":"2026-04-09T06:13:42.252944Z","steps":["trace[366070395] 'read index received' (duration: 299.327469ms)","trace[366070395] 'applied index is now lower than readState.Index' (duration: 189.455µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:13:42.253278Z","caller":"traceutil/trace.go:171","msg":"trace[1985083692] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"310.916763ms","start":"2026-04-09T06:13:41.942349Z","end":"2026-04-09T06:13:42.253265Z","steps":["trace[1985083692] 'process raft request' (duration: 310.463502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:42.253386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:41.94232Z","time spent":"310.995855ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:13:42.25363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.20366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q2pwc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T06:13:42.25371Z","caller":"traceutil/trace.go:171","msg":"trace[1516118109] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q2pwc; range_end:; response_count:1; response_revision:8463; }","duration":"300.309033ms","start":"2026-04-09T06:13:41.953385Z","end":"2026-04-09T06:13:42.253694Z","steps":["trace[1516118109] 'agreement among raft nodes before linearized reading' (duration: 300.130729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:42.253764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:41.953367Z","time spent":"300.386485ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-q2pwc\" "} {"level":"warn","ts":"2026-04-09T06:13:42.253806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.274629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-09T06:13:42.253875Z","caller":"traceutil/trace.go:171","msg":"trace[153030343] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8463; }","duration":"199.372591ms","start":"2026-04-09T06:13:42.054477Z","end":"2026-04-09T06:13:42.25385Z","steps":["trace[153030343] 'agreement among raft nodes before linearized reading' (duration: 199.212527ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:42.534655Z","caller":"traceutil/trace.go:171","msg":"trace[576896886] linearizableReadLoop","detail":"{readStateIndex:8767; appliedIndex:8766; }","duration":"277.418563ms","start":"2026-04-09T06:13:42.257203Z","end":"2026-04-09T06:13:42.534621Z","steps":["trace[576896886] 'read index received' (duration: 275.108606ms)","trace[576896886] 'applied index is now lower than readState.Index' (duration: 2.308547ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:13:42.534721Z","caller":"traceutil/trace.go:171","msg":"trace[68010517] transaction","detail":"{read_only:false; response_revision:8464; number_of_response:1; }","duration":"279.027291ms","start":"2026-04-09T06:13:42.255664Z","end":"2026-04-09T06:13:42.534691Z","steps":["trace[68010517] 'process raft request' (duration: 276.703254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:42.534913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.691449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-09T06:13:42.535005Z","caller":"traceutil/trace.go:171","msg":"trace[1614981193] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8464; }","duration":"277.809332ms","start":"2026-04-09T06:13:42.257179Z","end":"2026-04-09T06:13:42.534988Z","steps":["trace[1614981193] 'agreement among raft nodes before linearized reading' (duration: 277.542006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:42.535249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.891654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224135"} {"level":"info","ts":"2026-04-09T06:13:42.535341Z","caller":"traceutil/trace.go:171","msg":"trace[870787165] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8464; }","duration":"277.998607ms","start":"2026-04-09T06:13:42.257295Z","end":"2026-04-09T06:13:42.535293Z","steps":["trace[870787165] 'agreement among raft nodes before linearized reading' (duration: 277.517995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:42.535335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.553872ms","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-04-09T06:13:42.535403Z","caller":"traceutil/trace.go:171","msg":"trace[1932355765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8464; }","duration":"225.654965ms","start":"2026-04-09T06:13:42.309733Z","end":"2026-04-09T06:13:42.535388Z","steps":["trace[1932355765] 'agreement among raft nodes before linearized reading' (duration: 225.46356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:42.535423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.573501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:13:42.536481Z","caller":"traceutil/trace.go:171","msg":"trace[549016573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8464; }","duration":"219.606277ms","start":"2026-04-09T06:13:42.316785Z","end":"2026-04-09T06:13:42.536392Z","steps":["trace[549016573] 'agreement among raft nodes before linearized reading' (duration: 218.403507ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:53.558415Z","caller":"traceutil/trace.go:171","msg":"trace[1075563033] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"283.234985ms","start":"2026-04-09T06:13:53.27515Z","end":"2026-04-09T06:13:53.558385Z","steps":["trace[1075563033] 'process raft request' (duration: 260.966959ms)","trace[1075563033] 'compare' (duration: 21.433755ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:13:54.626942Z","caller":"traceutil/trace.go:171","msg":"trace[1377512631] linearizableReadLoop","detail":"{readStateIndex:8831; appliedIndex:8830; }","duration":"237.699171ms","start":"2026-04-09T06:13:54.389222Z","end":"2026-04-09T06:13:54.626921Z","steps":["trace[1377512631] 'read index received' (duration: 237.637009ms)","trace[1377512631] 'applied index is now lower than readState.Index' (duration: 61.122µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:13:54.627056Z","caller":"traceutil/trace.go:171","msg":"trace[312745250] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"286.221508ms","start":"2026-04-09T06:13:54.3408Z","end":"2026-04-09T06:13:54.627022Z","steps":["trace[312745250] 'process raft request' (duration: 285.982092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:54.627556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.310965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-09T06:13:54.627599Z","caller":"traceutil/trace.go:171","msg":"trace[1119000040] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8524; }","duration":"238.409558ms","start":"2026-04-09T06:13:54.38918Z","end":"2026-04-09T06:13:54.62759Z","steps":["trace[1119000040] 'agreement among raft nodes before linearized reading' (duration: 237.850494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:54.647841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.806651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T06:13:54.647926Z","caller":"traceutil/trace.go:171","msg":"trace[1125011927] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:8525; }","duration":"181.951305ms","start":"2026-04-09T06:13:54.465957Z","end":"2026-04-09T06:13:54.647908Z","steps":["trace[1125011927] 'agreement among raft nodes before linearized reading' (duration: 181.75819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:54.648176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.805835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-09T06:13:54.648308Z","caller":"traceutil/trace.go:171","msg":"trace[1493888261] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:8525; }","duration":"173.9708ms","start":"2026-04-09T06:13:54.474312Z","end":"2026-04-09T06:13:54.648283Z","steps":["trace[1493888261] 'agreement among raft nodes before linearized reading' (duration: 173.586921ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:55.500475Z","caller":"traceutil/trace.go:171","msg":"trace[1483278860] linearizableReadLoop","detail":"{readStateIndex:8839; appliedIndex:8838; }","duration":"154.871971ms","start":"2026-04-09T06:13:55.345585Z","end":"2026-04-09T06:13:55.500457Z","steps":["trace[1483278860] 'read index received' (duration: 58.564984ms)","trace[1483278860] 'applied index is now lower than readState.Index' (duration: 96.305597ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:13:55.500515Z","caller":"traceutil/trace.go:171","msg":"trace[1560361615] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"156.093851ms","start":"2026-04-09T06:13:55.344386Z","end":"2026-04-09T06:13:55.50048Z","steps":["trace[1560361615] 'process raft request' (duration: 59.825514ms)","trace[1560361615] 'compare' (duration: 96.148264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:13:55.500643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.049477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T06:13:55.500674Z","caller":"traceutil/trace.go:171","msg":"trace[389325093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8532; }","duration":"155.138199ms","start":"2026-04-09T06:13:55.345527Z","end":"2026-04-09T06:13:55.500666Z","steps":["trace[389325093] 'agreement among raft nodes before linearized reading' (duration: 155.020977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:56.240693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.811527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:13:56.240799Z","caller":"traceutil/trace.go:171","msg":"trace[1493975022] linearizableReadLoop","detail":"{readStateIndex:8840; appliedIndex:8839; }","duration":"677.812767ms","start":"2026-04-09T06:13:55.56297Z","end":"2026-04-09T06:13:56.240783Z","steps":["trace[1493975022] 'read index received' (duration: 262.791445ms)","trace[1493975022] 'applied index is now lower than readState.Index' (duration: 415.020112ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:13:56.240881Z","caller":"traceutil/trace.go:171","msg":"trace[286528393] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"735.744086ms","start":"2026-04-09T06:13:55.505126Z","end":"2026-04-09T06:13:56.24087Z","steps":["trace[286528393] 'process raft request' (duration: 320.653542ms)","trace[286528393] 'compare' (duration: 414.52331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:13:56.240936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:55.505087Z","time spent":"735.819468ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:13:56.241417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.423522ms","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-04-09T06:13:56.241536Z","caller":"traceutil/trace.go:171","msg":"trace[1805621502] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8533; }","duration":"678.603316ms","start":"2026-04-09T06:13:55.562907Z","end":"2026-04-09T06:13:56.24151Z","steps":["trace[1805621502] 'agreement among raft nodes before linearized reading' (duration: 678.274628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:56.241576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:55.562886Z","time spent":"678.681068ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-09T06:13:56.820465Z","caller":"traceutil/trace.go:171","msg":"trace[2011135477] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"227.40745ms","start":"2026-04-09T06:13:56.593031Z","end":"2026-04-09T06:13:56.820439Z","steps":["trace[2011135477] 'process raft request' (duration: 227.222005ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:57.492233Z","caller":"traceutil/trace.go:171","msg":"trace[924629745] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"859.407452ms","start":"2026-04-09T06:13:56.632802Z","end":"2026-04-09T06:13:57.49221Z","steps":["trace[924629745] 'process raft request' (duration: 859.222868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:57.492379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:56.632774Z","time spent":"859.527925ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:13:57.492564Z","caller":"traceutil/trace.go:171","msg":"trace[292300668] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"839.491325ms","start":"2026-04-09T06:13:56.653052Z","end":"2026-04-09T06:13:57.492543Z","steps":["trace[292300668] 'process raft request' (duration: 839.117985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:57.492763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:56.653017Z","time spent":"839.66678ms","remote":"127.0.0.1:56878","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-04-09T06:13:57.493226Z","caller":"traceutil/trace.go:171","msg":"trace[1673102035] linearizableReadLoop","detail":"{readStateIndex:8845; appliedIndex:8842; }","duration":"817.474096ms","start":"2026-04-09T06:13:56.67574Z","end":"2026-04-09T06:13:57.493214Z","steps":["trace[1673102035] 'read index received' (duration: 144.647522ms)","trace[1673102035] 'applied index is now lower than readState.Index' (duration: 672.825704ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:13:57.493317Z","caller":"traceutil/trace.go:171","msg":"trace[95132634] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"764.659812ms","start":"2026-04-09T06:13:56.728642Z","end":"2026-04-09T06:13:57.493302Z","steps":["trace[95132634] 'process raft request' (duration: 764.436347ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:57.49335Z","caller":"traceutil/trace.go:171","msg":"trace[1057778377] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"805.476963ms","start":"2026-04-09T06:13:56.687686Z","end":"2026-04-09T06:13:57.493163Z","steps":["trace[1057778377] 'process raft request' (duration: 804.764796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:57.493405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:56.728628Z","time spent":"764.735205ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:13:57.493553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.792954ms","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-04-09T06:13:57.493803Z","caller":"traceutil/trace.go:171","msg":"trace[1331601524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8540; }","duration":"818.03544ms","start":"2026-04-09T06:13:56.675701Z","end":"2026-04-09T06:13:57.493736Z","steps":["trace[1331601524] 'agreement among raft nodes before linearized reading' (duration: 817.681401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:57.494002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:56.675688Z","time spent":"818.294526ms","remote":"127.0.0.1:56878","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":"warn","ts":"2026-04-09T06:13:57.49404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:56.687669Z","time spent":"805.830961ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:13:57.625345Z","caller":"traceutil/trace.go:171","msg":"trace[722772398] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"758.662866ms","start":"2026-04-09T06:13:56.866652Z","end":"2026-04-09T06:13:57.625315Z","steps":["trace[722772398] 'process raft request' (duration: 735.991861ms)","trace[722772398] 'compare' (duration: 22.46477ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:13:57.625373Z","caller":"traceutil/trace.go:171","msg":"trace[2122843933] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"127.501813ms","start":"2026-04-09T06:13:57.497847Z","end":"2026-04-09T06:13:57.625349Z","steps":["trace[2122843933] 'process raft request' (duration: 127.440821ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:57.625408Z","caller":"traceutil/trace.go:171","msg":"trace[1988056049] linearizableReadLoop","detail":"{readStateIndex:8848; appliedIndex:8847; }","duration":"132.114375ms","start":"2026-04-09T06:13:57.493262Z","end":"2026-04-09T06:13:57.625377Z","steps":["trace[1988056049] 'read index received' (duration: 109.297917ms)","trace[1988056049] 'applied index is now lower than readState.Index' (duration: 22.808028ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:13:57.625484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:56.866628Z","time spent":"758.779699ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:13:57.625608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.626419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-09T06:13:57.62563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.492101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q2pwc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T06:13:57.625674Z","caller":"traceutil/trace.go:171","msg":"trace[734977648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8542; }","duration":"538.72675ms","start":"2026-04-09T06:13:57.086932Z","end":"2026-04-09T06:13:57.625659Z","steps":["trace[734977648] 'agreement among raft nodes before linearized reading' (duration: 538.523575ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:57.625688Z","caller":"traceutil/trace.go:171","msg":"trace[1675317388] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q2pwc; range_end:; response_count:1; response_revision:8542; }","duration":"945.592442ms","start":"2026-04-09T06:13:56.680079Z","end":"2026-04-09T06:13:57.625672Z","steps":["trace[1675317388] 'agreement among raft nodes before linearized reading' (duration: 945.385627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:57.625712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:57.086914Z","time spent":"538.790452ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T06:13:57.625738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:56.680064Z","time spent":"945.663755ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-q2pwc\" "} {"level":"warn","ts":"2026-04-09T06:13:57.625661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.300188ms","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-04-09T06:13:57.625918Z","caller":"traceutil/trace.go:171","msg":"trace[979189484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8542; }","duration":"533.585735ms","start":"2026-04-09T06:13:57.092227Z","end":"2026-04-09T06:13:57.625813Z","steps":["trace[979189484] 'agreement among raft nodes before linearized reading' (duration: 533.161245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:57.626039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:57.092207Z","time spent":"533.770939ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T06:13:59.7196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708645624258274445,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T06:13:59.752055Z","caller":"traceutil/trace.go:171","msg":"trace[1432000708] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"590.52396ms","start":"2026-04-09T06:13:59.161493Z","end":"2026-04-09T06:13:59.752017Z","steps":["trace[1432000708] 'process raft request' (duration: 590.255253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:59.752589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:59.161472Z","time spent":"590.799357ms","remote":"127.0.0.1:56746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T06:13:59.822276Z","caller":"traceutil/trace.go:171","msg":"trace[440886815] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"324.522546ms","start":"2026-04-09T06:13:59.497723Z","end":"2026-04-09T06:13:59.822245Z","steps":["trace[440886815] 'process raft request' (duration: 324.390313ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:59.822397Z","caller":"traceutil/trace.go:171","msg":"trace[1690156313] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"324.576848ms","start":"2026-04-09T06:13:59.497784Z","end":"2026-04-09T06:13:59.822361Z","steps":["trace[1690156313] 'process raft request' (duration: 324.368843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:59.822446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:59.497713Z","time spent":"324.657329ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:13:59.822576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:59.497777Z","time spent":"324.716991ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:13:59.822271Z","caller":"traceutil/trace.go:171","msg":"trace[1204737953] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"324.780513ms","start":"2026-04-09T06:13:59.49745Z","end":"2026-04-09T06:13:59.822231Z","steps":["trace[1204737953] 'process raft request' (duration: 324.516536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:59.822706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:59.497429Z","time spent":"325.216103ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:13:59.823027Z","caller":"traceutil/trace.go:171","msg":"trace[2070675869] linearizableReadLoop","detail":"{readStateIndex:8863; appliedIndex:8858; }","duration":"604.145592ms","start":"2026-04-09T06:13:59.218852Z","end":"2026-04-09T06:13:59.822997Z","steps":["trace[2070675869] 'read index received' (duration: 532.882458ms)","trace[2070675869] 'applied index is now lower than readState.Index' (duration: 71.262004ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:13:59.823077Z","caller":"traceutil/trace.go:171","msg":"trace[148104530] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"323.940572ms","start":"2026-04-09T06:13:59.499117Z","end":"2026-04-09T06:13:59.823057Z","steps":["trace[148104530] 'process raft request' (duration: 323.07753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:59.823155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:59.49909Z","time spent":"324.024914ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:13:59.823231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.354898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:13:59.823315Z","caller":"traceutil/trace.go:171","msg":"trace[1717780271] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:8555; }","duration":"604.46463ms","start":"2026-04-09T06:13:59.218833Z","end":"2026-04-09T06:13:59.823297Z","steps":["trace[1717780271] 'agreement among raft nodes before linearized reading' (duration: 604.289626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:59.823358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:59.218776Z","time spent":"604.571604ms","remote":"127.0.0.1:49226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-04-09T06:13:59.823374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.255586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T06:13:59.823385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.057846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:13:59.823441Z","caller":"traceutil/trace.go:171","msg":"trace[1537820750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8555; }","duration":"506.346608ms","start":"2026-04-09T06:13:59.317077Z","end":"2026-04-09T06:13:59.823424Z","steps":["trace[1537820750] 'agreement among raft nodes before linearized reading' (duration: 506.242846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:59.823483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:59.317061Z","time spent":"506.41429ms","remote":"127.0.0.1:56644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T06:13:59.823489Z","caller":"traceutil/trace.go:171","msg":"trace[454259868] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8555; }","duration":"295.201568ms","start":"2026-04-09T06:13:59.528256Z","end":"2026-04-09T06:13:59.823458Z","steps":["trace[454259868] 'agreement among raft nodes before linearized reading' (duration: 295.014264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:59.823548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.361621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/rook-ceph/rook-ceph-operator-76bbbb77ff-tpcjc\" ","response":"range_response_count:1 size:4462"} {"level":"warn","ts":"2026-04-09T06:13:59.823604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.428893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q2pwc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T06:13:59.823627Z","caller":"traceutil/trace.go:171","msg":"trace[1646730612] range","detail":"{range_begin:/registry/pods/rook-ceph/rook-ceph-operator-76bbbb77ff-tpcjc; range_end:; response_count:1; response_revision:8555; }","duration":"549.490254ms","start":"2026-04-09T06:13:59.274094Z","end":"2026-04-09T06:13:59.823584Z","steps":["trace[1646730612] 'agreement among raft nodes before linearized reading' (duration: 549.070814ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:59.823658Z","caller":"traceutil/trace.go:171","msg":"trace[1620924652] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q2pwc; range_end:; response_count:1; response_revision:8555; }","duration":"150.510616ms","start":"2026-04-09T06:13:59.673133Z","end":"2026-04-09T06:13:59.823643Z","steps":["trace[1620924652] 'agreement among raft nodes before linearized reading' (duration: 150.338682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:59.823673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:13:59.274073Z","time spent":"549.589246ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":4485,"request content":"key:\"/registry/pods/rook-ceph/rook-ceph-operator-76bbbb77ff-tpcjc\" "} {"level":"warn","ts":"2026-04-09T06:13:59.823891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.279186ms","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-04-09T06:13:59.823948Z","caller":"traceutil/trace.go:171","msg":"trace[1736408908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8555; }","duration":"156.355159ms","start":"2026-04-09T06:13:59.667581Z","end":"2026-04-09T06:13:59.823936Z","steps":["trace[1736408908] 'agreement among raft nodes before linearized reading' (duration: 155.620831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:13:59.824077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.429141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T06:13:59.824116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.210749ms","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-04-09T06:13:59.824166Z","caller":"traceutil/trace.go:171","msg":"trace[883381017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8555; }","duration":"195.095818ms","start":"2026-04-09T06:13:59.629048Z","end":"2026-04-09T06:13:59.824144Z","steps":["trace[883381017] 'agreement among raft nodes before linearized reading' (duration: 194.180906ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:13:59.824208Z","caller":"traceutil/trace.go:171","msg":"trace[1930030829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8555; }","duration":"157.381003ms","start":"2026-04-09T06:13:59.666812Z","end":"2026-04-09T06:13:59.824193Z","steps":["trace[1930030829] 'agreement among raft nodes before linearized reading' (duration: 156.599524ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:22.480994Z","caller":"traceutil/trace.go:171","msg":"trace[235619500] transaction","detail":"{read_only:false; response_revision:8727; number_of_response:1; }","duration":"114.635237ms","start":"2026-04-09T06:14:22.366303Z","end":"2026-04-09T06:14:22.480938Z","steps":["trace[235619500] 'process raft request' (duration: 114.229396ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:26.079833Z","caller":"traceutil/trace.go:171","msg":"trace[1481432048] transaction","detail":"{read_only:false; response_revision:8741; number_of_response:1; }","duration":"159.582658ms","start":"2026-04-09T06:14:25.920222Z","end":"2026-04-09T06:14:26.079805Z","steps":["trace[1481432048] 'process raft request' (duration: 159.432924ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:26.086542Z","caller":"traceutil/trace.go:171","msg":"trace[1302487700] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"161.408893ms","start":"2026-04-09T06:14:25.925112Z","end":"2026-04-09T06:14:26.086521Z","steps":["trace[1302487700] 'process raft request' (duration: 159.381363ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:26.086717Z","caller":"traceutil/trace.go:171","msg":"trace[748785062] transaction","detail":"{read_only:false; response_revision:8744; number_of_response:1; }","duration":"144.607491ms","start":"2026-04-09T06:14:25.942089Z","end":"2026-04-09T06:14:26.086696Z","steps":["trace[748785062] 'process raft request' (duration: 144.472158ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:26.086661Z","caller":"traceutil/trace.go:171","msg":"trace[1041894503] linearizableReadLoop","detail":"{readStateIndex:9059; appliedIndex:9056; }","duration":"138.189625ms","start":"2026-04-09T06:14:25.948444Z","end":"2026-04-09T06:14:26.086634Z","steps":["trace[1041894503] 'read index received' (duration: 131.626803ms)","trace[1041894503] 'applied index is now lower than readState.Index' (duration: 6.561522ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:26.086757Z","caller":"traceutil/trace.go:171","msg":"trace[1017229150] transaction","detail":"{read_only:false; response_revision:8743; number_of_response:1; }","duration":"145.129544ms","start":"2026-04-09T06:14:25.941593Z","end":"2026-04-09T06:14:26.086722Z","steps":["trace[1017229150] 'process raft request' (duration: 144.847057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:26.087037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.582964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-09T06:14:26.087063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.426039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q2pwc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T06:14:26.087097Z","caller":"traceutil/trace.go:171","msg":"trace[487246961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8744; }","duration":"138.687546ms","start":"2026-04-09T06:14:25.948396Z","end":"2026-04-09T06:14:26.087083Z","steps":["trace[487246961] 'agreement among raft nodes before linearized reading' (duration: 138.484992ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:26.087114Z","caller":"traceutil/trace.go:171","msg":"trace[1472561833] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q2pwc; range_end:; response_count:1; response_revision:8744; }","duration":"120.529781ms","start":"2026-04-09T06:14:25.96657Z","end":"2026-04-09T06:14:26.0871Z","steps":["trace[1472561833] 'agreement among raft nodes before linearized reading' (duration: 120.335106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:26.087096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.179669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-09T06:14:26.087285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.645854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T06:14:26.087292Z","caller":"traceutil/trace.go:171","msg":"trace[1919638955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8744; }","duration":"135.377675ms","start":"2026-04-09T06:14:25.951836Z","end":"2026-04-09T06:14:26.087214Z","steps":["trace[1919638955] 'agreement among raft nodes before linearized reading' (duration: 135.066867ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:26.087351Z","caller":"traceutil/trace.go:171","msg":"trace[1327669583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8744; }","duration":"131.739575ms","start":"2026-04-09T06:14:25.955593Z","end":"2026-04-09T06:14:26.087332Z","steps":["trace[1327669583] 'agreement among raft nodes before linearized reading' (duration: 131.420308ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:30.327368Z","caller":"traceutil/trace.go:171","msg":"trace[1669562111] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"232.971195ms","start":"2026-04-09T06:14:30.094376Z","end":"2026-04-09T06:14:30.327348Z","steps":["trace[1669562111] 'process raft request' (duration: 232.82458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:30.480832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.149214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T06:14:30.481052Z","caller":"traceutil/trace.go:171","msg":"trace[1520239288] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"383.413428ms","start":"2026-04-09T06:14:30.097608Z","end":"2026-04-09T06:14:30.481022Z","steps":["trace[1520239288] 'process raft request' (duration: 277.987337ms)","trace[1520239288] 'compare' (duration: 105.040571ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:30.481096Z","caller":"traceutil/trace.go:171","msg":"trace[1540289432] linearizableReadLoop","detail":"{readStateIndex:9082; appliedIndex:9078; }","duration":"369.189709ms","start":"2026-04-09T06:14:30.111889Z","end":"2026-04-09T06:14:30.481079Z","steps":["trace[1540289432] 'read index received' (duration: 215.378064ms)","trace[1540289432] 'applied index is now lower than readState.Index' (duration: 153.810805ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:30.481123Z","caller":"traceutil/trace.go:171","msg":"trace[379770329] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"377.691947ms","start":"2026-04-09T06:14:30.103412Z","end":"2026-04-09T06:14:30.481104Z","steps":["trace[379770329] 'process raft request' (duration: 377.593324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:30.481203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:30.09759Z","time spent":"383.53397ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:14:30.481204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:30.103404Z","time spent":"377.758059ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:14:30.481255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.361633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q2pwc\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T06:14:30.481288Z","caller":"traceutil/trace.go:171","msg":"trace[192927718] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q2pwc; range_end:; response_count:1; response_revision:8766; }","duration":"369.435925ms","start":"2026-04-09T06:14:30.111843Z","end":"2026-04-09T06:14:30.481279Z","steps":["trace[192927718] 'agreement among raft nodes before linearized reading' (duration: 369.309292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:30.481324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:30.111821Z","time spent":"369.494816ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-q2pwc\" "} {"level":"info","ts":"2026-04-09T06:14:30.481507Z","caller":"traceutil/trace.go:171","msg":"trace[793407138] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"382.65097ms","start":"2026-04-09T06:14:30.098841Z","end":"2026-04-09T06:14:30.481492Z","steps":["trace[793407138] 'process raft request' (duration: 382.088575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:30.481637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:30.098835Z","time spent":"382.753232ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T06:14:30.577311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.945104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T06:14:30.577346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.726275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:14:30.577389Z","caller":"traceutil/trace.go:171","msg":"trace[1280175181] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:8767; }","duration":"291.074998ms","start":"2026-04-09T06:14:30.286298Z","end":"2026-04-09T06:14:30.577373Z","steps":["trace[1280175181] 'agreement among raft nodes before linearized reading' (duration: 290.941114ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:30.577381Z","caller":"traceutil/trace.go:171","msg":"trace[942197343] transaction","detail":"{read_only:false; response_revision:8767; number_of_response:1; }","duration":"113.50048ms","start":"2026-04-09T06:14:30.463838Z","end":"2026-04-09T06:14:30.577339Z","steps":["trace[942197343] 'process raft request' (duration: 113.224903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:30.57744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.194182ms","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-04-09T06:14:30.577433Z","caller":"traceutil/trace.go:171","msg":"trace[1696384860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8767; }","duration":"258.860028ms","start":"2026-04-09T06:14:30.318552Z","end":"2026-04-09T06:14:30.577412Z","steps":["trace[1696384860] 'agreement among raft nodes before linearized reading' (duration: 258.669114ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:30.577475Z","caller":"traceutil/trace.go:171","msg":"trace[2063572731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8767; }","duration":"463.250154ms","start":"2026-04-09T06:14:30.114216Z","end":"2026-04-09T06:14:30.577466Z","steps":["trace[2063572731] 'agreement among raft nodes before linearized reading' (duration: 463.142651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:30.57744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.406886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-09T06:14:30.577499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:30.114206Z","time spent":"463.285744ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T06:14:30.577504Z","caller":"traceutil/trace.go:171","msg":"trace[1525741917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8767; }","duration":"465.501308ms","start":"2026-04-09T06:14:30.111995Z","end":"2026-04-09T06:14:30.577497Z","steps":["trace[1525741917] 'agreement among raft nodes before linearized reading' (duration: 465.364924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:30.577524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:30.111982Z","time spent":"465.536799ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T06:14:30.577506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.486848ms","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-04-09T06:14:30.577573Z","caller":"traceutil/trace.go:171","msg":"trace[19665461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8767; }","duration":"465.58931ms","start":"2026-04-09T06:14:30.111968Z","end":"2026-04-09T06:14:30.577557Z","steps":["trace[19665461] 'agreement among raft nodes before linearized reading' (duration: 465.319883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:30.57763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:30.111962Z","time spent":"465.654681ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T06:14:40.330411Z","caller":"traceutil/trace.go:171","msg":"trace[564055517] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"128.788734ms","start":"2026-04-09T06:14:40.201595Z","end":"2026-04-09T06:14:40.330384Z","steps":["trace[564055517] 'process raft request' (duration: 128.545708ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:40.391914Z","caller":"traceutil/trace.go:171","msg":"trace[1630284413] transaction","detail":"{read_only:false; response_revision:8861; number_of_response:1; }","duration":"148.303541ms","start":"2026-04-09T06:14:40.243558Z","end":"2026-04-09T06:14:40.391862Z","steps":["trace[1630284413] 'process raft request' (duration: 148.048845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:41.206445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.561535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:14:41.206531Z","caller":"traceutil/trace.go:171","msg":"trace[1289590640] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8873; }","duration":"259.706299ms","start":"2026-04-09T06:14:40.946807Z","end":"2026-04-09T06:14:41.206513Z","steps":["trace[1289590640] 'count revisions from in-memory index tree' (duration: 259.467883ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:41.522173Z","caller":"traceutil/trace.go:171","msg":"trace[52536584] transaction","detail":"{read_only:false; response_revision:8874; number_of_response:1; }","duration":"169.88641ms","start":"2026-04-09T06:14:41.352254Z","end":"2026-04-09T06:14:41.52214Z","steps":["trace[52536584] 'process raft request' (duration: 169.680706ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:50.941272Z","caller":"traceutil/trace.go:171","msg":"trace[363287151] transaction","detail":"{read_only:false; response_revision:8943; number_of_response:1; }","duration":"150.747681ms","start":"2026-04-09T06:14:50.790502Z","end":"2026-04-09T06:14:50.94125Z","steps":["trace[363287151] 'process raft request' (duration: 150.589967ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:50.941412Z","caller":"traceutil/trace.go:171","msg":"trace[880934363] transaction","detail":"{read_only:false; response_revision:8944; number_of_response:1; }","duration":"149.356147ms","start":"2026-04-09T06:14:50.792025Z","end":"2026-04-09T06:14:50.941381Z","steps":["trace[880934363] 'process raft request' (duration: 149.183133ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:51.615749Z","caller":"traceutil/trace.go:171","msg":"trace[677557876] linearizableReadLoop","detail":"{readStateIndex:9276; appliedIndex:9274; }","duration":"151.765006ms","start":"2026-04-09T06:14:51.46396Z","end":"2026-04-09T06:14:51.615725Z","steps":["trace[677557876] 'read index received' (duration: 70.075176ms)","trace[677557876] 'applied index is now lower than readState.Index' (duration: 81.68829ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:51.615768Z","caller":"traceutil/trace.go:171","msg":"trace[1071915062] transaction","detail":"{read_only:false; response_revision:8951; number_of_response:1; }","duration":"156.113833ms","start":"2026-04-09T06:14:51.459618Z","end":"2026-04-09T06:14:51.615731Z","steps":["trace[1071915062] 'process raft request' (duration: 146.846345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:51.61592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.9435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ceph-object-controller-detect-version-hjqvz\" ","response":"range_response_count:1 size:3408"} {"level":"info","ts":"2026-04-09T06:14:51.615951Z","caller":"traceutil/trace.go:171","msg":"trace[1643553443] range","detail":"{range_begin:/registry/pods/openstack/ceph-object-controller-detect-version-hjqvz; range_end:; response_count:1; response_revision:8951; }","duration":"152.007692ms","start":"2026-04-09T06:14:51.463934Z","end":"2026-04-09T06:14:51.615942Z","steps":["trace[1643553443] 'agreement among raft nodes before linearized reading' (duration: 151.864189ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:53.016278Z","caller":"traceutil/trace.go:171","msg":"trace[1979281236] transaction","detail":"{read_only:false; response_revision:8961; number_of_response:1; }","duration":"137.572149ms","start":"2026-04-09T06:14:52.878669Z","end":"2026-04-09T06:14:53.016241Z","steps":["trace[1979281236] 'process raft request' (duration: 137.134298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:54.23562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:53.906357Z","time spent":"329.224541ms","remote":"127.0.0.1:54328","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-09T06:14:54.281537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.595198ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T06:14:54.281671Z","caller":"traceutil/trace.go:171","msg":"trace[145470236] linearizableReadLoop","detail":"{readStateIndex:9293; appliedIndex:9292; }","duration":"563.906797ms","start":"2026-04-09T06:14:53.717743Z","end":"2026-04-09T06:14:54.28165Z","steps":["trace[145470236] 'read index received' (duration: 384.210817ms)","trace[145470236] 'applied index is now lower than readState.Index' (duration: 179.6945ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:14:54.281921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.170084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ceph-object-controller-detect-version-hjqvz\" ","response":"range_response_count:1 size:4559"} {"level":"info","ts":"2026-04-09T06:14:54.281965Z","caller":"traceutil/trace.go:171","msg":"trace[840014972] range","detail":"{range_begin:/registry/pods/openstack/ceph-object-controller-detect-version-hjqvz; range_end:; response_count:1; response_revision:8967; }","duration":"564.254886ms","start":"2026-04-09T06:14:53.717697Z","end":"2026-04-09T06:14:54.281951Z","steps":["trace[840014972] 'agreement among raft nodes before linearized reading' (duration: 564.078131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:54.282005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:53.717674Z","time spent":"564.320308ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":4582,"request content":"key:\"/registry/pods/openstack/ceph-object-controller-detect-version-hjqvz\" "} {"level":"warn","ts":"2026-04-09T06:14:54.282024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.882045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T06:14:54.282096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.414357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/ceph-mon\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T06:14:54.282163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.305226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-09T06:14:54.282328Z","caller":"traceutil/trace.go:171","msg":"trace[893402545] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8967; }","duration":"372.511571ms","start":"2026-04-09T06:14:53.909794Z","end":"2026-04-09T06:14:54.282306Z","steps":["trace[893402545] 'agreement among raft nodes before linearized reading' (duration: 372.152012ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:54.282145Z","caller":"traceutil/trace.go:171","msg":"trace[1721174467] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8967; }","duration":"394.064919ms","start":"2026-04-09T06:14:53.888056Z","end":"2026-04-09T06:14:54.282121Z","steps":["trace[1721174467] 'agreement among raft nodes before linearized reading' (duration: 393.873835ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:54.28227Z","caller":"traceutil/trace.go:171","msg":"trace[1421795083] range","detail":"{range_begin:/registry/services/endpoints/openstack/ceph-mon; range_end:; response_count:0; response_revision:8967; }","duration":"211.628572ms","start":"2026-04-09T06:14:54.07062Z","end":"2026-04-09T06:14:54.282248Z","steps":["trace[1421795083] 'agreement among raft nodes before linearized reading' (duration: 211.381247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:54.282381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:53.909779Z","time spent":"372.589303ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-09T06:14:54.28266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:14:53.888033Z","time spent":"394.344586ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-04-09T06:14:54.497771Z","caller":"traceutil/trace.go:171","msg":"trace[1672840335] linearizableReadLoop","detail":"{readStateIndex:9295; appliedIndex:9293; }","duration":"155.377845ms","start":"2026-04-09T06:14:54.342367Z","end":"2026-04-09T06:14:54.497745Z","steps":["trace[1672840335] 'read index received' (duration: 32.228189ms)","trace[1672840335] 'applied index is now lower than readState.Index' (duration: 123.148536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T06:14:54.497911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.522678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:14:54.497944Z","caller":"traceutil/trace.go:171","msg":"trace[178989626] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8969; }","duration":"155.606771ms","start":"2026-04-09T06:14:54.342327Z","end":"2026-04-09T06:14:54.497934Z","steps":["trace[178989626] 'agreement among raft nodes before linearized reading' (duration: 155.520249ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:54.497936Z","caller":"traceutil/trace.go:171","msg":"trace[1944220601] transaction","detail":"{read_only:false; response_revision:8969; number_of_response:1; }","duration":"206.61309ms","start":"2026-04-09T06:14:54.291279Z","end":"2026-04-09T06:14:54.497892Z","steps":["trace[1944220601] 'process raft request' (duration: 147.634376ms)","trace[1944220601] 'compare' (duration: 58.682546ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:54.500519Z","caller":"traceutil/trace.go:171","msg":"trace[204742460] transaction","detail":"{read_only:false; response_revision:8970; number_of_response:1; }","duration":"120.575713ms","start":"2026-04-09T06:14:54.379783Z","end":"2026-04-09T06:14:54.500359Z","steps":["trace[204742460] 'process raft request' (duration: 120.338136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:55.347423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.264796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ceph-object-controller-detect-version-hjqvz\" ","response":"range_response_count:1 size:4842"} {"level":"warn","ts":"2026-04-09T06:14:55.347396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.64579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/ceph-object-controller-detect-version\" ","response":"range_response_count:1 size:3161"} {"level":"info","ts":"2026-04-09T06:14:55.3475Z","caller":"traceutil/trace.go:171","msg":"trace[1413331533] range","detail":"{range_begin:/registry/pods/openstack/ceph-object-controller-detect-version-hjqvz; range_end:; response_count:1; response_revision:8985; }","duration":"158.372809ms","start":"2026-04-09T06:14:55.189109Z","end":"2026-04-09T06:14:55.347482Z","steps":["trace[1413331533] 'range keys from in-memory index tree' (duration: 158.135543ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:55.34751Z","caller":"traceutil/trace.go:171","msg":"trace[2076915367] range","detail":"{range_begin:/registry/jobs/openstack/ceph-object-controller-detect-version; range_end:; response_count:1; response_revision:8985; }","duration":"157.798544ms","start":"2026-04-09T06:14:55.189695Z","end":"2026-04-09T06:14:55.347493Z","steps":["trace[2076915367] 'range keys from in-memory index tree' (duration: 157.557758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:55.347591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.007487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/ceph-object-controller-detect-version\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-09T06:14:55.34769Z","caller":"traceutil/trace.go:171","msg":"trace[975255239] range","detail":"{range_begin:/registry/configmaps/openstack/ceph-object-controller-detect-version; range_end:; response_count:1; response_revision:8985; }","duration":"160.155611ms","start":"2026-04-09T06:14:55.187512Z","end":"2026-04-09T06:14:55.347668Z","steps":["trace[975255239] 'range keys from in-memory index tree' (duration: 159.907045ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:55.377663Z","caller":"traceutil/trace.go:171","msg":"trace[706927578] transaction","detail":"{read_only:false; response_revision:8986; number_of_response:1; }","duration":"184.516748ms","start":"2026-04-09T06:14:55.193114Z","end":"2026-04-09T06:14:55.37763Z","steps":["trace[706927578] 'process raft request' (duration: 184.127659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:55.474928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.019009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T06:14:55.474864Z","caller":"traceutil/trace.go:171","msg":"trace[731701600] transaction","detail":"{read_only:false; response_revision:8987; number_of_response:1; }","duration":"125.732369ms","start":"2026-04-09T06:14:55.349104Z","end":"2026-04-09T06:14:55.474836Z","steps":["trace[731701600] 'process raft request' (duration: 107.286288ms)","trace[731701600] 'compare' (duration: 18.251726ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:55.475017Z","caller":"traceutil/trace.go:171","msg":"trace[640089584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8987; }","duration":"158.145372ms","start":"2026-04-09T06:14:55.316852Z","end":"2026-04-09T06:14:55.474997Z","steps":["trace[640089584] 'agreement among raft nodes before linearized reading' (duration: 158.003439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:55.475258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.715044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/ceph-object-controller-detect-version\" ","response":"range_response_count:1 size:3161"} {"level":"info","ts":"2026-04-09T06:14:55.475351Z","caller":"traceutil/trace.go:171","msg":"trace[213218790] range","detail":"{range_begin:/registry/jobs/openstack/ceph-object-controller-detect-version; range_end:; response_count:1; response_revision:8987; }","duration":"124.835107ms","start":"2026-04-09T06:14:55.350489Z","end":"2026-04-09T06:14:55.475324Z","steps":["trace[213218790] 'agreement among raft nodes before linearized reading' (duration: 124.59056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:56.915005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.053104ms","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-04-09T06:14:56.915097Z","caller":"traceutil/trace.go:171","msg":"trace[125851804] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:9002; }","duration":"141.156216ms","start":"2026-04-09T06:14:56.773926Z","end":"2026-04-09T06:14:56.915082Z","steps":["trace[125851804] 'range keys from in-memory index tree' (duration: 141.038643ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:56.917425Z","caller":"traceutil/trace.go:171","msg":"trace[1483959981] linearizableReadLoop","detail":"{readStateIndex:9329; appliedIndex:9328; }","duration":"140.285036ms","start":"2026-04-09T06:14:56.77712Z","end":"2026-04-09T06:14:56.917405Z","steps":["trace[1483959981] 'read index received' (duration: 140.083711ms)","trace[1483959981] 'applied index is now lower than readState.Index' (duration: 200.194µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T06:14:56.917482Z","caller":"traceutil/trace.go:171","msg":"trace[1502662864] transaction","detail":"{read_only:false; response_revision:9003; number_of_response:1; }","duration":"156.402569ms","start":"2026-04-09T06:14:56.761047Z","end":"2026-04-09T06:14:56.91745Z","steps":["trace[1502662864] 'process raft request' (duration: 156.225704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:56.917617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.49104ms","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-04-09T06:14:56.917649Z","caller":"traceutil/trace.go:171","msg":"trace[1295435653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9003; }","duration":"140.556222ms","start":"2026-04-09T06:14:56.777083Z","end":"2026-04-09T06:14:56.91764Z","steps":["trace[1295435653] 'agreement among raft nodes before linearized reading' (duration: 140.435549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:14:56.917691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.956523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130299"} {"level":"info","ts":"2026-04-09T06:14:56.917747Z","caller":"traceutil/trace.go:171","msg":"trace[1614106670] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:9003; }","duration":"117.036575ms","start":"2026-04-09T06:14:56.800697Z","end":"2026-04-09T06:14:56.917734Z","steps":["trace[1614106670] 'agreement among raft nodes before linearized reading' (duration: 116.855111ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:14:57.62994Z","caller":"traceutil/trace.go:171","msg":"trace[1822209809] transaction","detail":"{read_only:false; response_revision:9016; number_of_response:1; }","duration":"130.761132ms","start":"2026-04-09T06:14:57.499156Z","end":"2026-04-09T06:14:57.629917Z","steps":["trace[1822209809] 'process raft request' (duration: 130.596108ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:15:00.750944Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249a.wal"} {"level":"info","ts":"2026-04-09T06:15:42.415876Z","caller":"traceutil/trace.go:171","msg":"trace[1636968025] transaction","detail":"{read_only:false; response_revision:9527; number_of_response:1; }","duration":"222.274384ms","start":"2026-04-09T06:15:42.193121Z","end":"2026-04-09T06:15:42.415395Z","steps":["trace[1636968025] 'process raft request' (duration: 222.049998ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:15:55.139852Z","caller":"traceutil/trace.go:171","msg":"trace[2001646314] transaction","detail":"{read_only:false; response_revision:9598; number_of_response:1; }","duration":"338.403656ms","start":"2026-04-09T06:15:54.801428Z","end":"2026-04-09T06:15:55.139832Z","steps":["trace[2001646314] 'process raft request' (duration: 338.204261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T06:15:55.140001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T06:15:54.801403Z","time spent":"338.513639ms","remote":"127.0.0.1:56878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T06:15:55.152267Z","caller":"traceutil/trace.go:171","msg":"trace[1767377006] transaction","detail":"{read_only:false; response_revision:9599; number_of_response:1; }","duration":"235.810743ms","start":"2026-04-09T06:15:54.916435Z","end":"2026-04-09T06:15:55.152245Z","steps":["trace[1767377006] 'process raft request' (duration: 235.625959ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:15:55.15258Z","caller":"traceutil/trace.go:171","msg":"trace[1782644527] transaction","detail":"{read_only:false; response_revision:9600; number_of_response:1; }","duration":"173.171629ms","start":"2026-04-09T06:15:54.979391Z","end":"2026-04-09T06:15:55.152563Z","steps":["trace[1782644527] 'process raft request' (duration: 172.80714ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:15:55.153005Z","caller":"traceutil/trace.go:171","msg":"trace[274286486] transaction","detail":"{read_only:false; response_revision:9601; number_of_response:1; }","duration":"156.652315ms","start":"2026-04-09T06:15:54.996333Z","end":"2026-04-09T06:15:55.152985Z","steps":["trace[274286486] 'process raft request' (duration: 156.315816ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:16:01.497366Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f52f30d5714009d5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T06:16:01.503227Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T06:16:01.503333Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T06:17:48.068691Z","caller":"traceutil/trace.go:171","msg":"trace[412169951] transaction","detail":"{read_only:false; response_revision:10409; number_of_response:1; }","duration":"104.877552ms","start":"2026-04-09T06:17:47.96377Z","end":"2026-04-09T06:17:48.068648Z","steps":["trace[412169951] 'process raft request' (duration: 104.696568ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T06:17:54.943768Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8013} {"level":"info","ts":"2026-04-09T06:17:55.012817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8013,"took":"66.237008ms","hash":100334802,"current-db-size-bytes":56557568,"current-db-size":"57 MB","current-db-size-in-use-bytes":28708864,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-09T06:17:55.012901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":100334802,"revision":8013,"compact-revision":5837} {"level":"info","ts":"2026-04-09T06:22:54.956857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10459} {"level":"info","ts":"2026-04-09T06:22:55.037959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10459,"took":"79.269697ms","hash":3215868890,"current-db-size-bytes":56557568,"current-db-size":"57 MB","current-db-size-in-use-bytes":21872640,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-09T06:22:55.038035Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3215868890,"revision":10459,"compact-revision":8013}