{"level":"warn","ts":"2026-09-09T06:14:04.962961Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:14:04.963082Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.167: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.167:2380","--initial-cluster=instance=https://162.253.55.167:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.167:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.167: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-09-09T06:14:04.963178Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:14:04.963199Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-09-09T06:14:04.963229Z","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-09-09T06:14:04.964098Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"]} {"level":"info","ts":"2026-09-09T06:14:04.964233Z","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.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.167: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-09-09T06:14:04.967426Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.901118ms"} {"level":"info","ts":"2026-09-09T06:14:04.973693Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"83287f3a81241ad4","cluster-id":"185ed9b8ed398b9a"} {"level":"info","ts":"2026-09-09T06:14:04.973789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T06:14:04.973812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 0"} {"level":"info","ts":"2026-09-09T06:14:04.973822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 83287f3a81241ad4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T06:14:04.973842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became follower at term 1"} {"level":"info","ts":"2026-09-09T06:14:04.973884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"warn","ts":"2026-09-09T06:14:04.979119Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T06:14:04.980955Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T06:14:04.982661Z","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-09-09T06:14:04.985378Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"83287f3a81241ad4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T06:14:04.985546Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"83287f3a81241ad4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T06:14:04.985567Z","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-09-09T06:14:04.985726Z","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-09-09T06:14:04.985744Z","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-09-09T06:14:04.989687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 switched to configuration voters=(9450943707288443604)"} {"level":"info","ts":"2026-09-09T06:14:04.990282Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","added-peer-id":"83287f3a81241ad4","added-peer-peer-urls":["https://162.253.55.167:2380"]} {"level":"info","ts":"2026-09-09T06:14:04.991743Z","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-09-09T06:14:04.991867Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-09-09T06:14:04.991983Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.167:2380"} {"level":"info","ts":"2026-09-09T06:14:04.992124Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"83287f3a81241ad4","initial-advertise-peer-urls":["https://162.253.55.167:2380"],"listen-peer-urls":["https://162.253.55.167:2380"],"advertise-client-urls":["https://162.253.55.167:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.167:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T06:14:04.992186Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T06:14:05.974624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T06:14:05.974705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T06:14:05.974752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgPreVoteResp from 83287f3a81241ad4 at term 1"} {"level":"info","ts":"2026-09-09T06:14:05.974777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became candidate at term 2"} {"level":"info","ts":"2026-09-09T06:14:05.974797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 received MsgVoteResp from 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-09-09T06:14:05.974858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"83287f3a81241ad4 became leader at term 2"} {"level":"info","ts":"2026-09-09T06:14:05.974889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 83287f3a81241ad4 elected leader 83287f3a81241ad4 at term 2"} {"level":"info","ts":"2026-09-09T06:14:05.976512Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:14:05.977675Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:14:05.977664Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"83287f3a81241ad4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.167:2379]}","request-path":"/0/members/83287f3a81241ad4/attributes","cluster-id":"185ed9b8ed398b9a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T06:14:05.977751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:14:05.978Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185ed9b8ed398b9a","local-member-id":"83287f3a81241ad4","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:14:05.978235Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:14:05.978168Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T06:14:05.978283Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:14:05.978323Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T06:14:05.981092Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.167:2379"} {"level":"info","ts":"2026-09-09T06:14:05.981343Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T06:14:24.25274Z","caller":"traceutil/trace.go:171","msg":"trace[1441737915] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"153.795069ms","start":"2026-09-09T06:14:24.09888Z","end":"2026-09-09T06:14:24.252675Z","steps":["trace[1441737915] 'read index received' (duration: 153.554424ms)","trace[1441737915] 'applied index is now lower than readState.Index' (duration: 239.555µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:14:24.252807Z","caller":"traceutil/trace.go:171","msg":"trace[1717176690] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"298.848949ms","start":"2026-09-09T06:14:23.953899Z","end":"2026-09-09T06:14:24.252748Z","steps":["trace[1717176690] 'process raft request' (duration: 298.614104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:24.253006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.102127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-09T06:14:24.253184Z","caller":"traceutil/trace.go:171","msg":"trace[1504016543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:374; }","duration":"154.355094ms","start":"2026-09-09T06:14:24.098804Z","end":"2026-09-09T06:14:24.25316Z","steps":["trace[1504016543] 'agreement among raft nodes before linearized reading' (duration: 154.048006ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:14:24.479731Z","caller":"traceutil/trace.go:171","msg":"trace[2127322831] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"210.580804ms","start":"2026-09-09T06:14:24.26912Z","end":"2026-09-09T06:14:24.4797Z","steps":["trace[2127322831] 'process raft request' (duration: 210.397459ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:14:49.092651Z","caller":"traceutil/trace.go:171","msg":"trace[843082308] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"103.481595ms","start":"2026-09-09T06:14:48.989135Z","end":"2026-09-09T06:14:49.092616Z","steps":["trace[843082308] 'read index received' (duration: 102.712087ms)","trace[843082308] 'applied index is now lower than readState.Index' (duration: 768.408µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:14:49.092767Z","caller":"traceutil/trace.go:171","msg":"trace[1993836936] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"188.723951ms","start":"2026-09-09T06:14:48.903996Z","end":"2026-09-09T06:14:49.09272Z","steps":["trace[1993836936] 'process raft request' (duration: 188.060886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:49.092923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.762342ms","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-09-09T06:14:49.092966Z","caller":"traceutil/trace.go:171","msg":"trace[860390122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"103.886975ms","start":"2026-09-09T06:14:48.989066Z","end":"2026-09-09T06:14:49.092953Z","steps":["trace[860390122] 'agreement among raft nodes before linearized reading' (duration: 103.705641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:49.511436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.6721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:14:49.511571Z","caller":"traceutil/trace.go:171","msg":"trace[2109523930] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"303.820204ms","start":"2026-09-09T06:14:49.207733Z","end":"2026-09-09T06:14:49.511553Z","steps":["trace[2109523930] 'read index received' (duration: 135.248444ms)","trace[2109523930] 'applied index is now lower than readState.Index' (duration: 168.57046ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:14:49.511699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.40765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:14:49.511748Z","caller":"traceutil/trace.go:171","msg":"trace[460289064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"254.504292ms","start":"2026-09-09T06:14:49.257231Z","end":"2026-09-09T06:14:49.511735Z","steps":["trace[460289064] 'agreement among raft nodes before linearized reading' (duration: 254.42651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:49.51174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.975967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:14:49.511845Z","caller":"traceutil/trace.go:171","msg":"trace[237146597] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:588; }","duration":"304.149011ms","start":"2026-09-09T06:14:49.207669Z","end":"2026-09-09T06:14:49.511818Z","steps":["trace[237146597] 'agreement among raft nodes before linearized reading' (duration: 303.951346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:49.511903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:14:49.207644Z","time spent":"304.249243ms","remote":"127.0.0.1:40002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"info","ts":"2026-09-09T06:14:49.511652Z","caller":"traceutil/trace.go:171","msg":"trace[1526108832] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"333.206398ms","start":"2026-09-09T06:14:49.178372Z","end":"2026-09-09T06:14:49.511579Z","steps":["trace[1526108832] 'process raft request' (duration: 164.743861ms)","trace[1526108832] 'compare' (duration: 167.524356ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:14:49.512231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:14:49.178345Z","time spent":"333.770751ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:14:49.512292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.625382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.167\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T06:14:49.512361Z","caller":"traceutil/trace.go:171","msg":"trace[1338765502] range","detail":"{range_begin:/registry/masterleases/162.253.55.167; range_end:; response_count:1; response_revision:588; }","duration":"236.752064ms","start":"2026-09-09T06:14:49.275586Z","end":"2026-09-09T06:14:49.512338Z","steps":["trace[1338765502] 'agreement among raft nodes before linearized reading' (duration: 236.610321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:50.067894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.522311ms","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-09-09T06:14:50.068121Z","caller":"traceutil/trace.go:171","msg":"trace[839886785] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:590; }","duration":"362.772767ms","start":"2026-09-09T06:14:49.705314Z","end":"2026-09-09T06:14:50.068087Z","steps":["trace[839886785] 'range keys from in-memory index tree' (duration: 362.491041ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:14:50.074518Z","caller":"traceutil/trace.go:171","msg":"trace[1827791218] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"158.453427ms","start":"2026-09-09T06:14:49.916047Z","end":"2026-09-09T06:14:50.0745Z","steps":["trace[1827791218] 'process raft request' (duration: 157.790382ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:15:35.523278Z","caller":"traceutil/trace.go:171","msg":"trace[964605027] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"124.456247ms","start":"2026-09-09T06:15:35.398789Z","end":"2026-09-09T06:15:35.523245Z","steps":["trace[964605027] 'process raft request' (duration: 124.285263ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:15:51.62464Z","caller":"traceutil/trace.go:171","msg":"trace[2074899766] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"121.635854ms","start":"2026-09-09T06:15:51.502975Z","end":"2026-09-09T06:15:51.62461Z","steps":["trace[2074899766] 'process raft request' (duration: 121.413699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:16:25.118693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:16:24.784421Z","time spent":"334.262907ms","remote":"127.0.0.1:56416","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-09T06:16:25.11911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.053788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:16:25.11926Z","caller":"traceutil/trace.go:171","msg":"trace[543551656] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"357.077089ms","start":"2026-09-09T06:16:24.762157Z","end":"2026-09-09T06:16:25.119234Z","steps":["trace[543551656] 'process raft request' (duration: 69.686584ms)","trace[543551656] 'compare' (duration: 286.867163ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:16:25.119376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:16:24.762128Z","time spent":"357.201272ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:16:25.125122Z","caller":"traceutil/trace.go:171","msg":"trace[746639454] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1916; }","duration":"173.739287ms","start":"2026-09-09T06:16:24.951357Z","end":"2026-09-09T06:16:25.125096Z","steps":["trace[746639454] 'read index received' (duration: 173.472681ms)","trace[746639454] 'applied index is now lower than readState.Index' (duration: 264.906µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:16:25.125197Z","caller":"traceutil/trace.go:171","msg":"trace[512684601] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"307.918973ms","start":"2026-09-09T06:16:24.817234Z","end":"2026-09-09T06:16:25.125153Z","steps":["trace[512684601] 'process raft request' (duration: 307.679508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:16:25.125338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.968723ms","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-09-09T06:16:25.125397Z","caller":"traceutil/trace.go:171","msg":"trace[1710979962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1846; }","duration":"174.099906ms","start":"2026-09-09T06:16:24.951283Z","end":"2026-09-09T06:16:25.125383Z","steps":["trace[1710979962] 'agreement among raft nodes before linearized reading' (duration: 173.902821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:16:25.125539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:16:24.817205Z","time spent":"308.096278ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:16:25.501303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.17314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:16:25.50161Z","caller":"traceutil/trace.go:171","msg":"trace[431578580] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"296.860556ms","start":"2026-09-09T06:16:25.204722Z","end":"2026-09-09T06:16:25.501583Z","steps":["trace[431578580] 'read index received' (duration: 88.088253ms)","trace[431578580] 'applied index is now lower than readState.Index' (duration: 208.769383ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:16:25.501665Z","caller":"traceutil/trace.go:171","msg":"trace[702936356] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"372.067428ms","start":"2026-09-09T06:16:25.129563Z","end":"2026-09-09T06:16:25.501631Z","steps":["trace[702936356] 'process raft request' (duration: 163.232113ms)","trace[702936356] 'compare' (duration: 207.839722ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:16:25.501848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:16:25.129542Z","time spent":"372.206331ms","remote":"127.0.0.1:39938","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-09-09T06:16:25.501946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.220734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db9d71fe-cf30-474f-b830-3809a029cbc8\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-09-09T06:16:25.502082Z","caller":"traceutil/trace.go:171","msg":"trace[1552236646] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db9d71fe-cf30-474f-b830-3809a029cbc8; range_end:; response_count:1; response_revision:1847; }","duration":"297.444059ms","start":"2026-09-09T06:16:25.204607Z","end":"2026-09-09T06:16:25.502051Z","steps":["trace[1552236646] 'agreement among raft nodes before linearized reading' (duration: 297.133262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:16:25.502373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.105428ms","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-09-09T06:16:25.502568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.821844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:16:25.502585Z","caller":"traceutil/trace.go:171","msg":"trace[551856294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1847; }","duration":"223.276982ms","start":"2026-09-09T06:16:25.279144Z","end":"2026-09-09T06:16:25.502421Z","steps":["trace[551856294] 'agreement among raft nodes before linearized reading' (duration: 222.77957ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:16:25.502649Z","caller":"traceutil/trace.go:171","msg":"trace[375386035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1847; }","duration":"244.925616ms","start":"2026-09-09T06:16:25.257696Z","end":"2026-09-09T06:16:25.502622Z","steps":["trace[375386035] 'agreement among raft nodes before linearized reading' (duration: 244.062685ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:16:38.001042Z","caller":"traceutil/trace.go:171","msg":"trace[1208000635] linearizableReadLoop","detail":"{readStateIndex:2023; appliedIndex:2022; }","duration":"150.021005ms","start":"2026-09-09T06:16:37.850978Z","end":"2026-09-09T06:16:38.000999Z","steps":["trace[1208000635] 'read index received' (duration: 53.432644ms)","trace[1208000635] 'applied index is now lower than readState.Index' (duration: 96.587091ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:16:38.001106Z","caller":"traceutil/trace.go:171","msg":"trace[1457353874] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"176.209844ms","start":"2026-09-09T06:16:37.824861Z","end":"2026-09-09T06:16:38.001071Z","steps":["trace[1457353874] 'process raft request' (duration: 79.473541ms)","trace[1457353874] 'compare' (duration: 96.543079ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:16:38.001311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.327183ms","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-09-09T06:16:38.001365Z","caller":"traceutil/trace.go:171","msg":"trace[119753071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1950; }","duration":"150.428355ms","start":"2026-09-09T06:16:37.85092Z","end":"2026-09-09T06:16:38.001349Z","steps":["trace[119753071] 'agreement among raft nodes before linearized reading' (duration: 150.274101ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:16:41.465886Z","caller":"traceutil/trace.go:171","msg":"trace[1907906404] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"172.243122ms","start":"2026-09-09T06:16:41.293613Z","end":"2026-09-09T06:16:41.465856Z","steps":["trace[1907906404] 'process raft request' (duration: 172.087459ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:20.370579Z","caller":"traceutil/trace.go:171","msg":"trace[1675081521] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"112.472499ms","start":"2026-09-09T06:17:20.258072Z","end":"2026-09-09T06:17:20.370545Z","steps":["trace[1675081521] 'read index received' (duration: 112.145061ms)","trace[1675081521] 'applied index is now lower than readState.Index' (duration: 325.928µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:17:20.370767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.673953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:17:20.370878Z","caller":"traceutil/trace.go:171","msg":"trace[772946657] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"218.303021ms","start":"2026-09-09T06:17:20.152554Z","end":"2026-09-09T06:17:20.370857Z","steps":["trace[772946657] 'process raft request' (duration: 217.749647ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:20.370877Z","caller":"traceutil/trace.go:171","msg":"trace[1601735713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2193; }","duration":"112.882579ms","start":"2026-09-09T06:17:20.257978Z","end":"2026-09-09T06:17:20.370861Z","steps":["trace[1601735713] 'agreement among raft nodes before linearized reading' (duration: 112.720814ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:20.805534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.285565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:17:20.805766Z","caller":"traceutil/trace.go:171","msg":"trace[2134149465] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"251.565965ms","start":"2026-09-09T06:17:20.554177Z","end":"2026-09-09T06:17:20.805743Z","steps":["trace[2134149465] 'process raft request' (duration: 251.502833ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:20.80586Z","caller":"traceutil/trace.go:171","msg":"trace[1216680479] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"419.667773ms","start":"2026-09-09T06:17:20.386151Z","end":"2026-09-09T06:17:20.805819Z","steps":["trace[1216680479] 'process raft request' (duration: 165.976659ms)","trace[1216680479] 'compare' (duration: 253.127901ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:20.806115Z","caller":"traceutil/trace.go:171","msg":"trace[1905751762] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2276; }","duration":"419.095909ms","start":"2026-09-09T06:17:20.386989Z","end":"2026-09-09T06:17:20.806085Z","steps":["trace[1905751762] 'read index received' (duration: 165.076778ms)","trace[1905751762] 'applied index is now lower than readState.Index' (duration: 254.015021ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:17:20.806182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:20.386124Z","time spent":"419.818237ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:17:20.806555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.018029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:17:20.806607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.355752ms","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-09-09T06:17:20.806647Z","caller":"traceutil/trace.go:171","msg":"trace[656235109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2195; }","duration":"418.428624ms","start":"2026-09-09T06:17:20.388208Z","end":"2026-09-09T06:17:20.806637Z","steps":["trace[656235109] 'agreement among raft nodes before linearized reading' (duration: 418.190149ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:20.806658Z","caller":"traceutil/trace.go:171","msg":"trace[60425726] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2195; }","duration":"363.157663ms","start":"2026-09-09T06:17:20.443475Z","end":"2026-09-09T06:17:20.806633Z","steps":["trace[60425726] 'agreement among raft nodes before linearized reading' (duration: 362.964989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:20.806681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:20.38819Z","time spent":"418.483355ms","remote":"127.0.0.1:39938","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-09-09T06:17:20.806576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.53054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-09T06:17:20.806712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:20.443461Z","time spent":"363.234434ms","remote":"127.0.0.1:46210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-09-09T06:17:20.806758Z","caller":"traceutil/trace.go:171","msg":"trace[882265196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2195; }","duration":"419.770725ms","start":"2026-09-09T06:17:20.386965Z","end":"2026-09-09T06:17:20.806736Z","steps":["trace[882265196] 'agreement among raft nodes before linearized reading' (duration: 419.236602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:17:20.80716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:17:20.386954Z","time spent":"419.893149ms","remote":"127.0.0.1:39938","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-09-09T06:17:20.953017Z","caller":"traceutil/trace.go:171","msg":"trace[1502658349] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"140.380916ms","start":"2026-09-09T06:17:20.8126Z","end":"2026-09-09T06:17:20.952981Z","steps":["trace[1502658349] 'process raft request' (duration: 139.188458ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:55.239841Z","caller":"traceutil/trace.go:171","msg":"trace[1399709375] linearizableReadLoop","detail":"{readStateIndex:2655; appliedIndex:2654; }","duration":"112.308025ms","start":"2026-09-09T06:17:55.127511Z","end":"2026-09-09T06:17:55.239819Z","steps":["trace[1399709375] 'read index received' (duration: 72.118089ms)","trace[1399709375] 'applied index is now lower than readState.Index' (duration: 40.188826ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:17:55.239967Z","caller":"traceutil/trace.go:171","msg":"trace[923848647] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"121.215595ms","start":"2026-09-09T06:17:55.118731Z","end":"2026-09-09T06:17:55.239947Z","steps":["trace[923848647] 'process raft request' (duration: 80.890865ms)","trace[923848647] 'compare' (duration: 40.023312ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:17:55.240301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.769206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-09T06:17:55.240381Z","caller":"traceutil/trace.go:171","msg":"trace[875480130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2559; }","duration":"112.897089ms","start":"2026-09-09T06:17:55.127465Z","end":"2026-09-09T06:17:55.240362Z","steps":["trace[875480130] 'agreement among raft nodes before linearized reading' (duration: 112.459929ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:18:28.785563Z","caller":"traceutil/trace.go:171","msg":"trace[2006057465] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"146.152512ms","start":"2026-09-09T06:18:28.639382Z","end":"2026-09-09T06:18:28.785535Z","steps":["trace[2006057465] 'process raft request' (duration: 145.999698ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:18:29.213169Z","caller":"traceutil/trace.go:171","msg":"trace[499916460] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"139.408073ms","start":"2026-09-09T06:18:29.073724Z","end":"2026-09-09T06:18:29.213132Z","steps":["trace[499916460] 'process raft request' (duration: 139.123887ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:18:29.437376Z","caller":"traceutil/trace.go:171","msg":"trace[1410597988] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"219.418518ms","start":"2026-09-09T06:18:29.217907Z","end":"2026-09-09T06:18:29.437326Z","steps":["trace[1410597988] 'process raft request' (duration: 172.078513ms)","trace[1410597988] 'compare' (duration: 46.861833ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:18:29.437639Z","caller":"traceutil/trace.go:171","msg":"trace[2018846273] linearizableReadLoop","detail":"{readStateIndex:2867; appliedIndex:2866; }","duration":"198.049543ms","start":"2026-09-09T06:18:29.239444Z","end":"2026-09-09T06:18:29.437493Z","steps":["trace[2018846273] 'read index received' (duration: 150.557545ms)","trace[2018846273] 'applied index is now lower than readState.Index' (duration: 47.487188ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:18:29.437825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.351631ms","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-09-09T06:18:29.437927Z","caller":"traceutil/trace.go:171","msg":"trace[1393032125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2764; }","duration":"198.527636ms","start":"2026-09-09T06:18:29.239374Z","end":"2026-09-09T06:18:29.437901Z","steps":["trace[1393032125] 'agreement among raft nodes before linearized reading' (duration: 198.25823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:18:29.438728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.790943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:18:29.438793Z","caller":"traceutil/trace.go:171","msg":"trace[1625314319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2765; }","duration":"176.902526ms","start":"2026-09-09T06:18:29.261874Z","end":"2026-09-09T06:18:29.438776Z","steps":["trace[1625314319] 'agreement among raft nodes before linearized reading' (duration: 176.774983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:18:29.439168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.678775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.167\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-09T06:18:29.439232Z","caller":"traceutil/trace.go:171","msg":"trace[190691962] range","detail":"{range_begin:/registry/masterleases/162.253.55.167; range_end:; response_count:1; response_revision:2765; }","duration":"152.765788ms","start":"2026-09-09T06:18:29.286451Z","end":"2026-09-09T06:18:29.439217Z","steps":["trace[190691962] 'agreement among raft nodes before linearized reading' (duration: 152.516372ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:15.605686Z","caller":"traceutil/trace.go:171","msg":"trace[1481838327] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"343.88152ms","start":"2026-09-09T06:23:15.261784Z","end":"2026-09-09T06:23:15.605665Z","steps":["trace[1481838327] 'process raft request' (duration: 343.738627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:15.60593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:23:15.261761Z","time spent":"344.078335ms","remote":"127.0.0.1:39938","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-09-09T06:23:15.632736Z","caller":"traceutil/trace.go:171","msg":"trace[2146403923] linearizableReadLoop","detail":"{readStateIndex:5374; appliedIndex:5371; }","duration":"127.610783ms","start":"2026-09-09T06:23:15.505101Z","end":"2026-09-09T06:23:15.632712Z","steps":["trace[2146403923] 'read index received' (duration: 100.429628ms)","trace[2146403923] 'applied index is now lower than readState.Index' (duration: 27.179825ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:23:15.632805Z","caller":"traceutil/trace.go:171","msg":"trace[894521125] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"317.28312ms","start":"2026-09-09T06:23:15.315491Z","end":"2026-09-09T06:23:15.632774Z","steps":["trace[894521125] 'process raft request' (duration: 317.005763ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:15.632879Z","caller":"traceutil/trace.go:171","msg":"trace[700914364] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"290.809552ms","start":"2026-09-09T06:23:15.341999Z","end":"2026-09-09T06:23:15.632809Z","steps":["trace[700914364] 'process raft request' (duration: 290.618948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:15.632986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:23:15.315467Z","time spent":"317.433033ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2899,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-09T06:23:15.633505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.3975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728959"} {"level":"info","ts":"2026-09-09T06:23:15.633601Z","caller":"traceutil/trace.go:171","msg":"trace[729198283] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5180; }","duration":"128.572955ms","start":"2026-09-09T06:23:15.505014Z","end":"2026-09-09T06:23:15.633587Z","steps":["trace[729198283] 'agreement among raft nodes before linearized reading' (duration: 127.964331ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:17.167146Z","caller":"traceutil/trace.go:171","msg":"trace[2127951595] linearizableReadLoop","detail":"{readStateIndex:5407; appliedIndex:5406; }","duration":"176.078915ms","start":"2026-09-09T06:23:16.991029Z","end":"2026-09-09T06:23:17.167108Z","steps":["trace[2127951595] 'read index received' (duration: 154.142921ms)","trace[2127951595] 'applied index is now lower than readState.Index' (duration: 21.934383ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:23:17.167319Z","caller":"traceutil/trace.go:171","msg":"trace[176200369] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"189.188257ms","start":"2026-09-09T06:23:16.978111Z","end":"2026-09-09T06:23:17.167299Z","steps":["trace[176200369] 'process raft request' (duration: 167.14272ms)","trace[176200369] 'compare' (duration: 21.532074ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:23:17.167691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.671582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T06:23:17.167785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.223995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4cefe85f-234f-4115-8e29-31d69fcbb80e\" ","response":"range_response_count:1 size:3891"} {"level":"info","ts":"2026-09-09T06:23:17.167786Z","caller":"traceutil/trace.go:171","msg":"trace[945262819] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:5212; }","duration":"113.809965ms","start":"2026-09-09T06:23:17.053951Z","end":"2026-09-09T06:23:17.167761Z","steps":["trace[945262819] 'agreement among raft nodes before linearized reading' (duration: 113.622191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:17.167874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.322013ms","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-09-09T06:23:17.167889Z","caller":"traceutil/trace.go:171","msg":"trace[1933127822] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4cefe85f-234f-4115-8e29-31d69fcbb80e; range_end:; response_count:1; response_revision:5212; }","duration":"158.374548ms","start":"2026-09-09T06:23:17.009492Z","end":"2026-09-09T06:23:17.167867Z","steps":["trace[1933127822] 'agreement among raft nodes before linearized reading' (duration: 158.097782ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:17.167915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.008561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787144"} {"level":"info","ts":"2026-09-09T06:23:17.167938Z","caller":"traceutil/trace.go:171","msg":"trace[1700647598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5212; }","duration":"163.422925ms","start":"2026-09-09T06:23:17.004499Z","end":"2026-09-09T06:23:17.167922Z","steps":["trace[1700647598] 'agreement among raft nodes before linearized reading' (duration: 163.255851ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:17.167968Z","caller":"traceutil/trace.go:171","msg":"trace[154159047] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5212; }","duration":"165.065172ms","start":"2026-09-09T06:23:17.002889Z","end":"2026-09-09T06:23:17.167954Z","steps":["trace[154159047] 'agreement among raft nodes before linearized reading' (duration: 164.50509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:17.168154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.106368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-rb79c-tls\" ","response":"range_response_count:1 size:1967"} {"level":"info","ts":"2026-09-09T06:23:17.168225Z","caller":"traceutil/trace.go:171","msg":"trace[1301932722] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-rb79c-tls; range_end:; response_count:1; response_revision:5212; }","duration":"177.224951ms","start":"2026-09-09T06:23:16.990984Z","end":"2026-09-09T06:23:17.168209Z","steps":["trace[1301932722] 'agreement among raft nodes before linearized reading' (duration: 176.968205ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:30.783487Z","caller":"traceutil/trace.go:171","msg":"trace[53108546] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"238.436278ms","start":"2026-09-09T06:23:30.545025Z","end":"2026-09-09T06:23:30.783461Z","steps":["trace[53108546] 'process raft request' (duration: 238.196323ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:30.937995Z","caller":"traceutil/trace.go:171","msg":"trace[1600075198] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"335.714104ms","start":"2026-09-09T06:23:30.602252Z","end":"2026-09-09T06:23:30.937966Z","steps":["trace[1600075198] 'process raft request' (duration: 330.470933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:30.938136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:23:30.602231Z","time spent":"335.829887ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:23:30.942909Z","caller":"traceutil/trace.go:171","msg":"trace[17547725] linearizableReadLoop","detail":"{readStateIndex:5652; appliedIndex:5650; }","duration":"126.824183ms","start":"2026-09-09T06:23:30.816069Z","end":"2026-09-09T06:23:30.942893Z","steps":["trace[17547725] 'read index received' (duration: 116.778482ms)","trace[17547725] 'applied index is now lower than readState.Index' (duration: 10.044961ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:23:30.942977Z","caller":"traceutil/trace.go:171","msg":"trace[1248738489] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"157.860356ms","start":"2026-09-09T06:23:30.785086Z","end":"2026-09-09T06:23:30.942947Z","steps":["trace[1248738489] 'process raft request' (duration: 157.723983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:30.943043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.962116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:23:30.943077Z","caller":"traceutil/trace.go:171","msg":"trace[1684528487] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5449; }","duration":"127.041178ms","start":"2026-09-09T06:23:30.816026Z","end":"2026-09-09T06:23:30.943067Z","steps":["trace[1684528487] 'agreement among raft nodes before linearized reading' (duration: 126.955386ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:37.793195Z","caller":"traceutil/trace.go:171","msg":"trace[1424380809] transaction","detail":"{read_only:false; response_revision:5549; number_of_response:1; }","duration":"117.852787ms","start":"2026-09-09T06:23:37.675315Z","end":"2026-09-09T06:23:37.793168Z","steps":["trace[1424380809] 'process raft request' (duration: 117.672093ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:39.305426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.420178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:23:39.305542Z","caller":"traceutil/trace.go:171","msg":"trace[417519246] linearizableReadLoop","detail":"{readStateIndex:5821; appliedIndex:5820; }","duration":"201.113621ms","start":"2026-09-09T06:23:39.10441Z","end":"2026-09-09T06:23:39.305524Z","steps":["trace[417519246] 'read index received' (duration: 29.437816ms)","trace[417519246] 'applied index is now lower than readState.Index' (duration: 171.674404ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:23:39.305679Z","caller":"traceutil/trace.go:171","msg":"trace[1181720196] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"201.441348ms","start":"2026-09-09T06:23:39.104204Z","end":"2026-09-09T06:23:39.305645Z","steps":["trace[1181720196] 'process raft request' (duration: 29.700953ms)","trace[1181720196] 'compare' (duration: 171.133942ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:23:39.306088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.672384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-rb79c\" ","response":"range_response_count:1 size:9769"} {"level":"info","ts":"2026-09-09T06:23:39.306138Z","caller":"traceutil/trace.go:171","msg":"trace[2071517557] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-rb79c; range_end:; response_count:1; response_revision:5617; }","duration":"201.781127ms","start":"2026-09-09T06:23:39.104344Z","end":"2026-09-09T06:23:39.306125Z","steps":["trace[2071517557] 'agreement among raft nodes before linearized reading' (duration: 201.664223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:39.587343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.748063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:23:39.587576Z","caller":"traceutil/trace.go:171","msg":"trace[1535062249] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"277.139408ms","start":"2026-09-09T06:23:39.310417Z","end":"2026-09-09T06:23:39.587557Z","steps":["trace[1535062249] 'process raft request' (duration: 146.107827ms)","trace[1535062249] 'compare' (duration: 130.5784ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:23:39.587644Z","caller":"traceutil/trace.go:171","msg":"trace[84186696] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"270.818362ms","start":"2026-09-09T06:23:39.316806Z","end":"2026-09-09T06:23:39.587625Z","steps":["trace[84186696] 'process raft request' (duration: 270.761031ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:39.587654Z","caller":"traceutil/trace.go:171","msg":"trace[664064072] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"276.529194ms","start":"2026-09-09T06:23:39.311096Z","end":"2026-09-09T06:23:39.587625Z","steps":["trace[664064072] 'process raft request' (duration: 276.399101ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:39.620799Z","caller":"traceutil/trace.go:171","msg":"trace[1910615126] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"275.837128ms","start":"2026-09-09T06:23:39.344949Z","end":"2026-09-09T06:23:39.620786Z","steps":["trace[1910615126] 'process raft request' (duration: 275.748026ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:39.766839Z","caller":"traceutil/trace.go:171","msg":"trace[1058719491] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"200.350644ms","start":"2026-09-09T06:23:39.566462Z","end":"2026-09-09T06:23:39.766813Z","steps":["trace[1058719491] 'process raft request' (duration: 187.357035ms)","trace[1058719491] 'compare' (duration: 12.716202ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:23:39.766929Z","caller":"traceutil/trace.go:171","msg":"trace[1428735959] linearizableReadLoop","detail":"{readStateIndex:5830; appliedIndex:5826; }","duration":"144.359306ms","start":"2026-09-09T06:23:39.622551Z","end":"2026-09-09T06:23:39.76691Z","steps":["trace[1428735959] 'read index received' (duration: 131.174993ms)","trace[1428735959] 'applied index is now lower than readState.Index' (duration: 13.183303ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:23:39.767192Z","caller":"traceutil/trace.go:171","msg":"trace[1784615991] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"174.176672ms","start":"2026-09-09T06:23:39.592996Z","end":"2026-09-09T06:23:39.767173Z","steps":["trace[1784615991] 'process raft request' (duration: 173.705721ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:39.767206Z","caller":"traceutil/trace.go:171","msg":"trace[161972652] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"167.382557ms","start":"2026-09-09T06:23:39.599799Z","end":"2026-09-09T06:23:39.767182Z","steps":["trace[161972652] 'process raft request' (duration: 167.048079ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:39.767262Z","caller":"traceutil/trace.go:171","msg":"trace[1477148776] transaction","detail":"{read_only:false; response_revision:5624; number_of_response:1; }","duration":"172.38314ms","start":"2026-09-09T06:23:39.594865Z","end":"2026-09-09T06:23:39.767248Z","steps":["trace[1477148776] 'process raft request' (duration: 171.907849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:23:39.767429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.863818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-09T06:23:39.767474Z","caller":"traceutil/trace.go:171","msg":"trace[702187151] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5625; }","duration":"144.942591ms","start":"2026-09-09T06:23:39.622521Z","end":"2026-09-09T06:23:39.767464Z","steps":["trace[702187151] 'agreement among raft nodes before linearized reading' (duration: 144.831428ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:24:06.01811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2965} {"level":"info","ts":"2026-09-09T06:24:06.099941Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2965,"took":"80.453708ms","hash":3014522300,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":32714752,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-09T06:24:06.100014Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3014522300,"revision":2965,"compact-revision":-1} {"level":"info","ts":"2026-09-09T06:29:06.029092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5897} {"level":"info","ts":"2026-09-09T06:29:06.137316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5897,"took":"104.331257ms","hash":3996603775,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":26193920,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T06:29:06.137399Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3996603775,"revision":5897,"compact-revision":2965} {"level":"info","ts":"2026-09-09T06:30:11.475338Z","caller":"traceutil/trace.go:171","msg":"trace[688665465] linearizableReadLoop","detail":"{readStateIndex:8891; appliedIndex:8890; }","duration":"267.513398ms","start":"2026-09-09T06:30:11.207806Z","end":"2026-09-09T06:30:11.475319Z","steps":["trace[688665465] 'read index received' (duration: 267.406895ms)","trace[688665465] 'applied index is now lower than readState.Index' (duration: 105.563µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:30:11.475476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.648032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:30:11.475443Z","caller":"traceutil/trace.go:171","msg":"trace[536014667] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"357.66768ms","start":"2026-09-09T06:30:11.117731Z","end":"2026-09-09T06:30:11.475399Z","steps":["trace[536014667] 'process raft request' (duration: 357.450725ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:30:11.475513Z","caller":"traceutil/trace.go:171","msg":"trace[1870301658] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:8590; }","duration":"267.725324ms","start":"2026-09-09T06:30:11.207775Z","end":"2026-09-09T06:30:11.475501Z","steps":["trace[1870301658] 'agreement among raft nodes before linearized reading' (duration: 267.653312ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:30:11.475671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:30:11.117703Z","time spent":"357.844935ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:30:11.478031Z","caller":"traceutil/trace.go:171","msg":"trace[2110311906] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"212.530993ms","start":"2026-09-09T06:30:11.26547Z","end":"2026-09-09T06:30:11.478001Z","steps":["trace[2110311906] 'process raft request' (duration: 212.350629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:30:11.478043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.67431ms","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-09-09T06:30:11.478099Z","caller":"traceutil/trace.go:171","msg":"trace[838498722] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8591; }","duration":"236.761322ms","start":"2026-09-09T06:30:11.241322Z","end":"2026-09-09T06:30:11.478083Z","steps":["trace[838498722] 'agreement among raft nodes before linearized reading' (duration: 236.639958ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:30:12.458487Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022c8.wal"} {"level":"info","ts":"2026-09-09T06:30:24.11521Z","caller":"traceutil/trace.go:171","msg":"trace[1690651163] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"115.313749ms","start":"2026-09-09T06:30:23.999867Z","end":"2026-09-09T06:30:24.115181Z","steps":["trace[1690651163] 'process raft request' (duration: 81.167905ms)","trace[1690651163] 'compare' (duration: 34.031662ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:30:52.574197Z","caller":"traceutil/trace.go:171","msg":"trace[603390143] linearizableReadLoop","detail":"{readStateIndex:9179; appliedIndex:9178; }","duration":"317.506887ms","start":"2026-09-09T06:30:52.256668Z","end":"2026-09-09T06:30:52.574175Z","steps":["trace[603390143] 'read index received' (duration: 317.289703ms)","trace[603390143] 'applied index is now lower than readState.Index' (duration: 216.144µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:30:52.574387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.707113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:30:52.574462Z","caller":"traceutil/trace.go:171","msg":"trace[1290526837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8867; }","duration":"317.846255ms","start":"2026-09-09T06:30:52.256593Z","end":"2026-09-09T06:30:52.57444Z","steps":["trace[1290526837] 'agreement among raft nodes before linearized reading' (duration: 317.727573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:30:52.57449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.368163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5688fb977-xnzzb.18d393452e56548b\" ","response":"range_response_count:1 size:1132"} {"level":"warn","ts":"2026-09-09T06:30:52.574515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:30:52.256574Z","time spent":"317.929147ms","remote":"127.0.0.1:39656","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-09-09T06:30:52.574538Z","caller":"traceutil/trace.go:171","msg":"trace[41827796] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5688fb977-xnzzb.18d393452e56548b; range_end:; response_count:1; response_revision:8867; }","duration":"237.461035ms","start":"2026-09-09T06:30:52.33706Z","end":"2026-09-09T06:30:52.574521Z","steps":["trace[41827796] 'agreement among raft nodes before linearized reading' (duration: 237.261031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:30:52.574672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.164945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rcf8g\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-09T06:30:52.574772Z","caller":"traceutil/trace.go:171","msg":"trace[2052263668] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rcf8g; range_end:; response_count:1; response_revision:8867; }","duration":"123.317228ms","start":"2026-09-09T06:30:52.451439Z","end":"2026-09-09T06:30:52.574756Z","steps":["trace[2052263668] 'agreement among raft nodes before linearized reading' (duration: 122.91817ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:30:52.574297Z","caller":"traceutil/trace.go:171","msg":"trace[1737136080] transaction","detail":"{read_only:false; response_revision:8867; number_of_response:1; }","duration":"336.257799ms","start":"2026-09-09T06:30:52.238006Z","end":"2026-09-09T06:30:52.574264Z","steps":["trace[1737136080] 'process raft request' (duration: 336.016204ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:30:52.57553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:30:52.23799Z","time spent":"337.430436ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:30:52.854647Z","caller":"traceutil/trace.go:171","msg":"trace[1765374285] linearizableReadLoop","detail":"{readStateIndex:9180; appliedIndex:9179; }","duration":"276.078484ms","start":"2026-09-09T06:30:52.578513Z","end":"2026-09-09T06:30:52.854591Z","steps":["trace[1765374285] 'read index received' (duration: 275.922681ms)","trace[1765374285] 'applied index is now lower than readState.Index' (duration: 154.343µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:30:52.854733Z","caller":"traceutil/trace.go:171","msg":"trace[201620606] transaction","detail":"{read_only:false; response_revision:8868; number_of_response:1; }","duration":"278.236044ms","start":"2026-09-09T06:30:52.57646Z","end":"2026-09-09T06:30:52.854696Z","steps":["trace[201620606] 'process raft request' (duration: 277.911896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:30:52.855356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.74914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228873"} {"level":"info","ts":"2026-09-09T06:30:52.855459Z","caller":"traceutil/trace.go:171","msg":"trace[1692773362] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8868; }","duration":"276.944984ms","start":"2026-09-09T06:30:52.578497Z","end":"2026-09-09T06:30:52.855442Z","steps":["trace[1692773362] 'agreement among raft nodes before linearized reading' (duration: 276.232997ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:30:52.85583Z","caller":"traceutil/trace.go:171","msg":"trace[1589266214] transaction","detail":"{read_only:false; response_revision:8870; number_of_response:1; }","duration":"106.801187ms","start":"2026-09-09T06:30:52.749008Z","end":"2026-09-09T06:30:52.855809Z","steps":["trace[1589266214] 'process raft request' (duration: 106.739286ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:30:52.855899Z","caller":"traceutil/trace.go:171","msg":"trace[2061312722] transaction","detail":"{read_only:false; response_revision:8869; number_of_response:1; }","duration":"268.426209ms","start":"2026-09-09T06:30:52.587455Z","end":"2026-09-09T06:30:52.855881Z","steps":["trace[2061312722] 'process raft request' (duration: 268.062261ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:30:55.781851Z","caller":"traceutil/trace.go:171","msg":"trace[1109097217] transaction","detail":"{read_only:false; response_revision:8880; number_of_response:1; }","duration":"104.531206ms","start":"2026-09-09T06:30:55.677291Z","end":"2026-09-09T06:30:55.781822Z","steps":["trace[1109097217] 'process raft request' (duration: 104.358642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:30:56.044407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.165311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:30:56.044491Z","caller":"traceutil/trace.go:171","msg":"trace[1852914891] linearizableReadLoop","detail":"{readStateIndex:9194; appliedIndex:9193; }","duration":"232.959763ms","start":"2026-09-09T06:30:55.811517Z","end":"2026-09-09T06:30:56.044477Z","steps":["trace[1852914891] 'read index received' (duration: 57.655277ms)","trace[1852914891] 'applied index is now lower than readState.Index' (duration: 175.303055ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:30:56.044564Z","caller":"traceutil/trace.go:171","msg":"trace[1349492900] transaction","detail":"{read_only:false; response_revision:8881; number_of_response:1; }","duration":"307.103768ms","start":"2026-09-09T06:30:55.737451Z","end":"2026-09-09T06:30:56.044555Z","steps":["trace[1349492900] 'process raft request' (duration: 131.707441ms)","trace[1349492900] 'compare' (duration: 174.960227ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:30:56.04461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:30:55.737429Z","time spent":"307.1587ms","remote":"127.0.0.1:39938","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-09-09T06:30:56.044801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.279939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-09T06:30:56.044875Z","caller":"traceutil/trace.go:171","msg":"trace[1803000359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8881; }","duration":"233.387172ms","start":"2026-09-09T06:30:55.81147Z","end":"2026-09-09T06:30:56.044857Z","steps":["trace[1803000359] 'agreement among raft nodes before linearized reading' (duration: 233.137186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:30:56.044883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.974261ms","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-09-09T06:30:56.044983Z","caller":"traceutil/trace.go:171","msg":"trace[1220491434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8881; }","duration":"153.126923ms","start":"2026-09-09T06:30:55.891838Z","end":"2026-09-09T06:30:56.044965Z","steps":["trace[1220491434] 'agreement among raft nodes before linearized reading' (duration: 152.859857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:30:56.045016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.709147ms","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-09-09T06:30:56.04509Z","caller":"traceutil/trace.go:171","msg":"trace[1538863096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8881; }","duration":"123.825709ms","start":"2026-09-09T06:30:55.921246Z","end":"2026-09-09T06:30:56.045072Z","steps":["trace[1538863096] 'agreement among raft nodes before linearized reading' (duration: 123.630724ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:30:56.176244Z","caller":"traceutil/trace.go:171","msg":"trace[251196186] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"126.735468ms","start":"2026-09-09T06:30:56.049482Z","end":"2026-09-09T06:30:56.176217Z","steps":["trace[251196186] 'process raft request' (duration: 126.43396ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:30:56.176329Z","caller":"traceutil/trace.go:171","msg":"trace[682120040] linearizableReadLoop","detail":"{readStateIndex:9197; appliedIndex:9194; }","duration":"106.145573ms","start":"2026-09-09T06:30:56.070167Z","end":"2026-09-09T06:30:56.176312Z","steps":["trace[682120040] 'read index received' (duration: 9.18431ms)","trace[682120040] 'applied index is now lower than readState.Index' (duration: 96.960193ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:30:56.176468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.294287ms","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-09-09T06:30:56.176469Z","caller":"traceutil/trace.go:171","msg":"trace[1373609172] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"126.994404ms","start":"2026-09-09T06:30:56.049442Z","end":"2026-09-09T06:30:56.176436Z","steps":["trace[1373609172] 'process raft request' (duration: 95.416046ms)","trace[1373609172] 'compare' (duration: 30.929712ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:30:56.176501Z","caller":"traceutil/trace.go:171","msg":"trace[886067080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8885; }","duration":"106.370338ms","start":"2026-09-09T06:30:56.070121Z","end":"2026-09-09T06:30:56.176491Z","steps":["trace[886067080] 'agreement among raft nodes before linearized reading' (duration: 106.251635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:31:04.236956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.868615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:31:04.237034Z","caller":"traceutil/trace.go:171","msg":"trace[29954681] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:8925; }","duration":"217.978767ms","start":"2026-09-09T06:31:04.019039Z","end":"2026-09-09T06:31:04.237018Z","steps":["trace[29954681] 'count revisions from in-memory index tree' (duration: 217.710382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:31:04.237199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.584055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:31:04.237385Z","caller":"traceutil/trace.go:171","msg":"trace[85739012] linearizableReadLoop","detail":"{readStateIndex:9241; appliedIndex:9239; }","duration":"127.106456ms","start":"2026-09-09T06:31:04.110262Z","end":"2026-09-09T06:31:04.237368Z","steps":["trace[85739012] 'read index received' (duration: 121.402045ms)","trace[85739012] 'applied index is now lower than readState.Index' (duration: 5.703061ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:31:04.237388Z","caller":"traceutil/trace.go:171","msg":"trace[210561584] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"218.312395ms","start":"2026-09-09T06:31:04.019054Z","end":"2026-09-09T06:31:04.237366Z","steps":["trace[210561584] 'process raft request' (duration: 31.450935ms)","trace[210561584] 'compare' (duration: 186.471041ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:31:04.23749Z","caller":"traceutil/trace.go:171","msg":"trace[1578339748] transaction","detail":"{read_only:false; response_revision:8927; number_of_response:1; }","duration":"168.886387ms","start":"2026-09-09T06:31:04.068584Z","end":"2026-09-09T06:31:04.237471Z","steps":["trace[1578339748] 'process raft request' (duration: 168.725434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:31:04.237596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.334011ms","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-09-09T06:31:04.237643Z","caller":"traceutil/trace.go:171","msg":"trace[769757837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8927; }","duration":"127.417673ms","start":"2026-09-09T06:31:04.110216Z","end":"2026-09-09T06:31:04.237634Z","steps":["trace[769757837] 'agreement among raft nodes before linearized reading' (duration: 127.29463ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:31:04.376809Z","caller":"traceutil/trace.go:171","msg":"trace[850713840] transaction","detail":"{read_only:false; response_revision:8929; number_of_response:1; }","duration":"135.437577ms","start":"2026-09-09T06:31:04.241353Z","end":"2026-09-09T06:31:04.37679Z","steps":["trace[850713840] 'process raft request' (duration: 98.420325ms)","trace[850713840] 'compare' (duration: 36.840877ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:31:04.376804Z","caller":"traceutil/trace.go:171","msg":"trace[2063742819] linearizableReadLoop","detail":"{readStateIndex:9243; appliedIndex:9242; }","duration":"104.950465ms","start":"2026-09-09T06:31:04.271837Z","end":"2026-09-09T06:31:04.376787Z","steps":["trace[2063742819] 'read index received' (duration: 67.947303ms)","trace[2063742819] 'applied index is now lower than readState.Index' (duration: 37.002202ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:31:04.377043Z","caller":"traceutil/trace.go:171","msg":"trace[406995876] transaction","detail":"{read_only:false; response_revision:8930; number_of_response:1; }","duration":"101.19738ms","start":"2026-09-09T06:31:04.27583Z","end":"2026-09-09T06:31:04.377027Z","steps":["trace[406995876] 'process raft request' (duration: 100.926104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:31:04.377148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.139535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:31:04.377238Z","caller":"traceutil/trace.go:171","msg":"trace[770477696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8930; }","duration":"120.257648ms","start":"2026-09-09T06:31:04.256956Z","end":"2026-09-09T06:31:04.377214Z","steps":["trace[770477696] 'agreement among raft nodes before linearized reading' (duration: 119.88747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:31:05.242848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.501601ms","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-09-09T06:31:05.242924Z","caller":"traceutil/trace.go:171","msg":"trace[2141035339] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8934; }","duration":"136.611864ms","start":"2026-09-09T06:31:05.106292Z","end":"2026-09-09T06:31:05.242904Z","steps":["trace[2141035339] 'count revisions from in-memory index tree' (duration: 136.44139ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:32:15.094391Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T06:32:15.101688Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T06:32:15.10178Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-09T06:32:37.204233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.937474ms","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-09-09T06:32:37.20433Z","caller":"traceutil/trace.go:171","msg":"trace[1490688241] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:9803; }","duration":"108.040837ms","start":"2026-09-09T06:32:37.096267Z","end":"2026-09-09T06:32:37.204308Z","steps":["trace[1490688241] 'count revisions from in-memory index tree' (duration: 107.864872ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:34:06.036333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8049} {"level":"info","ts":"2026-09-09T06:34:06.10633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8049,"took":"67.976687ms","hash":2228608204,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":27918336,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:34:06.106407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2228608204,"revision":8049,"compact-revision":5897} {"level":"warn","ts":"2026-09-09T06:38:00.097008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.315747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:38:00.097165Z","caller":"traceutil/trace.go:171","msg":"trace[878707416] transaction","detail":"{read_only:false; response_revision:12142; number_of_response:1; }","duration":"250.08757ms","start":"2026-09-09T06:37:59.84705Z","end":"2026-09-09T06:38:00.097137Z","steps":["trace[878707416] 'process raft request' (duration: 127.556668ms)","trace[878707416] 'compare' (duration: 122.202625ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:38:18.99051Z","caller":"traceutil/trace.go:171","msg":"trace[182996263] transaction","detail":"{read_only:false; response_revision:12252; number_of_response:1; }","duration":"249.980937ms","start":"2026-09-09T06:38:18.740503Z","end":"2026-09-09T06:38:18.990484Z","steps":["trace[182996263] 'process raft request' (duration: 249.727482ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:38:19.193873Z","caller":"traceutil/trace.go:171","msg":"trace[1690235210] transaction","detail":"{read_only:false; response_revision:12254; number_of_response:1; }","duration":"114.151229ms","start":"2026-09-09T06:38:19.079687Z","end":"2026-09-09T06:38:19.193838Z","steps":["trace[1690235210] 'process raft request' (duration: 113.972065ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:38:20.193262Z","caller":"traceutil/trace.go:171","msg":"trace[748004322] transaction","detail":"{read_only:false; response_revision:12258; number_of_response:1; }","duration":"201.597144ms","start":"2026-09-09T06:38:19.991633Z","end":"2026-09-09T06:38:20.19323Z","steps":["trace[748004322] 'process raft request' (duration: 201.43467ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:38:20.378248Z","caller":"traceutil/trace.go:171","msg":"trace[1347257586] linearizableReadLoop","detail":"{readStateIndex:12681; appliedIndex:12680; }","duration":"104.2208ms","start":"2026-09-09T06:38:20.273977Z","end":"2026-09-09T06:38:20.378198Z","steps":["trace[1347257586] 'read index received' (duration: 103.899123ms)","trace[1347257586] 'applied index is now lower than readState.Index' (duration: 320.667µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:38:20.378487Z","caller":"traceutil/trace.go:171","msg":"trace[1951662439] transaction","detail":"{read_only:false; response_revision:12259; number_of_response:1; }","duration":"199.108016ms","start":"2026-09-09T06:38:20.179316Z","end":"2026-09-09T06:38:20.378424Z","steps":["trace[1951662439] 'process raft request' (duration: 198.691057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:38:20.378806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.814114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rcf8g\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-09T06:38:20.3789Z","caller":"traceutil/trace.go:171","msg":"trace[1058464649] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rcf8g; range_end:; response_count:1; response_revision:12259; }","duration":"104.966788ms","start":"2026-09-09T06:38:20.273919Z","end":"2026-09-09T06:38:20.378886Z","steps":["trace[1058464649] 'agreement among raft nodes before linearized reading' (duration: 104.416744ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:39:06.043281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10408} {"level":"info","ts":"2026-09-09T06:39:06.132487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10408,"took":"86.724347ms","hash":1280424089,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":25751552,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-09T06:39:06.132561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1280424089,"revision":10408,"compact-revision":8049} {"level":"info","ts":"2026-09-09T06:44:06.050349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12580} {"level":"info","ts":"2026-09-09T06:44:06.134672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12580,"took":"82.184063ms","hash":2163381245,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":27631616,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-09T06:44:06.13473Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2163381245,"revision":12580,"compact-revision":10408} {"level":"info","ts":"2026-09-09T06:45:31.300923Z","caller":"traceutil/trace.go:171","msg":"trace[856309405] transaction","detail":"{read_only:false; response_revision:15068; number_of_response:1; }","duration":"112.644895ms","start":"2026-09-09T06:45:31.188244Z","end":"2026-09-09T06:45:31.300889Z","steps":["trace[856309405] 'process raft request' (duration: 83.978104ms)","trace[856309405] 'compare' (duration: 27.939393ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:45:40.167758Z","caller":"traceutil/trace.go:171","msg":"trace[555659506] linearizableReadLoop","detail":"{readStateIndex:15651; appliedIndex:15650; }","duration":"210.703351ms","start":"2026-09-09T06:45:39.957027Z","end":"2026-09-09T06:45:40.167731Z","steps":["trace[555659506] 'read index received' (duration: 210.593208ms)","trace[555659506] 'applied index is now lower than readState.Index' (duration: 109.053µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:45:40.167837Z","caller":"traceutil/trace.go:171","msg":"trace[264632543] transaction","detail":"{read_only:false; response_revision:15125; number_of_response:1; }","duration":"257.426498ms","start":"2026-09-09T06:45:39.91036Z","end":"2026-09-09T06:45:40.167787Z","steps":["trace[264632543] 'process raft request' (duration: 257.212152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:40.168061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.0138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-8vsz8\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-09-09T06:45:40.16817Z","caller":"traceutil/trace.go:171","msg":"trace[1141796985] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-8vsz8; range_end:; response_count:1; response_revision:15125; }","duration":"211.134412ms","start":"2026-09-09T06:45:39.957011Z","end":"2026-09-09T06:45:40.168146Z","steps":["trace[1141796985] 'agreement among raft nodes before linearized reading' (duration: 210.850046ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:45:40.368079Z","caller":"traceutil/trace.go:171","msg":"trace[1758915102] linearizableReadLoop","detail":"{readStateIndex:15652; appliedIndex:15651; }","duration":"200.20188ms","start":"2026-09-09T06:45:40.167847Z","end":"2026-09-09T06:45:40.368049Z","steps":["trace[1758915102] 'read index received' (duration: 148.053238ms)","trace[1758915102] 'applied index is now lower than readState.Index' (duration: 52.147592ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:45:40.368417Z","caller":"traceutil/trace.go:171","msg":"trace[510355477] transaction","detail":"{read_only:false; response_revision:15126; number_of_response:1; }","duration":"243.088809ms","start":"2026-09-09T06:45:40.125314Z","end":"2026-09-09T06:45:40.368403Z","steps":["trace[510355477] 'process raft request' (duration: 190.64285ms)","trace[510355477] 'compare' (duration: 51.970107ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:45:40.36874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.713881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-09-09T06:45:40.368785Z","caller":"traceutil/trace.go:171","msg":"trace[924497358] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15126; }","duration":"256.798353ms","start":"2026-09-09T06:45:40.111972Z","end":"2026-09-09T06:45:40.368771Z","steps":["trace[924497358] 'agreement among raft nodes before linearized reading' (duration: 256.619079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:40.372295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.909296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rcf8g\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-09-09T06:45:40.372451Z","caller":"traceutil/trace.go:171","msg":"trace[749007876] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rcf8g; range_end:; response_count:1; response_revision:15127; }","duration":"171.121531ms","start":"2026-09-09T06:45:40.201293Z","end":"2026-09-09T06:45:40.372415Z","steps":["trace[749007876] 'agreement among raft nodes before linearized reading' (duration: 170.747492ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:45:40.372597Z","caller":"traceutil/trace.go:171","msg":"trace[2075142713] transaction","detail":"{read_only:false; response_revision:15127; number_of_response:1; }","duration":"175.305776ms","start":"2026-09-09T06:45:40.197264Z","end":"2026-09-09T06:45:40.372569Z","steps":["trace[2075142713] 'process raft request' (duration: 174.489277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:45:40.372782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.615198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:45:40.372856Z","caller":"traceutil/trace.go:171","msg":"trace[223820449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15127; }","duration":"117.71646ms","start":"2026-09-09T06:45:40.255122Z","end":"2026-09-09T06:45:40.372838Z","steps":["trace[223820449] 'agreement among raft nodes before linearized reading' (duration: 117.604918ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:46:03.594774Z","caller":"traceutil/trace.go:171","msg":"trace[1489514180] transaction","detail":"{read_only:false; response_revision:15442; number_of_response:1; }","duration":"132.719616ms","start":"2026-09-09T06:46:03.462032Z","end":"2026-09-09T06:46:03.594752Z","steps":["trace[1489514180] 'process raft request' (duration: 132.559582ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:46:03.992506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.11059ms","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-09-09T06:46:03.992603Z","caller":"traceutil/trace.go:171","msg":"trace[1464443722] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15443; }","duration":"313.255514ms","start":"2026-09-09T06:46:03.679325Z","end":"2026-09-09T06:46:03.99258Z","steps":["trace[1464443722] 'range keys from in-memory index tree' (duration: 313.053808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:46:03.992646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:46:03.679306Z","time spent":"313.330516ms","remote":"127.0.0.1:40098","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":"warn","ts":"2026-09-09T06:46:03.992868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.291079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rj6vm\" ","response":"range_response_count:1 size:20644"} {"level":"info","ts":"2026-09-09T06:46:03.99298Z","caller":"traceutil/trace.go:171","msg":"trace[850256526] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rj6vm; range_end:; response_count:1; response_revision:15443; }","duration":"159.524944ms","start":"2026-09-09T06:46:03.833431Z","end":"2026-09-09T06:46:03.992956Z","steps":["trace[850256526] 'range keys from in-memory index tree' (duration: 159.029103ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:47:30.207606Z","caller":"traceutil/trace.go:171","msg":"trace[777285885] transaction","detail":"{read_only:false; response_revision:16258; number_of_response:1; }","duration":"120.918376ms","start":"2026-09-09T06:47:30.086659Z","end":"2026-09-09T06:47:30.207578Z","steps":["trace[777285885] 'process raft request' (duration: 120.474315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:37.020122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.856649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qqhk4\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-09-09T06:47:37.02022Z","caller":"traceutil/trace.go:171","msg":"trace[844652115] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qqhk4; range_end:; response_count:1; response_revision:16289; }","duration":"364.007853ms","start":"2026-09-09T06:47:36.656194Z","end":"2026-09-09T06:47:37.020202Z","steps":["trace[844652115] 'range keys from in-memory index tree' (duration: 363.662375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:37.020315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:47:36.656166Z","time spent":"364.086564ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-qqhk4\" "} {"level":"warn","ts":"2026-09-09T06:47:37.020605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.913713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qqhk4\" ","response":"range_response_count:1 size:22100"} {"level":"warn","ts":"2026-09-09T06:47:37.020602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.885884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-09T06:47:37.020742Z","caller":"traceutil/trace.go:171","msg":"trace[835536068] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16289; }","duration":"233.002316ms","start":"2026-09-09T06:47:36.787664Z","end":"2026-09-09T06:47:37.020666Z","steps":["trace[835536068] 'range keys from in-memory index tree' (duration: 232.7421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:37.020923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.444268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-09T06:47:37.020964Z","caller":"traceutil/trace.go:171","msg":"trace[1271091129] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16289; }","duration":"237.660034ms","start":"2026-09-09T06:47:36.783294Z","end":"2026-09-09T06:47:37.020954Z","steps":["trace[1271091129] 'range keys from in-memory index tree' (duration: 237.352496ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:47:37.02109Z","caller":"traceutil/trace.go:171","msg":"trace[1193735051] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qqhk4; range_end:; response_count:1; response_revision:16289; }","duration":"258.989624ms","start":"2026-09-09T06:47:36.76164Z","end":"2026-09-09T06:47:37.020629Z","steps":["trace[1193735051] 'range keys from in-memory index tree' (duration: 258.772929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:47:37.021438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.757923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:47:37.021472Z","caller":"traceutil/trace.go:171","msg":"trace[1011735141] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16289; }","duration":"233.811534ms","start":"2026-09-09T06:47:36.787651Z","end":"2026-09-09T06:47:37.021462Z","steps":["trace[1011735141] 'range keys from in-memory index tree' (duration: 233.667351ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:47:37.021579Z","caller":"traceutil/trace.go:171","msg":"trace[1424790734] transaction","detail":"{read_only:false; response_revision:16290; number_of_response:1; }","duration":"206.083275ms","start":"2026-09-09T06:47:36.815438Z","end":"2026-09-09T06:47:37.021521Z","steps":["trace[1424790734] 'process raft request' (duration: 194.356486ms)","trace[1424790734] 'compare' (duration: 10.095582ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:49:06.058016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14580} {"level":"info","ts":"2026-09-09T06:49:06.137794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14580,"took":"77.227489ms","hash":2132211187,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":30838784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T06:49:06.137882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2132211187,"revision":14580,"compact-revision":12580} {"level":"info","ts":"2026-09-09T06:49:32.482437Z","caller":"traceutil/trace.go:171","msg":"trace[1005889481] linearizableReadLoop","detail":"{readStateIndex:17599; appliedIndex:17598; }","duration":"115.489869ms","start":"2026-09-09T06:49:32.36692Z","end":"2026-09-09T06:49:32.48241Z","steps":["trace[1005889481] 'read index received' (duration: 94.919625ms)","trace[1005889481] 'applied index is now lower than readState.Index' (duration: 20.568754ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:49:32.482464Z","caller":"traceutil/trace.go:171","msg":"trace[1023294979] transaction","detail":"{read_only:false; response_revision:17012; number_of_response:1; }","duration":"117.087947ms","start":"2026-09-09T06:49:32.365354Z","end":"2026-09-09T06:49:32.482442Z","steps":["trace[1023294979] 'process raft request' (duration: 96.613805ms)","trace[1023294979] 'compare' (duration: 20.330369ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:49:32.482674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.729984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rj6vm\" ","response":"range_response_count:1 size:20645"} {"level":"info","ts":"2026-09-09T06:49:32.482754Z","caller":"traceutil/trace.go:171","msg":"trace[79179063] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rj6vm; range_end:; response_count:1; response_revision:17012; }","duration":"115.857147ms","start":"2026-09-09T06:49:32.366886Z","end":"2026-09-09T06:49:32.482743Z","steps":["trace[79179063] 'agreement among raft nodes before linearized reading' (duration: 115.615172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:49:32.484458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.099849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-09T06:49:32.484552Z","caller":"traceutil/trace.go:171","msg":"trace[659964296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17013; }","duration":"101.226152ms","start":"2026-09-09T06:49:32.383306Z","end":"2026-09-09T06:49:32.484532Z","steps":["trace[659964296] 'agreement among raft nodes before linearized reading' (duration: 99.938833ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:49:56.812371Z","caller":"traceutil/trace.go:171","msg":"trace[1249319808] transaction","detail":"{read_only:false; response_revision:17237; number_of_response:1; }","duration":"102.743656ms","start":"2026-09-09T06:49:56.709602Z","end":"2026-09-09T06:49:56.812346Z","steps":["trace[1249319808] 'process raft request' (duration: 91.869316ms)","trace[1249319808] 'compare' (duration: 10.769718ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:50:50.869695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.795083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T06:50:50.869756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.666445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-09-09T06:50:50.869799Z","caller":"traceutil/trace.go:171","msg":"trace[1888978431] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17817; }","duration":"134.970918ms","start":"2026-09-09T06:50:50.734806Z","end":"2026-09-09T06:50:50.869776Z","steps":["trace[1888978431] 'range keys from in-memory index tree' (duration: 134.722002ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:50.869845Z","caller":"traceutil/trace.go:171","msg":"trace[528869935] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17817; }","duration":"129.750058ms","start":"2026-09-09T06:50:50.740051Z","end":"2026-09-09T06:50:50.869801Z","steps":["trace[528869935] 'range keys from in-memory index tree' (duration: 129.513962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:50.869861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.879496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T06:50:50.869724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.997178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:50:50.869891Z","caller":"traceutil/trace.go:171","msg":"trace[769335155] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17817; }","duration":"134.988589ms","start":"2026-09-09T06:50:50.734894Z","end":"2026-09-09T06:50:50.869883Z","steps":["trace[769335155] 'range keys from in-memory index tree' (duration: 134.759163ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:50.86994Z","caller":"traceutil/trace.go:171","msg":"trace[2037145239] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:17817; }","duration":"135.246205ms","start":"2026-09-09T06:50:50.734664Z","end":"2026-09-09T06:50:50.86991Z","steps":["trace[2037145239] 'range keys from in-memory index tree' (duration: 134.930466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:50.869935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.977485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:50:50.870023Z","caller":"traceutil/trace.go:171","msg":"trace[770049127] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:0; response_revision:17817; }","duration":"134.125889ms","start":"2026-09-09T06:50:50.735878Z","end":"2026-09-09T06:50:50.870003Z","steps":["trace[770049127] 'range keys from in-memory index tree' (duration: 133.828362ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:50.939533Z","caller":"traceutil/trace.go:171","msg":"trace[67973018] linearizableReadLoop","detail":"{readStateIndex:18430; appliedIndex:18429; }","duration":"102.851497ms","start":"2026-09-09T06:50:50.836659Z","end":"2026-09-09T06:50:50.93951Z","steps":["trace[67973018] 'read index received' (duration: 102.617492ms)","trace[67973018] 'applied index is now lower than readState.Index' (duration: 233.185µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:50:50.939624Z","caller":"traceutil/trace.go:171","msg":"trace[1224413921] transaction","detail":"{read_only:false; response_revision:17818; number_of_response:1; }","duration":"112.829708ms","start":"2026-09-09T06:50:50.826778Z","end":"2026-09-09T06:50:50.939608Z","steps":["trace[1224413921] 'process raft request' (duration: 112.591232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:50.939771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.173895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-09T06:50:50.939851Z","caller":"traceutil/trace.go:171","msg":"trace[787990136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17818; }","duration":"103.307509ms","start":"2026-09-09T06:50:50.836528Z","end":"2026-09-09T06:50:50.939836Z","steps":["trace[787990136] 'agreement among raft nodes before linearized reading' (duration: 103.108643ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:58.002664Z","caller":"traceutil/trace.go:171","msg":"trace[2123939845] linearizableReadLoop","detail":"{readStateIndex:18495; appliedIndex:18494; }","duration":"113.133135ms","start":"2026-09-09T06:50:57.889504Z","end":"2026-09-09T06:50:58.002637Z","steps":["trace[2123939845] 'read index received' (duration: 112.998192ms)","trace[2123939845] 'applied index is now lower than readState.Index' (duration: 134.063µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:50:58.002901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.38524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-09T06:50:58.002941Z","caller":"traceutil/trace.go:171","msg":"trace[1426225656] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:17882; }","duration":"113.476373ms","start":"2026-09-09T06:50:57.889453Z","end":"2026-09-09T06:50:58.00293Z","steps":["trace[1426225656] 'agreement among raft nodes before linearized reading' (duration: 113.278468ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:58.00325Z","caller":"traceutil/trace.go:171","msg":"trace[1322127470] transaction","detail":"{read_only:false; response_revision:17882; number_of_response:1; }","duration":"154.076048ms","start":"2026-09-09T06:50:57.848713Z","end":"2026-09-09T06:50:58.002789Z","steps":["trace[1322127470] 'process raft request' (duration: 153.780781ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:58.147336Z","caller":"traceutil/trace.go:171","msg":"trace[617018491] transaction","detail":"{read_only:false; response_revision:17883; number_of_response:1; }","duration":"177.892216ms","start":"2026-09-09T06:50:57.96941Z","end":"2026-09-09T06:50:58.147303Z","steps":["trace[617018491] 'process raft request' (duration: 94.64171ms)","trace[617018491] 'compare' (duration: 82.99285ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:50:58.246304Z","caller":"traceutil/trace.go:171","msg":"trace[1148488372] transaction","detail":"{read_only:false; response_revision:17885; number_of_response:1; }","duration":"103.901742ms","start":"2026-09-09T06:50:58.142382Z","end":"2026-09-09T06:50:58.246284Z","steps":["trace[1148488372] 'process raft request' (duration: 103.773599ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:59.200709Z","caller":"traceutil/trace.go:171","msg":"trace[1749006281] linearizableReadLoop","detail":"{readStateIndex:18499; appliedIndex:18498; }","duration":"183.395093ms","start":"2026-09-09T06:50:59.017259Z","end":"2026-09-09T06:50:59.200654Z","steps":["trace[1749006281] 'read index received' (duration: 183.175777ms)","trace[1749006281] 'applied index is now lower than readState.Index' (duration: 218.166µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:50:59.200888Z","caller":"traceutil/trace.go:171","msg":"trace[1591488996] transaction","detail":"{read_only:false; response_revision:17886; number_of_response:1; }","duration":"215.586094ms","start":"2026-09-09T06:50:58.985266Z","end":"2026-09-09T06:50:59.200852Z","steps":["trace[1591488996] 'process raft request' (duration: 215.217105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.200983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.70446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-09T06:50:59.201031Z","caller":"traceutil/trace.go:171","msg":"trace[298138525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17886; }","duration":"183.796852ms","start":"2026-09-09T06:50:59.017218Z","end":"2026-09-09T06:50:59.201015Z","steps":["trace[298138525] 'agreement among raft nodes before linearized reading' (duration: 183.637649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.201076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.977096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-09T06:50:59.201458Z","caller":"traceutil/trace.go:171","msg":"trace[2000052557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17886; }","duration":"118.406586ms","start":"2026-09-09T06:50:59.083027Z","end":"2026-09-09T06:50:59.201434Z","steps":["trace[2000052557] 'agreement among raft nodes before linearized reading' (duration: 117.863564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.201248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.359415ms","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:2083"} {"level":"info","ts":"2026-09-09T06:50:59.201585Z","caller":"traceutil/trace.go:171","msg":"trace[995056428] 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:17886; }","duration":"105.747565ms","start":"2026-09-09T06:50:59.095811Z","end":"2026-09-09T06:50:59.201559Z","steps":["trace[995056428] 'agreement among raft nodes before linearized reading' (duration: 105.295904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.201277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.143282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-09T06:50:59.201735Z","caller":"traceutil/trace.go:171","msg":"trace[1960732316] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:17886; }","duration":"102.602662ms","start":"2026-09-09T06:50:59.099118Z","end":"2026-09-09T06:50:59.20172Z","steps":["trace[1960732316] 'agreement among raft nodes before linearized reading' (duration: 101.947598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.312918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.866293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4bc04db5-1321-405c-a1d9-70632d619e20\" ","response":"range_response_count:1 size:1436"} {"level":"info","ts":"2026-09-09T06:50:59.313023Z","caller":"traceutil/trace.go:171","msg":"trace[1954506453] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4bc04db5-1321-405c-a1d9-70632d619e20; range_end:; response_count:1; response_revision:17886; }","duration":"107.997696ms","start":"2026-09-09T06:50:59.204994Z","end":"2026-09-09T06:50:59.312992Z","steps":["trace[1954506453] 'range keys from in-memory index tree' (duration: 107.68328ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:59.313022Z","caller":"traceutil/trace.go:171","msg":"trace[1220255362] transaction","detail":"{read_only:false; response_revision:17887; number_of_response:1; }","duration":"107.72382ms","start":"2026-09-09T06:50:59.205257Z","end":"2026-09-09T06:50:59.312981Z","steps":["trace[1220255362] 'process raft request' (duration: 89.535111ms)","trace[1220255362] 'compare' (duration: 17.877892ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:50:59.857503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.81756ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:50:59.857645Z","caller":"traceutil/trace.go:171","msg":"trace[28059603] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:17887; }","duration":"152.965773ms","start":"2026-09-09T06:50:59.704657Z","end":"2026-09-09T06:50:59.857622Z","steps":["trace[28059603] 'range keys from in-memory index tree' (duration: 152.798329ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.857727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.15167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T06:50:59.858002Z","caller":"traceutil/trace.go:171","msg":"trace[643596690] transaction","detail":"{read_only:false; response_revision:17888; number_of_response:1; }","duration":"651.724745ms","start":"2026-09-09T06:50:59.20626Z","end":"2026-09-09T06:50:59.857984Z","steps":["trace[643596690] 'process raft request' (duration: 454.215398ms)","trace[643596690] 'compare' (duration: 197.021236ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:50:59.858274Z","caller":"traceutil/trace.go:171","msg":"trace[21609535] transaction","detail":"{read_only:false; response_revision:17890; number_of_response:1; }","duration":"651.214664ms","start":"2026-09-09T06:50:59.207021Z","end":"2026-09-09T06:50:59.858235Z","steps":["trace[21609535] 'process raft request' (duration: 650.926787ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:59.858187Z","caller":"traceutil/trace.go:171","msg":"trace[173385587] transaction","detail":"{read_only:false; response_revision:17891; number_of_response:1; }","duration":"188.686174ms","start":"2026-09-09T06:50:59.669473Z","end":"2026-09-09T06:50:59.858159Z","steps":["trace[173385587] 'process raft request' (duration: 188.557441ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:59.858309Z","caller":"traceutil/trace.go:171","msg":"trace[1311096313] transaction","detail":"{read_only:false; response_revision:17889; number_of_response:1; }","duration":"651.849088ms","start":"2026-09-09T06:50:59.206397Z","end":"2026-09-09T06:50:59.858246Z","steps":["trace[1311096313] 'process raft request' (duration: 651.46789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.858344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:59.207009Z","time spent":"651.297655ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:50:59.858318Z","caller":"traceutil/trace.go:171","msg":"trace[1000664372] linearizableReadLoop","detail":"{readStateIndex:18503; appliedIndex:18499; }","duration":"600.835074ms","start":"2026-09-09T06:50:59.257438Z","end":"2026-09-09T06:50:59.858273Z","steps":["trace[1000664372] 'read index received' (duration: 37.450952ms)","trace[1000664372] 'applied index is now lower than readState.Index' (duration: 563.355751ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:50:59.858416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.967796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T06:50:59.858104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:59.206233Z","time spent":"651.802316ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:50:59.858486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:59.206379Z","time spent":"652.004731ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:50:59.85851Z","caller":"traceutil/trace.go:171","msg":"trace[514540061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17891; }","duration":"601.102909ms","start":"2026-09-09T06:50:59.257383Z","end":"2026-09-09T06:50:59.858486Z","steps":["trace[514540061] 'agreement among raft nodes before linearized reading' (duration: 600.955526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.858562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:59.257363Z","time spent":"601.186842ms","remote":"127.0.0.1:39656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T06:50:59.858763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.651071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.167\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-09-09T06:50:59.858799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.455541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-09T06:50:59.858842Z","caller":"traceutil/trace.go:171","msg":"trace[1999705092] range","detail":"{range_begin:/registry/masterleases/162.253.55.167; range_end:; response_count:1; response_revision:17891; }","duration":"465.742653ms","start":"2026-09-09T06:50:59.393058Z","end":"2026-09-09T06:50:59.8588Z","steps":["trace[1999705092] 'agreement among raft nodes before linearized reading' (duration: 465.528588ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:50:59.858854Z","caller":"traceutil/trace.go:171","msg":"trace[48711145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17891; }","duration":"410.561853ms","start":"2026-09-09T06:50:59.448282Z","end":"2026-09-09T06:50:59.858844Z","steps":["trace[48711145] 'agreement among raft nodes before linearized reading' (duration: 410.42889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.858866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.684572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rj6vm\" ","response":"range_response_count:1 size:20645"} {"level":"warn","ts":"2026-09-09T06:50:59.858888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:59.393038Z","time spent":"465.838136ms","remote":"127.0.0.1:39702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/162.253.55.167\" "} {"level":"info","ts":"2026-09-09T06:50:59.858922Z","caller":"traceutil/trace.go:171","msg":"trace[1586999860] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rj6vm; range_end:; response_count:1; response_revision:17891; }","duration":"210.776684ms","start":"2026-09-09T06:50:59.648129Z","end":"2026-09-09T06:50:59.858906Z","steps":["trace[1586999860] 'agreement among raft nodes before linearized reading' (duration: 210.569289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.858995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.343222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qqhk4\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-09-09T06:50:59.859021Z","caller":"traceutil/trace.go:171","msg":"trace[720754844] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qqhk4; range_end:; response_count:1; response_revision:17891; }","duration":"259.404473ms","start":"2026-09-09T06:50:59.599609Z","end":"2026-09-09T06:50:59.859013Z","steps":["trace[720754844] 'agreement among raft nodes before linearized reading' (duration: 259.290211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.858883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:59.448258Z","time spent":"410.618545ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-09T06:50:59.859146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.647404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-09T06:50:59.859203Z","caller":"traceutil/trace.go:171","msg":"trace[69005214] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:17891; }","duration":"456.752896ms","start":"2026-09-09T06:50:59.402435Z","end":"2026-09-09T06:50:59.859188Z","steps":["trace[69005214] 'agreement among raft nodes before linearized reading' (duration: 456.548722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.859236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:50:59.402417Z","time spent":"456.811298ms","remote":"127.0.0.1:39984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":133,"response size":32,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-09-09T06:50:59.987443Z","caller":"traceutil/trace.go:171","msg":"trace[1471641919] linearizableReadLoop","detail":"{readStateIndex:18506; appliedIndex:18504; }","duration":"121.231791ms","start":"2026-09-09T06:50:59.86619Z","end":"2026-09-09T06:50:59.987422Z","steps":["trace[1471641919] 'read index received' (duration: 19.167712ms)","trace[1471641919] 'applied index is now lower than readState.Index' (duration: 102.063139ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:50:59.987529Z","caller":"traceutil/trace.go:171","msg":"trace[1250790219] transaction","detail":"{read_only:false; response_revision:17892; number_of_response:1; }","duration":"122.103723ms","start":"2026-09-09T06:50:59.865398Z","end":"2026-09-09T06:50:59.987502Z","steps":["trace[1250790219] 'process raft request' (duration: 121.877797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:50:59.988353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.143142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:90 size:905543"} {"level":"info","ts":"2026-09-09T06:50:59.9884Z","caller":"traceutil/trace.go:171","msg":"trace[132327889] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:90; response_revision:17892; }","duration":"122.232595ms","start":"2026-09-09T06:50:59.866155Z","end":"2026-09-09T06:50:59.988388Z","steps":["trace[132327889] 'agreement among raft nodes before linearized reading' (duration: 121.369765ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:51:00.018528Z","caller":"traceutil/trace.go:171","msg":"trace[2136052350] transaction","detail":"{read_only:false; response_revision:17893; number_of_response:1; }","duration":"132.122742ms","start":"2026-09-09T06:50:59.886382Z","end":"2026-09-09T06:51:00.018505Z","steps":["trace[2136052350] 'process raft request' (duration: 131.426786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:51:00.018983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.423276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:90 size:905543"} {"level":"info","ts":"2026-09-09T06:51:00.019068Z","caller":"traceutil/trace.go:171","msg":"trace[729626279] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:90; response_revision:17893; }","duration":"151.5514ms","start":"2026-09-09T06:50:59.867499Z","end":"2026-09-09T06:51:00.019051Z","steps":["trace[729626279] 'agreement among raft nodes before linearized reading' (duration: 150.487265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:51:00.019466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.360411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:90 size:905543"} {"level":"info","ts":"2026-09-09T06:51:00.019535Z","caller":"traceutil/trace.go:171","msg":"trace[1836421285] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:90; response_revision:17893; }","duration":"150.448494ms","start":"2026-09-09T06:50:59.869069Z","end":"2026-09-09T06:51:00.019518Z","steps":["trace[1836421285] 'agreement among raft nodes before linearized reading' (duration: 149.287038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:51:00.019604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.866943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:90 size:905543"} {"level":"info","ts":"2026-09-09T06:51:00.019654Z","caller":"traceutil/trace.go:171","msg":"trace[1344552499] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:90; response_revision:17893; }","duration":"150.989176ms","start":"2026-09-09T06:50:59.868654Z","end":"2026-09-09T06:51:00.019643Z","steps":["trace[1344552499] 'agreement among raft nodes before linearized reading' (duration: 149.736578ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:12.471787Z","caller":"traceutil/trace.go:171","msg":"trace[863943510] linearizableReadLoop","detail":"{readStateIndex:18944; appliedIndex:18943; }","duration":"190.906016ms","start":"2026-09-09T06:52:12.280849Z","end":"2026-09-09T06:52:12.471755Z","steps":["trace[863943510] 'read index received' (duration: 104.6589ms)","trace[863943510] 'applied index is now lower than readState.Index' (duration: 86.245726ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:52:12.471853Z","caller":"traceutil/trace.go:171","msg":"trace[1336246966] transaction","detail":"{read_only:false; response_revision:18315; number_of_response:1; }","duration":"206.464234ms","start":"2026-09-09T06:52:12.265314Z","end":"2026-09-09T06:52:12.471779Z","steps":["trace[1336246966] 'process raft request' (duration: 120.184747ms)","trace[1336246966] 'compare' (duration: 86.125743ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:52:12.47205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.170722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-09T06:52:12.472131Z","caller":"traceutil/trace.go:171","msg":"trace[1267466487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18315; }","duration":"191.305515ms","start":"2026-09-09T06:52:12.280804Z","end":"2026-09-09T06:52:12.47211Z","steps":["trace[1267466487] 'agreement among raft nodes before linearized reading' (duration: 191.090211ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:12.569553Z","caller":"traceutil/trace.go:171","msg":"trace[1599749891] transaction","detail":"{read_only:false; response_revision:18316; number_of_response:1; }","duration":"261.621304ms","start":"2026-09-09T06:52:12.307894Z","end":"2026-09-09T06:52:12.569515Z","steps":["trace[1599749891] 'process raft request' (duration: 261.47391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.569668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.204453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-09T06:52:12.569758Z","caller":"traceutil/trace.go:171","msg":"trace[469098980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18316; }","duration":"211.346827ms","start":"2026-09-09T06:52:12.358394Z","end":"2026-09-09T06:52:12.569741Z","steps":["trace[469098980] 'agreement among raft nodes before linearized reading' (duration: 211.125952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:12.569942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.154598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-09T06:52:12.569967Z","caller":"traceutil/trace.go:171","msg":"trace[1505941553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18316; }","duration":"276.210969ms","start":"2026-09-09T06:52:12.29375Z","end":"2026-09-09T06:52:12.569961Z","steps":["trace[1505941553] 'agreement among raft nodes before linearized reading' (duration: 276.143878ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:12.665046Z","caller":"traceutil/trace.go:171","msg":"trace[1498428101] transaction","detail":"{read_only:false; response_revision:18317; number_of_response:1; }","duration":"188.677435ms","start":"2026-09-09T06:52:12.476315Z","end":"2026-09-09T06:52:12.664992Z","steps":["trace[1498428101] 'process raft request' (duration: 115.778946ms)","trace[1498428101] 'compare' (duration: 72.763596ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:52:12.665321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.110596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-09-09T06:52:12.665396Z","caller":"traceutil/trace.go:171","msg":"trace[1924286684] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18320; }","duration":"130.220038ms","start":"2026-09-09T06:52:12.535157Z","end":"2026-09-09T06:52:12.665377Z","steps":["trace[1924286684] 'agreement among raft nodes before linearized reading' (duration: 129.988173ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:38.784473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.395583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-09T06:52:38.784571Z","caller":"traceutil/trace.go:171","msg":"trace[465348872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18489; }","duration":"180.545887ms","start":"2026-09-09T06:52:38.604007Z","end":"2026-09-09T06:52:38.784553Z","steps":["trace[465348872] 'range keys from in-memory index tree' (duration: 180.214309ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:38.785767Z","caller":"traceutil/trace.go:171","msg":"trace[868978457] transaction","detail":"{read_only:false; response_revision:18490; number_of_response:1; }","duration":"113.855522ms","start":"2026-09-09T06:52:38.671889Z","end":"2026-09-09T06:52:38.785745Z","steps":["trace[868978457] 'process raft request' (duration: 113.707999ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:48.680498Z","caller":"traceutil/trace.go:171","msg":"trace[830461014] transaction","detail":"{read_only:false; response_revision:18542; number_of_response:1; }","duration":"182.510183ms","start":"2026-09-09T06:52:48.497957Z","end":"2026-09-09T06:52:48.680468Z","steps":["trace[830461014] 'process raft request' (duration: 182.35549ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:49.62402Z","caller":"traceutil/trace.go:171","msg":"trace[487942987] transaction","detail":"{read_only:false; response_revision:18551; number_of_response:1; }","duration":"105.703004ms","start":"2026-09-09T06:52:49.518285Z","end":"2026-09-09T06:52:49.623988Z","steps":["trace[487942987] 'process raft request' (duration: 105.458607ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:52:49.85414Z","caller":"traceutil/trace.go:171","msg":"trace[472304217] transaction","detail":"{read_only:false; response_revision:18553; number_of_response:1; }","duration":"119.360678ms","start":"2026-09-09T06:52:49.734738Z","end":"2026-09-09T06:52:49.854099Z","steps":["trace[472304217] 'process raft request' (duration: 58.202069ms)","trace[472304217] 'compare' (duration: 61.019936ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:52:49.860189Z","caller":"traceutil/trace.go:171","msg":"trace[1338689356] linearizableReadLoop","detail":"{readStateIndex:19191; appliedIndex:19189; }","duration":"101.142888ms","start":"2026-09-09T06:52:49.759021Z","end":"2026-09-09T06:52:49.860164Z","steps":["trace[1338689356] 'read index received' (duration: 34.048493ms)","trace[1338689356] 'applied index is now lower than readState.Index' (duration: 67.093325ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:52:49.860255Z","caller":"traceutil/trace.go:171","msg":"trace[329910359] transaction","detail":"{read_only:false; response_revision:18554; number_of_response:1; }","duration":"102.167732ms","start":"2026-09-09T06:52:49.758056Z","end":"2026-09-09T06:52:49.860224Z","steps":["trace[329910359] 'process raft request' (duration: 101.971768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:52:49.860432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.400964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-qqhk4\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-09-09T06:52:49.860471Z","caller":"traceutil/trace.go:171","msg":"trace[899977236] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-qqhk4; range_end:; response_count:1; response_revision:18554; }","duration":"101.494926ms","start":"2026-09-09T06:52:49.758966Z","end":"2026-09-09T06:52:49.860461Z","steps":["trace[899977236] 'agreement among raft nodes before linearized reading' (duration: 101.291982ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:04.624804Z","caller":"traceutil/trace.go:171","msg":"trace[1552534586] linearizableReadLoop","detail":"{readStateIndex:19729; appliedIndex:19728; }","duration":"214.222542ms","start":"2026-09-09T06:54:04.410556Z","end":"2026-09-09T06:54:04.624779Z","steps":["trace[1552534586] 'read index received' (duration: 200.316062ms)","trace[1552534586] 'applied index is now lower than readState.Index' (duration: 13.90505ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:54:04.625084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.555904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-09T06:54:04.62509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.504438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-09-09T06:54:04.625173Z","caller":"traceutil/trace.go:171","msg":"trace[1892287887] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:19073; }","duration":"214.639642ms","start":"2026-09-09T06:54:04.410512Z","end":"2026-09-09T06:54:04.625152Z","steps":["trace[1892287887] 'agreement among raft nodes before linearized reading' (duration: 214.390616ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:04.625126Z","caller":"traceutil/trace.go:171","msg":"trace[196415188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19073; }","duration":"189.642776ms","start":"2026-09-09T06:54:04.435472Z","end":"2026-09-09T06:54:04.625115Z","steps":["trace[196415188] 'agreement among raft nodes before linearized reading' (duration: 189.468942ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:06.068571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16871} {"level":"info","ts":"2026-09-09T06:54:06.168347Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16871,"took":"96.873941ms","hash":2063929359,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":31903744,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T06:54:06.168406Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2063929359,"revision":16871,"compact-revision":14580} {"level":"info","ts":"2026-09-09T06:54:11.500915Z","caller":"traceutil/trace.go:171","msg":"trace[160459458] linearizableReadLoop","detail":"{readStateIndex:19770; appliedIndex:19769; }","duration":"182.135404ms","start":"2026-09-09T06:54:11.318749Z","end":"2026-09-09T06:54:11.500884Z","steps":["trace[160459458] 'read index received' (duration: 162.990014ms)","trace[160459458] 'applied index is now lower than readState.Index' (duration: 19.14127ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:54:11.501021Z","caller":"traceutil/trace.go:171","msg":"trace[1835676690] transaction","detail":"{read_only:false; response_revision:19112; number_of_response:1; }","duration":"223.776703ms","start":"2026-09-09T06:54:11.2772Z","end":"2026-09-09T06:54:11.500977Z","steps":["trace[1835676690] 'process raft request' (duration: 204.615612ms)","trace[1835676690] 'compare' (duration: 18.901255ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:54:11.501114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.332358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T06:54:11.50116Z","caller":"traceutil/trace.go:171","msg":"trace[542051553] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:19112; }","duration":"182.443451ms","start":"2026-09-09T06:54:11.318705Z","end":"2026-09-09T06:54:11.501148Z","steps":["trace[542051553] 'agreement among raft nodes before linearized reading' (duration: 182.326808ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:12.464667Z","caller":"traceutil/trace.go:171","msg":"trace[738916727] linearizableReadLoop","detail":"{readStateIndex:19776; appliedIndex:19775; }","duration":"362.572078ms","start":"2026-09-09T06:54:12.10207Z","end":"2026-09-09T06:54:12.464642Z","steps":["trace[738916727] 'read index received' (duration: 362.431394ms)","trace[738916727] 'applied index is now lower than readState.Index' (duration: 139.574µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:54:12.464752Z","caller":"traceutil/trace.go:171","msg":"trace[1564167492] transaction","detail":"{read_only:false; response_revision:19118; number_of_response:1; }","duration":"506.87127ms","start":"2026-09-09T06:54:11.957853Z","end":"2026-09-09T06:54:12.464724Z","steps":["trace[1564167492] 'process raft request' (duration: 506.644304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:54:12.4649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:54:11.957796Z","time spent":"507.020744ms","remote":"127.0.0.1:60914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1942,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:54:12.465169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.869855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-09-09T06:54:12.465244Z","caller":"traceutil/trace.go:171","msg":"trace[1573190714] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:19118; }","duration":"363.200722ms","start":"2026-09-09T06:54:12.102027Z","end":"2026-09-09T06:54:12.465228Z","steps":["trace[1573190714] 'agreement among raft nodes before linearized reading' (duration: 362.805644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:54:12.465275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:54:12.102003Z","time spent":"363.263943ms","remote":"127.0.0.1:39798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-09T06:54:12.621111Z","caller":"traceutil/trace.go:171","msg":"trace[1419950692] linearizableReadLoop","detail":"{readStateIndex:19777; appliedIndex:19776; }","duration":"156.314379ms","start":"2026-09-09T06:54:12.464771Z","end":"2026-09-09T06:54:12.621085Z","steps":["trace[1419950692] 'read index received' (duration: 110.385191ms)","trace[1419950692] 'applied index is now lower than readState.Index' (duration: 45.927838ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:54:12.621452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.614943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:54:12.6215Z","caller":"traceutil/trace.go:171","msg":"trace[1575491006] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:19119; }","duration":"241.720236ms","start":"2026-09-09T06:54:12.379768Z","end":"2026-09-09T06:54:12.621489Z","steps":["trace[1575491006] 'agreement among raft nodes before linearized reading' (duration: 241.434698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:54:12.621572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.428251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:54:12.621611Z","caller":"traceutil/trace.go:171","msg":"trace[1718292198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19119; }","duration":"364.495023ms","start":"2026-09-09T06:54:12.257105Z","end":"2026-09-09T06:54:12.6216Z","steps":["trace[1718292198] 'agreement among raft nodes before linearized reading' (duration: 364.429802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:54:12.621633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:54:12.257087Z","time spent":"364.540354ms","remote":"127.0.0.1:39656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-09T06:54:12.62186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.757149ms","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:6"} {"level":"info","ts":"2026-09-09T06:54:12.621902Z","caller":"traceutil/trace.go:171","msg":"trace[1304389121] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:19119; }","duration":"430.872461ms","start":"2026-09-09T06:54:12.191019Z","end":"2026-09-09T06:54:12.621891Z","steps":["trace[1304389121] 'agreement among raft nodes before linearized reading' (duration: 430.769908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:54:12.621962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:54:12.191003Z","time spent":"430.947823ms","remote":"127.0.0.1:58316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-09-09T06:54:12.623538Z","caller":"traceutil/trace.go:171","msg":"trace[1693523486] transaction","detail":"{read_only:false; response_revision:19119; number_of_response:1; }","duration":"400.966702ms","start":"2026-09-09T06:54:12.221619Z","end":"2026-09-09T06:54:12.622586Z","steps":["trace[1693523486] 'process raft request' (duration: 353.523019ms)","trace[1693523486] 'compare' (duration: 45.826646ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:54:12.623614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:54:12.22159Z","time spent":"401.979905ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:54:12.62121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.403887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:54:12.624947Z","caller":"traceutil/trace.go:171","msg":"trace[175720003] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:19119; }","duration":"238.164613ms","start":"2026-09-09T06:54:12.386755Z","end":"2026-09-09T06:54:12.624919Z","steps":["trace[175720003] 'agreement among raft nodes before linearized reading' (duration: 234.355305ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:12.789688Z","caller":"traceutil/trace.go:171","msg":"trace[385543918] linearizableReadLoop","detail":"{readStateIndex:19778; appliedIndex:19777; }","duration":"168.470059ms","start":"2026-09-09T06:54:12.621176Z","end":"2026-09-09T06:54:12.789646Z","steps":["trace[385543918] 'read index received' (duration: 167.943846ms)","trace[385543918] 'applied index is now lower than readState.Index' (duration: 525.153µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:54:12.78977Z","caller":"traceutil/trace.go:171","msg":"trace[632813651] transaction","detail":"{read_only:false; response_revision:19120; number_of_response:1; }","duration":"316.680461ms","start":"2026-09-09T06:54:12.473058Z","end":"2026-09-09T06:54:12.789739Z","steps":["trace[632813651] 'process raft request' (duration: 316.044636ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:54:12.789938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:54:12.473038Z","time spent":"316.816345ms","remote":"127.0.0.1:60914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:54:12.789978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.098756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2b5adb04-9397-4357-8624-a6f7809e9ffb\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-09-09T06:54:12.790042Z","caller":"traceutil/trace.go:171","msg":"trace[1811982778] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2b5adb04-9397-4357-8624-a6f7809e9ffb; range_end:; response_count:1; response_revision:19120; }","duration":"322.197218ms","start":"2026-09-09T06:54:12.467807Z","end":"2026-09-09T06:54:12.790004Z","steps":["trace[1811982778] 'agreement among raft nodes before linearized reading' (duration: 321.964353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:54:12.790085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:54:12.467793Z","time spent":"322.281169ms","remote":"127.0.0.1:39790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1346,"request content":"key:\"/registry/persistentvolumes/pvc-2b5adb04-9397-4357-8624-a6f7809e9ffb\" "} {"level":"warn","ts":"2026-09-09T06:54:12.801279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.607969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T06:54:12.801337Z","caller":"traceutil/trace.go:171","msg":"trace[638439863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19121; }","duration":"177.696962ms","start":"2026-09-09T06:54:12.623627Z","end":"2026-09-09T06:54:12.801324Z","steps":["trace[638439863] 'agreement among raft nodes before linearized reading' (duration: 177.60319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:54:12.801728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.101422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-09T06:54:12.801767Z","caller":"traceutil/trace.go:171","msg":"trace[856361685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19121; }","duration":"132.182503ms","start":"2026-09-09T06:54:12.669574Z","end":"2026-09-09T06:54:12.801757Z","steps":["trace[856361685] 'agreement among raft nodes before linearized reading' (duration: 132.074881ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:20.79222Z","caller":"traceutil/trace.go:171","msg":"trace[1418330185] linearizableReadLoop","detail":"{readStateIndex:19934; appliedIndex:19932; }","duration":"173.805012ms","start":"2026-09-09T06:54:20.618388Z","end":"2026-09-09T06:54:20.792193Z","steps":["trace[1418330185] 'read index received' (duration: 54.345992ms)","trace[1418330185] 'applied index is now lower than readState.Index' (duration: 119.4577ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:54:20.792323Z","caller":"traceutil/trace.go:171","msg":"trace[1055464992] transaction","detail":"{read_only:false; response_revision:19272; number_of_response:1; }","duration":"169.650476ms","start":"2026-09-09T06:54:20.622666Z","end":"2026-09-09T06:54:20.792316Z","steps":["trace[1055464992] 'process raft request' (duration: 169.39515ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:20.792489Z","caller":"traceutil/trace.go:171","msg":"trace[2110784530] transaction","detail":"{read_only:false; response_revision:19271; number_of_response:1; }","duration":"174.382774ms","start":"2026-09-09T06:54:20.618089Z","end":"2026-09-09T06:54:20.792471Z","steps":["trace[2110784530] 'process raft request' (duration: 171.356825ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:20.792647Z","caller":"traceutil/trace.go:171","msg":"trace[1237306513] transaction","detail":"{read_only:false; response_revision:19273; number_of_response:1; }","duration":"141.421296ms","start":"2026-09-09T06:54:20.651217Z","end":"2026-09-09T06:54:20.792638Z","steps":["trace[1237306513] 'process raft request' (duration: 140.922304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:54:20.792894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.487738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-09-09T06:54:20.792933Z","caller":"traceutil/trace.go:171","msg":"trace[2008199798] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:19273; }","duration":"174.557269ms","start":"2026-09-09T06:54:20.618362Z","end":"2026-09-09T06:54:20.792919Z","steps":["trace[2008199798] 'agreement among raft nodes before linearized reading' (duration: 174.410726ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:20.797196Z","caller":"traceutil/trace.go:171","msg":"trace[1284186070] transaction","detail":"{read_only:false; response_revision:19274; number_of_response:1; }","duration":"101.584539ms","start":"2026-09-09T06:54:20.695598Z","end":"2026-09-09T06:54:20.797182Z","steps":["trace[1284186070] 'process raft request' (duration: 101.256802ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:20.797318Z","caller":"traceutil/trace.go:171","msg":"trace[1415029992] transaction","detail":"{read_only:false; response_revision:19275; number_of_response:1; }","duration":"101.62345ms","start":"2026-09-09T06:54:20.695686Z","end":"2026-09-09T06:54:20.79731Z","steps":["trace[1415029992] 'process raft request' (duration: 101.252742ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:20.7974Z","caller":"traceutil/trace.go:171","msg":"trace[1070538593] transaction","detail":"{read_only:false; response_revision:19276; number_of_response:1; }","duration":"101.63831ms","start":"2026-09-09T06:54:20.695755Z","end":"2026-09-09T06:54:20.797393Z","steps":["trace[1070538593] 'process raft request' (duration: 101.208811ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:20.79748Z","caller":"traceutil/trace.go:171","msg":"trace[1714527778] transaction","detail":"{read_only:false; response_revision:19277; number_of_response:1; }","duration":"101.520748ms","start":"2026-09-09T06:54:20.695952Z","end":"2026-09-09T06:54:20.797473Z","steps":["trace[1714527778] 'process raft request' (duration: 101.028617ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:20.797558Z","caller":"traceutil/trace.go:171","msg":"trace[886987156] transaction","detail":"{read_only:false; response_revision:19278; number_of_response:1; }","duration":"100.984145ms","start":"2026-09-09T06:54:20.696564Z","end":"2026-09-09T06:54:20.797548Z","steps":["trace[886987156] 'process raft request' (duration: 100.435412ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:20.797637Z","caller":"traceutil/trace.go:171","msg":"trace[764648789] transaction","detail":"{read_only:false; response_revision:19279; number_of_response:1; }","duration":"100.582706ms","start":"2026-09-09T06:54:20.697048Z","end":"2026-09-09T06:54:20.797631Z","steps":["trace[764648789] 'process raft request' (duration: 99.969683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:54:20.798396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.995464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T06:54:20.798443Z","caller":"traceutil/trace.go:171","msg":"trace[1691289002] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:19281; }","duration":"147.074776ms","start":"2026-09-09T06:54:20.651355Z","end":"2026-09-09T06:54:20.79843Z","steps":["trace[1691289002] 'agreement among raft nodes before linearized reading' (duration: 146.971374ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:54:25.043078Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T06:54:25.051294Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T06:54:25.051401Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T06:59:06.077215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19083} {"level":"info","ts":"2026-09-09T06:59:06.18994Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19083,"took":"109.382308ms","hash":2932698196,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":33964032,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-09T06:59:06.190021Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2932698196,"revision":19083,"compact-revision":16871} {"level":"info","ts":"2026-09-09T07:00:11.361661Z","caller":"traceutil/trace.go:171","msg":"trace[113405304] linearizableReadLoop","detail":"{readStateIndex:22701; appliedIndex:22700; }","duration":"316.758923ms","start":"2026-09-09T07:00:11.044886Z","end":"2026-09-09T07:00:11.361645Z","steps":["trace[113405304] 'read index received' (duration: 316.688441ms)","trace[113405304] 'applied index is now lower than readState.Index' (duration: 70.092µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:00:11.361741Z","caller":"traceutil/trace.go:171","msg":"trace[956418488] transaction","detail":"{read_only:false; response_revision:21953; number_of_response:1; }","duration":"319.083057ms","start":"2026-09-09T07:00:11.042651Z","end":"2026-09-09T07:00:11.361734Z","steps":["trace[956418488] 'process raft request' (duration: 318.894883ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:11.361863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:11.042634Z","time spent":"319.123207ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:00:11.361959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.315267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T07:00:11.362003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.116931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29815620-gbddl\" ","response":"range_response_count:1 size:7361"} {"level":"info","ts":"2026-09-09T07:00:11.36203Z","caller":"traceutil/trace.go:171","msg":"trace[1976412253] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29815620-gbddl; range_end:; response_count:1; response_revision:21953; }","duration":"317.160051ms","start":"2026-09-09T07:00:11.044863Z","end":"2026-09-09T07:00:11.362023Z","steps":["trace[1976412253] 'agreement among raft nodes before linearized reading' (duration: 317.08512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:11.362052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:11.044855Z","time spent":"317.192363ms","remote":"127.0.0.1:39826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":7384,"request content":"key:\"/registry/pods/openstack/heat-engine-cleaner-29815620-gbddl\" "} {"level":"info","ts":"2026-09-09T07:00:11.362009Z","caller":"traceutil/trace.go:171","msg":"trace[1399832682] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:21953; }","duration":"284.393268ms","start":"2026-09-09T07:00:11.077605Z","end":"2026-09-09T07:00:11.361998Z","steps":["trace[1399832682] 'agreement among raft nodes before linearized reading' (duration: 284.316046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:11.61965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.038168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T07:00:11.619743Z","caller":"traceutil/trace.go:171","msg":"trace[820973488] linearizableReadLoop","detail":"{readStateIndex:22702; appliedIndex:22701; }","duration":"224.192132ms","start":"2026-09-09T07:00:11.395536Z","end":"2026-09-09T07:00:11.619728Z","steps":["trace[820973488] 'read index received' (duration: 37.131µs)","trace[820973488] 'applied index is now lower than readState.Index' (duration: 224.153901ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:00:11.620017Z","caller":"traceutil/trace.go:171","msg":"trace[1283383179] transaction","detail":"{read_only:false; response_revision:21954; number_of_response:1; }","duration":"255.336989ms","start":"2026-09-09T07:00:11.364651Z","end":"2026-09-09T07:00:11.619988Z","steps":["trace[1283383179] 'process raft request' (duration: 30.886331ms)","trace[1283383179] 'compare' (duration: 223.543367ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:00:11.620081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.53317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-09T07:00:11.620082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.846883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-09T07:00:11.620132Z","caller":"traceutil/trace.go:171","msg":"trace[747518610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21954; }","duration":"224.614102ms","start":"2026-09-09T07:00:11.395501Z","end":"2026-09-09T07:00:11.620115Z","steps":["trace[747518610] 'agreement among raft nodes before linearized reading' (duration: 224.457089ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:00:11.620151Z","caller":"traceutil/trace.go:171","msg":"trace[1798954101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21954; }","duration":"206.954605ms","start":"2026-09-09T07:00:11.413182Z","end":"2026-09-09T07:00:11.620136Z","steps":["trace[1798954101] 'agreement among raft nodes before linearized reading' (duration: 206.762721ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:11.620163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.334303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-09T07:00:11.620214Z","caller":"traceutil/trace.go:171","msg":"trace[1681061787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21954; }","duration":"147.411655ms","start":"2026-09-09T07:00:11.472788Z","end":"2026-09-09T07:00:11.620199Z","steps":["trace[1681061787] 'agreement among raft nodes before linearized reading' (duration: 147.266722ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:11.933101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.914843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T07:00:11.933375Z","caller":"traceutil/trace.go:171","msg":"trace[1164076316] transaction","detail":"{read_only:false; response_revision:21957; number_of_response:1; }","duration":"309.052115ms","start":"2026-09-09T07:00:11.624302Z","end":"2026-09-09T07:00:11.933354Z","steps":["trace[1164076316] 'process raft request' (duration: 309.002884ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:00:11.93336Z","caller":"traceutil/trace.go:171","msg":"trace[21789295] transaction","detail":"{read_only:false; response_revision:21955; number_of_response:1; }","duration":"309.872224ms","start":"2026-09-09T07:00:11.623448Z","end":"2026-09-09T07:00:11.93332Z","steps":["trace[21789295] 'process raft request' (duration: 112.651064ms)","trace[21789295] 'compare' (duration: 196.436102ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:00:11.933459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:11.624285Z","time spent":"309.132907ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:00:11.933546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:11.623432Z","time spent":"310.004697ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T07:00:11.933588Z","caller":"traceutil/trace.go:171","msg":"trace[944982432] transaction","detail":"{read_only:false; response_revision:21956; number_of_response:1; }","duration":"309.63162ms","start":"2026-09-09T07:00:11.623944Z","end":"2026-09-09T07:00:11.933575Z","steps":["trace[944982432] 'process raft request' (duration: 309.288831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:11.933647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:11.623925Z","time spent":"309.689461ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:00:12.297002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.348734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-09T07:00:12.297077Z","caller":"traceutil/trace.go:171","msg":"trace[2052287184] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:21957; }","duration":"250.464827ms","start":"2026-09-09T07:00:12.046596Z","end":"2026-09-09T07:00:12.297061Z","steps":["trace[2052287184] 'count revisions from in-memory index tree' (duration: 250.269273ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:12.297534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.255068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11787"} {"level":"info","ts":"2026-09-09T07:00:12.297575Z","caller":"traceutil/trace.go:171","msg":"trace[1662757481] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:21957; }","duration":"116.34103ms","start":"2026-09-09T07:00:12.181224Z","end":"2026-09-09T07:00:12.297565Z","steps":["trace[1662757481] 'range keys from in-memory index tree' (duration: 116.103412ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:00:12.512584Z","caller":"traceutil/trace.go:171","msg":"trace[1254104081] linearizableReadLoop","detail":"{readStateIndex:22706; appliedIndex:22705; }","duration":"270.425696ms","start":"2026-09-09T07:00:12.242139Z","end":"2026-09-09T07:00:12.512565Z","steps":["trace[1254104081] 'read index received' (duration: 270.309023ms)","trace[1254104081] 'applied index is now lower than readState.Index' (duration: 115.733µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:00:12.513042Z","caller":"traceutil/trace.go:171","msg":"trace[1849201531] transaction","detail":"{read_only:false; response_revision:21958; number_of_response:1; }","duration":"330.507939ms","start":"2026-09-09T07:00:12.182522Z","end":"2026-09-09T07:00:12.51303Z","steps":["trace[1849201531] 'process raft request' (duration: 329.925766ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:12.513129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:12.182499Z","time spent":"330.575081ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:00:12.513285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.149344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-09T07:00:12.513317Z","caller":"traceutil/trace.go:171","msg":"trace[1265622470] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:21958; }","duration":"271.218624ms","start":"2026-09-09T07:00:12.242088Z","end":"2026-09-09T07:00:12.513306Z","steps":["trace[1265622470] 'agreement among raft nodes before linearized reading' (duration: 271.170123ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:00:12.520223Z","caller":"traceutil/trace.go:171","msg":"trace[1334748715] transaction","detail":"{read_only:false; response_revision:21959; number_of_response:1; }","duration":"112.963351ms","start":"2026-09-09T07:00:12.407242Z","end":"2026-09-09T07:00:12.520206Z","steps":["trace[1334748715] 'process raft request' (duration: 112.795266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:12.520305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.960355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T07:00:12.520336Z","caller":"traceutil/trace.go:171","msg":"trace[249641435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21959; }","duration":"263.044377ms","start":"2026-09-09T07:00:12.257285Z","end":"2026-09-09T07:00:12.520329Z","steps":["trace[249641435] 'agreement among raft nodes before linearized reading' (duration: 262.886803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:12.520389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.292117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11787"} {"level":"info","ts":"2026-09-09T07:00:12.520452Z","caller":"traceutil/trace.go:171","msg":"trace[1049113243] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:21959; }","duration":"215.400359ms","start":"2026-09-09T07:00:12.305037Z","end":"2026-09-09T07:00:12.520437Z","steps":["trace[1049113243] 'agreement among raft nodes before linearized reading' (duration: 215.194745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:13.086416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.202024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T07:00:13.086678Z","caller":"traceutil/trace.go:171","msg":"trace[1761656363] linearizableReadLoop","detail":"{readStateIndex:22708; appliedIndex:22707; }","duration":"370.226973ms","start":"2026-09-09T07:00:12.716423Z","end":"2026-09-09T07:00:13.08665Z","steps":["trace[1761656363] 'read index received' (duration: 181.642431ms)","trace[1761656363] 'applied index is now lower than readState.Index' (duration: 188.579432ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:00:13.086791Z","caller":"traceutil/trace.go:171","msg":"trace[549307358] transaction","detail":"{read_only:false; response_revision:21960; number_of_response:1; }","duration":"548.868427ms","start":"2026-09-09T07:00:12.537911Z","end":"2026-09-09T07:00:13.086779Z","steps":["trace[549307358] 'process raft request' (duration: 360.236894ms)","trace[549307358] 'compare' (duration: 187.935918ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:00:13.086899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:12.537886Z","time spent":"548.922198ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:00:13.087087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.333672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418288"} {"level":"info","ts":"2026-09-09T07:00:13.087138Z","caller":"traceutil/trace.go:171","msg":"trace[620538580] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:21961; }","duration":"177.407194ms","start":"2026-09-09T07:00:12.909717Z","end":"2026-09-09T07:00:13.087124Z","steps":["trace[620538580] 'agreement among raft nodes before linearized reading' (duration: 177.21448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:13.087248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.822318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-09T07:00:13.087278Z","caller":"traceutil/trace.go:171","msg":"trace[1167500248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21961; }","duration":"370.892289ms","start":"2026-09-09T07:00:12.716378Z","end":"2026-09-09T07:00:13.08727Z","steps":["trace[1167500248] 'agreement among raft nodes before linearized reading' (duration: 370.769476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:00:13.087301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:00:12.71636Z","time spent":"370.933921ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-09T07:00:13.08734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.875006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T07:00:13.087366Z","caller":"traceutil/trace.go:171","msg":"trace[101120025] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:21961; }","duration":"144.903216ms","start":"2026-09-09T07:00:12.942454Z","end":"2026-09-09T07:00:13.087357Z","steps":["trace[101120025] 'agreement among raft nodes before linearized reading' (duration: 144.854675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:02:06.644578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.585742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T07:02:06.64472Z","caller":"traceutil/trace.go:171","msg":"trace[1129325467] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-user; range_end:; response_count:0; response_revision:22903; }","duration":"105.709844ms","start":"2026-09-09T07:02:06.538961Z","end":"2026-09-09T07:02:06.64467Z","steps":["trace[1129325467] 'range keys from in-memory index tree' (duration: 105.52137ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:02:06.64475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.507772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/octavia-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T07:02:06.644994Z","caller":"traceutil/trace.go:171","msg":"trace[723850676] range","detail":"{range_begin:/registry/jobs/openstack/octavia-ks-endpoints; range_end:; response_count:0; response_revision:22903; }","duration":"106.739788ms","start":"2026-09-09T07:02:06.538124Z","end":"2026-09-09T07:02:06.644864Z","steps":["trace[723850676] 'range keys from in-memory index tree' (duration: 106.42032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:02:06.644786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.396307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-09-09T07:02:06.645112Z","caller":"traceutil/trace.go:171","msg":"trace[2026283966] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22903; }","duration":"105.738534ms","start":"2026-09-09T07:02:06.539349Z","end":"2026-09-09T07:02:06.645087Z","steps":["trace[2026283966] 'range keys from in-memory index tree' (duration: 105.344225ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:06.085816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21510} {"level":"info","ts":"2026-09-09T07:04:06.205621Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21510,"took":"116.126574ms","hash":433948471,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":31182848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T07:04:06.205761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":433948471,"revision":21510,"compact-revision":19083} {"level":"info","ts":"2026-09-09T07:04:29.954456Z","caller":"traceutil/trace.go:171","msg":"trace[569098081] transaction","detail":"{read_only:false; response_revision:24404; number_of_response:1; }","duration":"110.018333ms","start":"2026-09-09T07:04:29.844414Z","end":"2026-09-09T07:04:29.954432Z","steps":["trace[569098081] 'process raft request' (duration: 109.894511ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:29.954482Z","caller":"traceutil/trace.go:171","msg":"trace[1316519459] transaction","detail":"{read_only:false; response_revision:24403; number_of_response:1; }","duration":"111.069297ms","start":"2026-09-09T07:04:29.843373Z","end":"2026-09-09T07:04:29.954442Z","steps":["trace[1316519459] 'process raft request' (duration: 110.798911ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:29.954591Z","caller":"traceutil/trace.go:171","msg":"trace[690840922] linearizableReadLoop","detail":"{readStateIndex:25229; appliedIndex:25226; }","duration":"108.99656ms","start":"2026-09-09T07:04:29.84558Z","end":"2026-09-09T07:04:29.954577Z","steps":["trace[690840922] 'read index received' (duration: 26.47703ms)","trace[690840922] 'applied index is now lower than readState.Index' (duration: 82.51882ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:04:29.954758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.160253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-09-09T07:04:29.954781Z","caller":"traceutil/trace.go:171","msg":"trace[2123841059] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:24405; }","duration":"109.216875ms","start":"2026-09-09T07:04:29.845555Z","end":"2026-09-09T07:04:29.954772Z","steps":["trace[2123841059] 'agreement among raft nodes before linearized reading' (duration: 109.064782ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:37.016048Z","caller":"traceutil/trace.go:171","msg":"trace[1463519022] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"341.811149ms","start":"2026-09-09T07:04:36.674208Z","end":"2026-09-09T07:04:37.016019Z","steps":["trace[1463519022] 'process raft request' (duration: 290.727414ms)","trace[1463519022] 'compare' (duration: 49.167752ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:04:37.016192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:04:36.674161Z","time spent":"341.961603ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T07:04:37.016279Z","caller":"traceutil/trace.go:171","msg":"trace[724307917] linearizableReadLoop","detail":"{readStateIndex:25395; appliedIndex:25394; }","duration":"295.367701ms","start":"2026-09-09T07:04:36.720882Z","end":"2026-09-09T07:04:37.01625Z","steps":["trace[724307917] 'read index received' (duration: 244.147501ms)","trace[724307917] 'applied index is now lower than readState.Index' (duration: 50.913322ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:04:37.016301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.422641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-09T07:04:37.016374Z","caller":"traceutil/trace.go:171","msg":"trace[1576976521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24568; }","duration":"295.556635ms","start":"2026-09-09T07:04:36.720799Z","end":"2026-09-09T07:04:37.016355Z","steps":["trace[1576976521] 'agreement among raft nodes before linearized reading' (duration: 295.336509ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:37.145997Z","caller":"traceutil/trace.go:171","msg":"trace[986004183] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"341.652116ms","start":"2026-09-09T07:04:36.80431Z","end":"2026-09-09T07:04:37.145962Z","steps":["trace[986004183] 'process raft request' (duration: 312.716601ms)","trace[986004183] 'compare' (duration: 28.656359ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T07:04:37.146143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:04:36.80429Z","time spent":"341.776909ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T07:04:37.14651Z","caller":"traceutil/trace.go:171","msg":"trace[1038757583] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"125.52301ms","start":"2026-09-09T07:04:37.02097Z","end":"2026-09-09T07:04:37.146493Z","steps":["trace[1038757583] 'process raft request' (duration: 125.323285ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:37.454477Z","caller":"traceutil/trace.go:171","msg":"trace[1794317133] linearizableReadLoop","detail":"{readStateIndex:25398; appliedIndex:25397; }","duration":"298.125304ms","start":"2026-09-09T07:04:37.156325Z","end":"2026-09-09T07:04:37.45445Z","steps":["trace[1794317133] 'read index received' (duration: 288.718417ms)","trace[1794317133] 'applied index is now lower than readState.Index' (duration: 9.405667ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T07:04:37.454479Z","caller":"traceutil/trace.go:171","msg":"trace[1442955733] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"302.876844ms","start":"2026-09-09T07:04:37.151573Z","end":"2026-09-09T07:04:37.45445Z","steps":["trace[1442955733] 'process raft request' (duration: 293.457747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T07:04:37.455001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.63801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T07:04:37.455069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T07:04:37.151549Z","time spent":"303.230092ms","remote":"127.0.0.1:39938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T07:04:37.455066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.740817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/pxc-operator\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-09T07:04:37.455108Z","caller":"traceutil/trace.go:171","msg":"trace[1467467734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24572; }","duration":"197.792033ms","start":"2026-09-09T07:04:37.257301Z","end":"2026-09-09T07:04:37.455093Z","steps":["trace[1467467734] 'agreement among raft nodes before linearized reading' (duration: 197.63124ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:04:37.455146Z","caller":"traceutil/trace.go:171","msg":"trace[888470531] range","detail":"{range_begin:/registry/serviceaccounts/openstack/pxc-operator; range_end:; response_count:1; response_revision:24572; }","duration":"298.881551ms","start":"2026-09-09T07:04:37.156246Z","end":"2026-09-09T07:04:37.455128Z","steps":["trace[888470531] 'agreement among raft nodes before linearized reading' (duration: 298.508443ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T07:05:28.652292Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000065c5.wal"} {"level":"info","ts":"2026-09-09T07:09:06.094631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23795} {"level":"info","ts":"2026-09-09T07:09:06.224064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23795,"took":"122.183448ms","hash":2112185235,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":42188800,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-09T07:09:06.224142Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2112185235,"revision":23795,"compact-revision":21510} {"level":"info","ts":"2026-09-09T07:13:52.769148Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"83287f3a81241ad4","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T07:13:52.774373Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-09T07:13:52.774482Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-09T07:14:06.101374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26920} {"level":"info","ts":"2026-09-09T07:14:06.249397Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26920,"took":"144.562482ms","hash":249752525,"current-db-size-bytes":57679872,"current-db-size":"58 MB","current-db-size-in-use-bytes":29884416,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T07:14:06.249488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":249752525,"revision":26920,"compact-revision":23795}