{"level":"warn","ts":"2026-06-02T21:31:01.816224Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T21:31:01.816317Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-06-02T21:31:01.816384Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T21:31:01.816396Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-06-02T21:31:01.816415Z","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-06-02T21:31:01.817035Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-06-02T21:31:01.817113Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-06-02T21:31:01.820987Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.667678ms"} {"level":"info","ts":"2026-06-02T21:31:01.826098Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-06-02T21:31:01.826183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T21:31:01.826208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-06-02T21:31:01.826231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T21:31:01.826242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-06-02T21:31:01.826295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-06-02T21:31:01.838179Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T21:31:01.839728Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T21:31:01.840923Z","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-06-02T21:31:01.843538Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T21:31:01.844484Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T21:31:01.844751Z","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-06-02T21:31:01.844801Z","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-06-02T21:31:01.844824Z","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-06-02T21:31:01.84607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-06-02T21:31:01.846349Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-06-02T21:31:01.848537Z","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-06-02T21:31:01.848642Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-06-02T21:31:01.848689Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-06-02T21:31:01.848857Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T21:31:01.848914Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T21:31:02.827508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T21:31:02.827574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T21:31:02.827776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-06-02T21:31:02.827822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-06-02T21:31:02.827839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-06-02T21:31:02.827856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-06-02T21:31:02.827929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-06-02T21:31:02.829157Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:31:02.830336Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T21:31:02.830335Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T21:31:02.830407Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T21:31:02.830735Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:31:02.830981Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:31:02.831037Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T21:31:02.831204Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T21:31:02.831473Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T21:31:02.833822Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T21:31:02.833981Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-06-02T21:31:28.995877Z","caller":"traceutil/trace.go:171","msg":"trace[1848415043] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"100.054348ms","start":"2026-06-02T21:31:28.8958Z","end":"2026-06-02T21:31:28.995854Z","steps":["trace[1848415043] 'process raft request' (duration: 99.923135ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:31:44.620783Z","caller":"traceutil/trace.go:171","msg":"trace[1563864343] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"309.23602ms","start":"2026-06-02T21:31:44.311504Z","end":"2026-06-02T21:31:44.62074Z","steps":["trace[1563864343] 'process raft request' (duration: 304.008694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:44.621599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:31:44.311469Z","time spent":"309.402264ms","remote":"127.0.0.1:44242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036273,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:31:45.164315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.39488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:31:45.164576Z","caller":"traceutil/trace.go:171","msg":"trace[96645767] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"468.611803ms","start":"2026-06-02T21:31:44.695928Z","end":"2026-06-02T21:31:45.16454Z","steps":["trace[96645767] 'read index received' (duration: 216.850024ms)","trace[96645767] 'applied index is now lower than readState.Index' (duration: 251.757329ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:31:45.164595Z","caller":"traceutil/trace.go:171","msg":"trace[1267903652] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"490.458124ms","start":"2026-06-02T21:31:44.6741Z","end":"2026-06-02T21:31:45.164558Z","steps":["trace[1267903652] 'process raft request' (duration: 238.627734ms)","trace[1267903652] 'compare' (duration: 251.242426ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:31:45.164879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:31:44.674074Z","time spent":"490.720101ms","remote":"127.0.0.1:44660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T21:31:45.164882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.930611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T21:31:45.164968Z","caller":"traceutil/trace.go:171","msg":"trace[1560805573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"469.062473ms","start":"2026-06-02T21:31:44.695879Z","end":"2026-06-02T21:31:45.164942Z","steps":["trace[1560805573] 'agreement among raft nodes before linearized reading' (duration: 468.776977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:31:45.165029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:31:44.695863Z","time spent":"469.152866ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T21:32:06.178733Z","caller":"traceutil/trace.go:171","msg":"trace[1919672305] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"170.462694ms","start":"2026-06-02T21:32:06.008244Z","end":"2026-06-02T21:32:06.178707Z","steps":["trace[1919672305] 'process raft request' (duration: 170.309651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:33:17.251763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.886253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:33:17.251886Z","caller":"traceutil/trace.go:171","msg":"trace[1823724092] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"193.598619ms","start":"2026-06-02T21:33:17.058268Z","end":"2026-06-02T21:33:17.251867Z","steps":["trace[1823724092] 'read index received' (duration: 61.952µs)","trace[1823724092] 'applied index is now lower than readState.Index' (duration: 193.535167ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:33:17.251947Z","caller":"traceutil/trace.go:171","msg":"trace[276927] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"346.977436ms","start":"2026-06-02T21:33:16.904913Z","end":"2026-06-02T21:33:17.251891Z","steps":["trace[276927] 'process raft request' (duration: 72.883049ms)","trace[276927] 'compare' (duration: 273.654177ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:33:17.252132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:33:16.904898Z","time spent":"347.140081ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T21:33:17.252169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.900377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-286f3331-e288-4b04-a413-3ce40cc3de4f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-02T21:33:17.252264Z","caller":"traceutil/trace.go:171","msg":"trace[1807570991] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-286f3331-e288-4b04-a413-3ce40cc3de4f; range_end:; response_count:1; response_revision:1864; }","duration":"194.05303ms","start":"2026-06-02T21:33:17.058187Z","end":"2026-06-02T21:33:17.25224Z","steps":["trace[1807570991] 'agreement among raft nodes before linearized reading' (duration: 193.765923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:33:17.252693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.409469ms","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-06-02T21:33:17.252773Z","caller":"traceutil/trace.go:171","msg":"trace[1588146100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1864; }","duration":"124.529012ms","start":"2026-06-02T21:33:17.12823Z","end":"2026-06-02T21:33:17.252759Z","steps":["trace[1588146100] 'agreement among raft nodes before linearized reading' (duration: 124.352928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:33:17.252712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.771584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-286f3331-e288-4b04-a413-3ce40cc3de4f\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-02T21:33:17.253418Z","caller":"traceutil/trace.go:171","msg":"trace[145368779] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-286f3331-e288-4b04-a413-3ce40cc3de4f; range_end:; response_count:1; response_revision:1864; }","duration":"184.498752ms","start":"2026-06-02T21:33:17.068898Z","end":"2026-06-02T21:33:17.253397Z","steps":["trace[145368779] 'agreement among raft nodes before linearized reading' (duration: 183.665792ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:34:01.715008Z","caller":"traceutil/trace.go:171","msg":"trace[2027111021] linearizableReadLoop","detail":"{readStateIndex:2238; appliedIndex:2237; }","duration":"101.228058ms","start":"2026-06-02T21:34:01.613753Z","end":"2026-06-02T21:34:01.714982Z","steps":["trace[2027111021] 'read index received' (duration: 37.579197ms)","trace[2027111021] 'applied index is now lower than readState.Index' (duration: 63.647781ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:34:01.715235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.472985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:34:01.715327Z","caller":"traceutil/trace.go:171","msg":"trace[484294515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2161; }","duration":"101.625298ms","start":"2026-06-02T21:34:01.613687Z","end":"2026-06-02T21:34:01.715312Z","steps":["trace[484294515] 'agreement among raft nodes before linearized reading' (duration: 101.493445ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:34:01.715346Z","caller":"traceutil/trace.go:171","msg":"trace[1600712105] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"236.176599ms","start":"2026-06-02T21:34:01.479146Z","end":"2026-06-02T21:34:01.715322Z","steps":["trace[1600712105] 'process raft request' (duration: 172.253541ms)","trace[1600712105] 'compare' (duration: 63.310444ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:34:07.899631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.892412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:34:07.899736Z","caller":"traceutil/trace.go:171","msg":"trace[1206333586] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2268; }","duration":"161.442837ms","start":"2026-06-02T21:34:07.738277Z","end":"2026-06-02T21:34:07.89972Z","steps":["trace[1206333586] 'read index received' (duration: 14.32005ms)","trace[1206333586] 'applied index is now lower than readState.Index' (duration: 147.121397ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:34:07.899823Z","caller":"traceutil/trace.go:171","msg":"trace[262203411] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"283.169425ms","start":"2026-06-02T21:34:07.616641Z","end":"2026-06-02T21:34:07.899811Z","steps":["trace[262203411] 'process raft request' (duration: 136.002047ms)","trace[262203411] 'compare' (duration: 146.661316ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:34:07.900254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.95468ms","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-06-02T21:34:07.900352Z","caller":"traceutil/trace.go:171","msg":"trace[291709813] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2191; }","duration":"162.217656ms","start":"2026-06-02T21:34:07.738112Z","end":"2026-06-02T21:34:07.900329Z","steps":["trace[291709813] 'agreement among raft nodes before linearized reading' (duration: 161.902278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:34:08.124851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.99459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:34:08.12498Z","caller":"traceutil/trace.go:171","msg":"trace[478308776] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"193.818856ms","start":"2026-06-02T21:34:07.931143Z","end":"2026-06-02T21:34:08.124962Z","steps":["trace[478308776] 'read index received' (duration: 88.55059ms)","trace[478308776] 'applied index is now lower than readState.Index' (duration: 105.265566ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:34:08.125084Z","caller":"traceutil/trace.go:171","msg":"trace[434020761] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"220.336084ms","start":"2026-06-02T21:34:07.90473Z","end":"2026-06-02T21:34:08.125066Z","steps":["trace[434020761] 'process raft request' (duration: 115.016975ms)","trace[434020761] 'compare' (duration: 104.880707ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:34:08.12531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.649175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T21:34:08.125346Z","caller":"traceutil/trace.go:171","msg":"trace[117865634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2192; }","duration":"164.793389ms","start":"2026-06-02T21:34:07.960544Z","end":"2026-06-02T21:34:08.125338Z","steps":["trace[117865634] 'agreement among raft nodes before linearized reading' (duration: 164.529823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:34:08.125345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.190007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-02T21:34:08.125434Z","caller":"traceutil/trace.go:171","msg":"trace[1465747331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2192; }","duration":"194.323289ms","start":"2026-06-02T21:34:07.93109Z","end":"2026-06-02T21:34:08.125413Z","steps":["trace[1465747331] 'agreement among raft nodes before linearized reading' (duration: 194.082233ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:35:32.992142Z","caller":"traceutil/trace.go:171","msg":"trace[1504167865] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"152.441449ms","start":"2026-06-02T21:35:32.839655Z","end":"2026-06-02T21:35:32.992097Z","steps":["trace[1504167865] 'process raft request' (duration: 68.816934ms)","trace[1504167865] 'compare' (duration: 83.322968ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:35:32.992327Z","caller":"traceutil/trace.go:171","msg":"trace[53628061] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"149.331033ms","start":"2026-06-02T21:35:32.842732Z","end":"2026-06-02T21:35:32.992063Z","steps":["trace[53628061] 'process raft request' (duration: 149.278122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:35:33.289941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.817084ms","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-06-02T21:35:33.29006Z","caller":"traceutil/trace.go:171","msg":"trace[2031448803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2849; }","duration":"107.988358ms","start":"2026-06-02T21:35:33.182042Z","end":"2026-06-02T21:35:33.29003Z","steps":["trace[2031448803] 'range keys from in-memory index tree' (duration: 107.601129ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:35:33.467557Z","caller":"traceutil/trace.go:171","msg":"trace[1758573011] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"170.574011ms","start":"2026-06-02T21:35:33.296963Z","end":"2026-06-02T21:35:33.467537Z","steps":["trace[1758573011] 'process raft request' (duration: 170.427057ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:37:59.147543Z","caller":"traceutil/trace.go:171","msg":"trace[1883269344] linearizableReadLoop","detail":"{readStateIndex:3956; appliedIndex:3955; }","duration":"104.927351ms","start":"2026-06-02T21:37:59.042592Z","end":"2026-06-02T21:37:59.147519Z","steps":["trace[1883269344] 'read index received' (duration: 104.634454ms)","trace[1883269344] 'applied index is now lower than readState.Index' (duration: 281.966µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:37:59.147564Z","caller":"traceutil/trace.go:171","msg":"trace[1551511066] transaction","detail":"{read_only:false; response_revision:3818; number_of_response:1; }","duration":"116.716453ms","start":"2026-06-02T21:37:59.030811Z","end":"2026-06-02T21:37:59.147527Z","steps":["trace[1551511066] 'process raft request' (duration: 116.512068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:37:59.147778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.172377ms","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-06-02T21:37:59.147916Z","caller":"traceutil/trace.go:171","msg":"trace[1823949313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3818; }","duration":"105.364412ms","start":"2026-06-02T21:37:59.042534Z","end":"2026-06-02T21:37:59.147899Z","steps":["trace[1823949313] 'agreement among raft nodes before linearized reading' (duration: 105.091835ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:39:24.902215Z","caller":"traceutil/trace.go:171","msg":"trace[603304996] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"204.021199ms","start":"2026-06-02T21:39:24.698169Z","end":"2026-06-02T21:39:24.90219Z","steps":["trace[603304996] 'process raft request' (duration: 203.787704ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:39:25.039659Z","caller":"traceutil/trace.go:171","msg":"trace[2049123398] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"130.720516ms","start":"2026-06-02T21:39:24.908918Z","end":"2026-06-02T21:39:25.039639Z","steps":["trace[2049123398] 'process raft request' (duration: 130.594673ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:39:25.26892Z","caller":"traceutil/trace.go:171","msg":"trace[1206435619] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"119.035337ms","start":"2026-06-02T21:39:25.149856Z","end":"2026-06-02T21:39:25.268892Z","steps":["trace[1206435619] 'process raft request' (duration: 99.700275ms)","trace[1206435619] 'compare' (duration: 19.164027ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:39:25.269171Z","caller":"traceutil/trace.go:171","msg":"trace[235262691] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"119.18363ms","start":"2026-06-02T21:39:25.149958Z","end":"2026-06-02T21:39:25.269142Z","steps":["trace[235262691] 'process raft request' (duration: 118.887293ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:39:25.269284Z","caller":"traceutil/trace.go:171","msg":"trace[1577784704] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"114.719853ms","start":"2026-06-02T21:39:25.154538Z","end":"2026-06-02T21:39:25.269258Z","steps":["trace[1577784704] 'process raft request' (duration: 114.513198ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:39:38.130525Z","caller":"traceutil/trace.go:171","msg":"trace[1477950194] linearizableReadLoop","detail":"{readStateIndex:5447; appliedIndex:5446; }","duration":"189.839939ms","start":"2026-06-02T21:39:37.940658Z","end":"2026-06-02T21:39:38.130498Z","steps":["trace[1477950194] 'read index received' (duration: 159.307539ms)","trace[1477950194] 'applied index is now lower than readState.Index' (duration: 30.53124ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:39:38.130555Z","caller":"traceutil/trace.go:171","msg":"trace[2024222158] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"201.502659ms","start":"2026-06-02T21:39:37.929006Z","end":"2026-06-02T21:39:38.130509Z","steps":["trace[2024222158] 'process raft request' (duration: 170.981049ms)","trace[2024222158] 'compare' (duration: 30.361156ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:39:38.130776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.092746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T21:39:38.130868Z","caller":"traceutil/trace.go:171","msg":"trace[759007200] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:5255; }","duration":"190.23981ms","start":"2026-06-02T21:39:37.940608Z","end":"2026-06-02T21:39:38.130848Z","steps":["trace[759007200] 'agreement among raft nodes before linearized reading' (duration: 190.028045ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:39:38.225637Z","caller":"traceutil/trace.go:171","msg":"trace[940118337] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"263.757717ms","start":"2026-06-02T21:39:37.961855Z","end":"2026-06-02T21:39:38.225613Z","steps":["trace[940118337] 'process raft request' (duration: 263.580653ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:39:38.225921Z","caller":"traceutil/trace.go:171","msg":"trace[136515826] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"258.627366ms","start":"2026-06-02T21:39:37.96726Z","end":"2026-06-02T21:39:38.225888Z","steps":["trace[136515826] 'process raft request' (duration: 258.308028ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:39:38.226032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.863773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-thbsl\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-06-02T21:39:38.226098Z","caller":"traceutil/trace.go:171","msg":"trace[979001866] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-thbsl; range_end:; response_count:1; response_revision:5257; }","duration":"246.979586ms","start":"2026-06-02T21:39:37.979097Z","end":"2026-06-02T21:39:38.226076Z","steps":["trace[979001866] 'agreement among raft nodes before linearized reading' (duration: 246.70621ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:39:38.29229Z","caller":"traceutil/trace.go:171","msg":"trace[1307433501] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"160.026397ms","start":"2026-06-02T21:39:38.132237Z","end":"2026-06-02T21:39:38.292263Z","steps":["trace[1307433501] 'process raft request' (duration: 106.767354ms)","trace[1307433501] 'compare' (duration: 52.943976ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:39:38.719232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.955738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-06-02T21:39:38.719327Z","caller":"traceutil/trace.go:171","msg":"trace[965330818] linearizableReadLoop","detail":"{readStateIndex:5457; appliedIndex:5456; }","duration":"105.294728ms","start":"2026-06-02T21:39:38.614017Z","end":"2026-06-02T21:39:38.719311Z","steps":["trace[965330818] 'read index received' (duration: 44.861µs)","trace[965330818] 'applied index is now lower than readState.Index' (duration: 105.248877ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:39:38.719402Z","caller":"traceutil/trace.go:171","msg":"trace[1054797626] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5264; }","duration":"354.883447ms","start":"2026-06-02T21:39:38.364501Z","end":"2026-06-02T21:39:38.719385Z","steps":["trace[1054797626] 'process raft request' (duration: 68.711733ms)","trace[1054797626] 'compare' (duration: 285.913527ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:39:38.719462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T21:39:38.364474Z","time spent":"354.966989ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T21:39:38.719452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.431632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:39:38.719535Z","caller":"traceutil/trace.go:171","msg":"trace[754964633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5264; }","duration":"105.556835ms","start":"2026-06-02T21:39:38.613964Z","end":"2026-06-02T21:39:38.719521Z","steps":["trace[754964633] 'agreement among raft nodes before linearized reading' (duration: 105.427552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:39:46.441142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.651055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T21:39:46.441599Z","caller":"traceutil/trace.go:171","msg":"trace[1001218145] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"191.54399ms","start":"2026-06-02T21:39:46.250012Z","end":"2026-06-02T21:39:46.441556Z","steps":["trace[1001218145] 'process raft request' (duration: 75.395953ms)","trace[1001218145] 'compare' (duration: 115.520793ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:39:46.44161Z","caller":"traceutil/trace.go:171","msg":"trace[403351978] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"189.897101ms","start":"2026-06-02T21:39:46.251671Z","end":"2026-06-02T21:39:46.441568Z","steps":["trace[403351978] 'process raft request' (duration: 189.585293ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:41:02.855868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3011} {"level":"info","ts":"2026-06-02T21:41:02.938816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3011,"took":"81.247287ms","hash":1242933005,"current-db-size-bytes":58474496,"current-db-size":"58 MB","current-db-size-in-use-bytes":31825920,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T21:41:02.93888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1242933005,"revision":3011,"compact-revision":-1} {"level":"info","ts":"2026-06-02T21:46:02.863186Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6119} {"level":"info","ts":"2026-06-02T21:46:02.957099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6119,"took":"91.987268ms","hash":1917653351,"current-db-size-bytes":58474496,"current-db-size":"58 MB","current-db-size-in-use-bytes":23400448,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-02T21:46:02.957167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1917653351,"revision":6119,"compact-revision":3011} {"level":"info","ts":"2026-06-02T21:46:39.376953Z","caller":"traceutil/trace.go:171","msg":"trace[1988444247] linearizableReadLoop","detail":"{readStateIndex:8854; appliedIndex:8853; }","duration":"182.752375ms","start":"2026-06-02T21:46:39.194174Z","end":"2026-06-02T21:46:39.376926Z","steps":["trace[1988444247] 'read index received' (duration: 182.53414ms)","trace[1988444247] 'applied index is now lower than readState.Index' (duration: 217.375µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:46:39.377047Z","caller":"traceutil/trace.go:171","msg":"trace[1748546112] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"209.391093ms","start":"2026-06-02T21:46:39.167625Z","end":"2026-06-02T21:46:39.377016Z","steps":["trace[1748546112] 'process raft request' (duration: 209.164687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:46:39.377127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.929689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T21:46:39.377188Z","caller":"traceutil/trace.go:171","msg":"trace[893584577] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:8557; }","duration":"183.043892ms","start":"2026-06-02T21:46:39.194128Z","end":"2026-06-02T21:46:39.377172Z","steps":["trace[893584577] 'agreement among raft nodes before linearized reading' (duration: 182.914029ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:46:48.613912Z","caller":"traceutil/trace.go:171","msg":"trace[1362269831] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"101.885184ms","start":"2026-06-02T21:46:48.511996Z","end":"2026-06-02T21:46:48.613881Z","steps":["trace[1362269831] 'process raft request' (duration: 101.72311ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:46:48.763035Z","caller":"traceutil/trace.go:171","msg":"trace[2136882542] linearizableReadLoop","detail":"{readStateIndex:8952; appliedIndex:8951; }","duration":"148.166868ms","start":"2026-06-02T21:46:48.614838Z","end":"2026-06-02T21:46:48.763005Z","steps":["trace[2136882542] 'read index received' (duration: 142.918964ms)","trace[2136882542] 'applied index is now lower than readState.Index' (duration: 5.246104ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:46:48.763182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.330543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:46:48.763241Z","caller":"traceutil/trace.go:171","msg":"trace[2067654927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8651; }","duration":"148.432016ms","start":"2026-06-02T21:46:48.614796Z","end":"2026-06-02T21:46:48.763228Z","steps":["trace[2067654927] 'agreement among raft nodes before linearized reading' (duration: 148.333243ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:46:48.763436Z","caller":"traceutil/trace.go:171","msg":"trace[645132431] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"245.202737ms","start":"2026-06-02T21:46:48.517866Z","end":"2026-06-02T21:46:48.763069Z","steps":["trace[645132431] 'process raft request' (duration: 239.976083ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:47:29.783012Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002477.wal"} {"level":"info","ts":"2026-06-02T21:48:04.499893Z","caller":"traceutil/trace.go:171","msg":"trace[2061133400] transaction","detail":"{read_only:false; response_revision:9393; number_of_response:1; }","duration":"187.28684ms","start":"2026-06-02T21:48:04.312577Z","end":"2026-06-02T21:48:04.499864Z","steps":["trace[2061133400] 'process raft request' (duration: 187.102566ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:48:04.509586Z","caller":"traceutil/trace.go:171","msg":"trace[811654706] linearizableReadLoop","detail":"{readStateIndex:9716; appliedIndex:9714; }","duration":"186.387029ms","start":"2026-06-02T21:48:04.323168Z","end":"2026-06-02T21:48:04.509555Z","steps":["trace[811654706] 'read index received' (duration: 176.49475ms)","trace[811654706] 'applied index is now lower than readState.Index' (duration: 9.891249ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:48:04.509698Z","caller":"traceutil/trace.go:171","msg":"trace[1789854371] transaction","detail":"{read_only:false; response_revision:9394; number_of_response:1; }","duration":"195.392695ms","start":"2026-06-02T21:48:04.314295Z","end":"2026-06-02T21:48:04.509688Z","steps":["trace[1789854371] 'process raft request' (duration: 195.109877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:48:04.509813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.697565ms","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-06-02T21:48:04.509853Z","caller":"traceutil/trace.go:171","msg":"trace[1795543788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9394; }","duration":"181.788227ms","start":"2026-06-02T21:48:04.328055Z","end":"2026-06-02T21:48:04.509844Z","steps":["trace[1795543788] 'agreement among raft nodes before linearized reading' (duration: 181.675055ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:48:04.50995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.791818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-02T21:48:04.509967Z","caller":"traceutil/trace.go:171","msg":"trace[1028801293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9394; }","duration":"186.849329ms","start":"2026-06-02T21:48:04.323112Z","end":"2026-06-02T21:48:04.509962Z","steps":["trace[1028801293] 'agreement among raft nodes before linearized reading' (duration: 186.795408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:48:04.510019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.837867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-02T21:48:04.510091Z","caller":"traceutil/trace.go:171","msg":"trace[319036760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9394; }","duration":"178.917479ms","start":"2026-06-02T21:48:04.331156Z","end":"2026-06-02T21:48:04.510074Z","steps":["trace[319036760] 'agreement among raft nodes before linearized reading' (duration: 178.716724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:48:04.510019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.943646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-02T21:48:04.51055Z","caller":"traceutil/trace.go:171","msg":"trace[1748266323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9394; }","duration":"178.55085ms","start":"2026-06-02T21:48:04.331982Z","end":"2026-06-02T21:48:04.510532Z","steps":["trace[1748266323] 'agreement among raft nodes before linearized reading' (duration: 177.817192ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:48:04.646913Z","caller":"traceutil/trace.go:171","msg":"trace[606743854] transaction","detail":"{read_only:false; response_revision:9396; number_of_response:1; }","duration":"133.368674ms","start":"2026-06-02T21:48:04.513503Z","end":"2026-06-02T21:48:04.646872Z","steps":["trace[606743854] 'process raft request' (duration: 103.313142ms)","trace[606743854] 'compare' (duration: 29.829926ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:48:04.646921Z","caller":"traceutil/trace.go:171","msg":"trace[271728645] transaction","detail":"{read_only:false; response_revision:9397; number_of_response:1; }","duration":"132.703299ms","start":"2026-06-02T21:48:04.514188Z","end":"2026-06-02T21:48:04.646892Z","steps":["trace[271728645] 'process raft request' (duration: 132.596815ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:48:04.647013Z","caller":"traceutil/trace.go:171","msg":"trace[54639268] transaction","detail":"{read_only:false; response_revision:9398; number_of_response:1; }","duration":"132.443242ms","start":"2026-06-02T21:48:04.514554Z","end":"2026-06-02T21:48:04.646998Z","steps":["trace[54639268] 'process raft request' (duration: 132.288899ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:48:40.775364Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T21:48:40.779863Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-02T21:48:40.779954Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-02T21:51:02.870247Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8308} {"level":"info","ts":"2026-06-02T21:51:02.949753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8308,"took":"77.155461ms","hash":2220896841,"current-db-size-bytes":58474496,"current-db-size":"58 MB","current-db-size-in-use-bytes":26333184,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-02T21:51:02.949823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2220896841,"revision":8308,"compact-revision":6119} {"level":"info","ts":"2026-06-02T21:51:49.393072Z","caller":"traceutil/trace.go:171","msg":"trace[478860845] transaction","detail":"{read_only:false; response_revision:11250; number_of_response:1; }","duration":"103.298538ms","start":"2026-06-02T21:51:49.289748Z","end":"2026-06-02T21:51:49.393047Z","steps":["trace[478860845] 'process raft request' (duration: 103.141614ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:51:49.407979Z","caller":"traceutil/trace.go:171","msg":"trace[605962211] transaction","detail":"{read_only:false; response_revision:11251; number_of_response:1; }","duration":"111.81092ms","start":"2026-06-02T21:51:49.296143Z","end":"2026-06-02T21:51:49.407954Z","steps":["trace[605962211] 'process raft request' (duration: 111.680857ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:56:02.878049Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10794} {"level":"info","ts":"2026-06-02T21:56:02.958446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10794,"took":"77.957839ms","hash":1452160819,"current-db-size-bytes":58474496,"current-db-size":"58 MB","current-db-size-in-use-bytes":27484160,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-02T21:56:02.958499Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1452160819,"revision":10794,"compact-revision":8308} {"level":"info","ts":"2026-06-02T21:59:54.899743Z","caller":"traceutil/trace.go:171","msg":"trace[1979055035] transaction","detail":"{read_only:false; response_revision:14923; number_of_response:1; }","duration":"114.083828ms","start":"2026-06-02T21:59:54.785625Z","end":"2026-06-02T21:59:54.899709Z","steps":["trace[1979055035] 'process raft request' (duration: 113.842542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:59:55.176657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.992163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T21:59:55.17674Z","caller":"traceutil/trace.go:171","msg":"trace[686547943] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14923; }","duration":"253.120077ms","start":"2026-06-02T21:59:54.923602Z","end":"2026-06-02T21:59:55.176722Z","steps":["trace[686547943] 'range keys from in-memory index tree' (duration: 252.92135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:59:55.176797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.737163ms","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-06-02T21:59:55.176889Z","caller":"traceutil/trace.go:171","msg":"trace[802527046] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14923; }","duration":"251.835675ms","start":"2026-06-02T21:59:54.925026Z","end":"2026-06-02T21:59:55.176862Z","steps":["trace[802527046] 'range keys from in-memory index tree' (duration: 251.66056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:59:55.176898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.335962ms","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-06-02T21:59:55.176946Z","caller":"traceutil/trace.go:171","msg":"trace[880249992] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14923; }","duration":"253.390793ms","start":"2026-06-02T21:59:54.92354Z","end":"2026-06-02T21:59:55.17693Z","steps":["trace[880249992] 'range keys from in-memory index tree' (duration: 253.29848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:59:55.176928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.130162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-02T21:59:55.177026Z","caller":"traceutil/trace.go:171","msg":"trace[324715695] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14923; }","duration":"252.272545ms","start":"2026-06-02T21:59:54.924729Z","end":"2026-06-02T21:59:55.177001Z","steps":["trace[324715695] 'range keys from in-memory index tree' (duration: 251.978988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:59:55.176729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.471023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T21:59:55.177125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.247835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-02T21:59:55.177198Z","caller":"traceutil/trace.go:171","msg":"trace[2040205918] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14923; }","duration":"252.339917ms","start":"2026-06-02T21:59:54.924839Z","end":"2026-06-02T21:59:55.177179Z","steps":["trace[2040205918] 'range keys from in-memory index tree' (duration: 252.124252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:59:55.177201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.960562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-02T21:59:55.177288Z","caller":"traceutil/trace.go:171","msg":"trace[1735376757] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:14923; }","duration":"258.084305ms","start":"2026-06-02T21:59:54.919184Z","end":"2026-06-02T21:59:55.177269Z","steps":["trace[1735376757] 'range keys from in-memory index tree' (duration: 257.40734ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:59:55.177312Z","caller":"traceutil/trace.go:171","msg":"trace[632091876] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14923; }","duration":"255.884653ms","start":"2026-06-02T21:59:54.921202Z","end":"2026-06-02T21:59:55.177086Z","steps":["trace[632091876] 'range keys from in-memory index tree' (duration: 255.408711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:59:55.17693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.611915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-02T21:59:55.177723Z","caller":"traceutil/trace.go:171","msg":"trace[1571122497] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14923; }","duration":"246.439275ms","start":"2026-06-02T21:59:54.931266Z","end":"2026-06-02T21:59:55.177705Z","steps":["trace[1571122497] 'range keys from in-memory index tree' (duration: 245.467052ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T21:59:55.177751Z","caller":"traceutil/trace.go:171","msg":"trace[1467734814] transaction","detail":"{read_only:false; response_revision:14924; number_of_response:1; }","duration":"245.053732ms","start":"2026-06-02T21:59:54.932677Z","end":"2026-06-02T21:59:55.177731Z","steps":["trace[1467734814] 'process raft request' (duration: 179.80077ms)","trace[1467734814] 'compare' (duration: 64.32096ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T21:59:55.177756Z","caller":"traceutil/trace.go:171","msg":"trace[1818020810] linearizableReadLoop","detail":"{readStateIndex:15427; appliedIndex:15426; }","duration":"240.916472ms","start":"2026-06-02T21:59:54.936811Z","end":"2026-06-02T21:59:55.177727Z","steps":["trace[1818020810] 'read index received' (duration: 175.623009ms)","trace[1818020810] 'applied index is now lower than readState.Index' (duration: 65.291823ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T21:59:55.17795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.111856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-vf6t4\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-06-02T21:59:55.178014Z","caller":"traceutil/trace.go:171","msg":"trace[236367099] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-vf6t4; range_end:; response_count:1; response_revision:14924; }","duration":"241.211419ms","start":"2026-06-02T21:59:54.936784Z","end":"2026-06-02T21:59:55.177995Z","steps":["trace[236367099] 'agreement among raft nodes before linearized reading' (duration: 241.045445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:59:55.178185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.429401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-vf6t4\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-06-02T21:59:55.178254Z","caller":"traceutil/trace.go:171","msg":"trace[1112341215] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-vf6t4; range_end:; response_count:1; response_revision:14924; }","duration":"223.540934ms","start":"2026-06-02T21:59:54.954697Z","end":"2026-06-02T21:59:55.178238Z","steps":["trace[1112341215] 'agreement among raft nodes before linearized reading' (duration: 223.233697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T21:59:55.178266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.683541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-02T21:59:55.178326Z","caller":"traceutil/trace.go:171","msg":"trace[884137955] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14924; }","duration":"192.778384ms","start":"2026-06-02T21:59:54.985533Z","end":"2026-06-02T21:59:55.178311Z","steps":["trace[884137955] 'agreement among raft nodes before linearized reading' (duration: 192.64931ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:01:02.884638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12907} {"level":"info","ts":"2026-06-02T22:01:02.9624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12907,"took":"75.025567ms","hash":1498914995,"current-db-size-bytes":58474496,"current-db-size":"58 MB","current-db-size-in-use-bytes":30408704,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T22:01:02.962459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1498914995,"revision":12907,"compact-revision":10794} {"level":"info","ts":"2026-06-02T22:05:57.861566Z","caller":"traceutil/trace.go:171","msg":"trace[1701778909] transaction","detail":"{read_only:false; response_revision:17660; number_of_response:1; }","duration":"159.244092ms","start":"2026-06-02T22:05:57.702295Z","end":"2026-06-02T22:05:57.861539Z","steps":["trace[1701778909] 'process raft request' (duration: 159.042377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:05:58.144399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.187083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:05:58.144478Z","caller":"traceutil/trace.go:171","msg":"trace[1210326569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17660; }","duration":"155.304175ms","start":"2026-06-02T22:05:57.989156Z","end":"2026-06-02T22:05:58.14446Z","steps":["trace[1210326569] 'range keys from in-memory index tree' (duration: 155.05151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:05:58.144712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.821454ms","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-06-02T22:05:58.144749Z","caller":"traceutil/trace.go:171","msg":"trace[102716959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17660; }","duration":"154.874486ms","start":"2026-06-02T22:05:57.989866Z","end":"2026-06-02T22:05:58.14474Z","steps":["trace[102716959] 'range keys from in-memory index tree' (duration: 154.733823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:05:58.144925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.093881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:05:58.144959Z","caller":"traceutil/trace.go:171","msg":"trace[1786375952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17660; }","duration":"155.146112ms","start":"2026-06-02T22:05:57.989805Z","end":"2026-06-02T22:05:58.144951Z","steps":["trace[1786375952] 'range keys from in-memory index tree' (duration: 154.954597ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:06:02.889358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15387} {"level":"info","ts":"2026-06-02T22:06:02.995848Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15387,"took":"103.147918ms","hash":3097885041,"current-db-size-bytes":58474496,"current-db-size":"58 MB","current-db-size-in-use-bytes":32698368,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-02T22:06:02.995901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3097885041,"revision":15387,"compact-revision":12907} {"level":"info","ts":"2026-06-02T22:06:06.305222Z","caller":"traceutil/trace.go:171","msg":"trace[395137708] transaction","detail":"{read_only:false; response_revision:17820; number_of_response:1; }","duration":"101.530689ms","start":"2026-06-02T22:06:06.203664Z","end":"2026-06-02T22:06:06.305194Z","steps":["trace[395137708] 'process raft request' (duration: 100.990356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:06:06.305824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.009354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-nvq2s-vnc-bbxb6\" ","response":"range_response_count:1 size:2533"} {"level":"info","ts":"2026-06-02T22:06:06.305871Z","caller":"traceutil/trace.go:171","msg":"trace[1671646251] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-nvq2s-vnc-bbxb6; range_end:; response_count:1; response_revision:17821; }","duration":"108.090257ms","start":"2026-06-02T22:06:06.197769Z","end":"2026-06-02T22:06:06.305859Z","steps":["trace[1671646251] 'agreement among raft nodes before linearized reading' (duration: 107.929783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:06:06.305918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.274663ms","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-06-02T22:06:06.307274Z","caller":"traceutil/trace.go:171","msg":"trace[2015524011] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:17821; }","duration":"107.660847ms","start":"2026-06-02T22:06:06.199582Z","end":"2026-06-02T22:06:06.307243Z","steps":["trace[2015524011] 'agreement among raft nodes before linearized reading' (duration: 106.195341ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:08:40.236575Z","caller":"traceutil/trace.go:171","msg":"trace[286063296] linearizableReadLoop","detail":"{readStateIndex:19730; appliedIndex:19729; }","duration":"116.894432ms","start":"2026-06-02T22:08:40.119651Z","end":"2026-06-02T22:08:40.236546Z","steps":["trace[286063296] 'read index received' (duration: 116.032681ms)","trace[286063296] 'applied index is now lower than readState.Index' (duration: 860.091µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:08:40.236645Z","caller":"traceutil/trace.go:171","msg":"trace[1834903085] transaction","detail":"{read_only:false; response_revision:19096; number_of_response:1; }","duration":"195.655041ms","start":"2026-06-02T22:08:40.040941Z","end":"2026-06-02T22:08:40.236596Z","steps":["trace[1834903085] 'process raft request' (duration: 194.878502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:08:40.236814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.143889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:08:40.236894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.045193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:1 size:6652"} {"level":"info","ts":"2026-06-02T22:08:40.23695Z","caller":"traceutil/trace.go:171","msg":"trace[566483801] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:1; response_revision:19096; }","duration":"116.149746ms","start":"2026-06-02T22:08:40.120785Z","end":"2026-06-02T22:08:40.236934Z","steps":["trace[566483801] 'agreement among raft nodes before linearized reading' (duration: 115.946331ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:08:40.236944Z","caller":"traceutil/trace.go:171","msg":"trace[880222443] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19096; }","duration":"117.318903ms","start":"2026-06-02T22:08:40.119592Z","end":"2026-06-02T22:08:40.236911Z","steps":["trace[880222443] 'agreement among raft nodes before linearized reading' (duration: 117.114198ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:09:24.394128Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T22:09:24.400051Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-02T22:09:24.40021Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-02T22:11:02.895808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17752} {"level":"info","ts":"2026-06-02T22:11:02.994819Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17752,"took":"95.486285ms","hash":3096511936,"current-db-size-bytes":58474496,"current-db-size":"58 MB","current-db-size-in-use-bytes":34172928,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-02T22:11:02.99488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3096511936,"revision":17752,"compact-revision":15387} {"level":"warn","ts":"2026-06-02T22:11:44.626904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.488394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:11:44.627195Z","caller":"traceutil/trace.go:171","msg":"trace[1517496959] transaction","detail":"{read_only:false; response_revision:20364; number_of_response:1; }","duration":"253.294827ms","start":"2026-06-02T22:11:44.373845Z","end":"2026-06-02T22:11:44.62714Z","steps":["trace[1517496959] 'process raft request' (duration: 144.452225ms)","trace[1517496959] 'compare' (duration: 108.222478ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:11:44.627042Z","caller":"traceutil/trace.go:171","msg":"trace[1283689127] linearizableReadLoop","detail":"{readStateIndex:21045; appliedIndex:21044; }","duration":"243.69293ms","start":"2026-06-02T22:11:44.383327Z","end":"2026-06-02T22:11:44.627019Z","steps":["trace[1283689127] 'read index received' (duration: 134.8937ms)","trace[1283689127] 'applied index is now lower than readState.Index' (duration: 108.79762ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:11:44.627327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.995197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:11:44.627548Z","caller":"traceutil/trace.go:171","msg":"trace[826144295] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:20364; }","duration":"244.236702ms","start":"2026-06-02T22:11:44.383288Z","end":"2026-06-02T22:11:44.627525Z","steps":["trace[826144295] 'agreement among raft nodes before linearized reading' (duration: 243.982876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:11:44.627733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.66002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-06-02T22:11:44.627783Z","caller":"traceutil/trace.go:171","msg":"trace[868945685] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:20364; }","duration":"125.728192ms","start":"2026-06-02T22:11:44.502031Z","end":"2026-06-02T22:11:44.62776Z","steps":["trace[868945685] 'agreement among raft nodes before linearized reading' (duration: 125.612369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:11:44.901241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.083276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:11:44.9014Z","caller":"traceutil/trace.go:171","msg":"trace[805130444] transaction","detail":"{read_only:false; response_revision:20365; number_of_response:1; }","duration":"198.486788ms","start":"2026-06-02T22:11:44.702875Z","end":"2026-06-02T22:11:44.901362Z","steps":["trace[805130444] 'process raft request' (duration: 76.204627ms)","trace[805130444] 'compare' (duration: 121.964603ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:11:45.793298Z","caller":"traceutil/trace.go:171","msg":"trace[1057205327] transaction","detail":"{read_only:false; response_revision:20367; number_of_response:1; }","duration":"105.805519ms","start":"2026-06-02T22:11:45.687463Z","end":"2026-06-02T22:11:45.793268Z","steps":["trace[1057205327] 'process raft request' (duration: 105.590134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:11:46.073003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.893105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:11:46.073109Z","caller":"traceutil/trace.go:171","msg":"trace[1312375381] linearizableReadLoop","detail":"{readStateIndex:21049; appliedIndex:21048; }","duration":"110.49208ms","start":"2026-06-02T22:11:45.9626Z","end":"2026-06-02T22:11:46.073092Z","steps":["trace[1312375381] 'read index received' (duration: 68.221µs)","trace[1312375381] 'applied index is now lower than readState.Index' (duration: 110.422689ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:11:46.073188Z","caller":"traceutil/trace.go:171","msg":"trace[78178984] transaction","detail":"{read_only:false; response_revision:20368; number_of_response:1; }","duration":"334.557745ms","start":"2026-06-02T22:11:45.738619Z","end":"2026-06-02T22:11:46.073177Z","steps":["trace[78178984] 'process raft request' (duration: 189.421213ms)","trace[78178984] 'compare' (duration: 144.761793ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:11:46.073253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:11:45.738594Z","time spent":"334.625816ms","remote":"127.0.0.1:44402","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-06-02T22:11:46.073556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.94456ms","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-06-02T22:11:46.073641Z","caller":"traceutil/trace.go:171","msg":"trace[553325383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20368; }","duration":"108.053723ms","start":"2026-06-02T22:11:45.965566Z","end":"2026-06-02T22:11:46.07362Z","steps":["trace[553325383] 'agreement among raft nodes before linearized reading' (duration: 107.847127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:11:46.073703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.100866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:11:46.073763Z","caller":"traceutil/trace.go:171","msg":"trace[87066286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20368; }","duration":"111.197077ms","start":"2026-06-02T22:11:45.962551Z","end":"2026-06-02T22:11:46.073748Z","steps":["trace[87066286] 'agreement among raft nodes before linearized reading' (duration: 111.060724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:11:46.073828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.781739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:11:46.07391Z","caller":"traceutil/trace.go:171","msg":"trace[28151582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20368; }","duration":"105.903761ms","start":"2026-06-02T22:11:45.967988Z","end":"2026-06-02T22:11:46.073892Z","steps":["trace[28151582] 'agreement among raft nodes before linearized reading' (duration: 105.645225ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:11:46.290254Z","caller":"traceutil/trace.go:171","msg":"trace[149953523] transaction","detail":"{read_only:false; response_revision:20369; number_of_response:1; }","duration":"211.266491ms","start":"2026-06-02T22:11:46.078959Z","end":"2026-06-02T22:11:46.290226Z","steps":["trace[149953523] 'process raft request' (duration: 150.343776ms)","trace[149953523] 'compare' (duration: 60.478655ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:11:46.290443Z","caller":"traceutil/trace.go:171","msg":"trace[717554994] transaction","detail":"{read_only:false; response_revision:20370; number_of_response:1; }","duration":"211.452156ms","start":"2026-06-02T22:11:46.078981Z","end":"2026-06-02T22:11:46.290434Z","steps":["trace[717554994] 'process raft request' (duration: 211.19817ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:11:46.290672Z","caller":"traceutil/trace.go:171","msg":"trace[2080475008] transaction","detail":"{read_only:false; response_revision:20371; number_of_response:1; }","duration":"211.542448ms","start":"2026-06-02T22:11:46.079101Z","end":"2026-06-02T22:11:46.290644Z","steps":["trace[2080475008] 'process raft request' (duration: 211.286032ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:11:46.290961Z","caller":"traceutil/trace.go:171","msg":"trace[410777293] transaction","detail":"{read_only:false; response_revision:20373; number_of_response:1; }","duration":"134.230054ms","start":"2026-06-02T22:11:46.156706Z","end":"2026-06-02T22:11:46.290936Z","steps":["trace[410777293] 'process raft request' (duration: 134.141232ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:11:46.290994Z","caller":"traceutil/trace.go:171","msg":"trace[1241094792] transaction","detail":"{read_only:false; response_revision:20372; number_of_response:1; }","duration":"211.630439ms","start":"2026-06-02T22:11:46.07932Z","end":"2026-06-02T22:11:46.29095Z","steps":["trace[1241094792] 'process raft request' (duration: 211.23205ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:35.733873Z","caller":"traceutil/trace.go:171","msg":"trace[333736861] linearizableReadLoop","detail":"{readStateIndex:23539; appliedIndex:23538; }","duration":"251.489548ms","start":"2026-06-02T22:15:35.482358Z","end":"2026-06-02T22:15:35.733847Z","steps":["trace[333736861] 'read index received' (duration: 250.972366ms)","trace[333736861] 'applied index is now lower than readState.Index' (duration: 516.122µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:15:35.734191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.818117ms","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-06-02T22:15:35.734272Z","caller":"traceutil/trace.go:171","msg":"trace[1717873916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22788; }","duration":"251.948969ms","start":"2026-06-02T22:15:35.482306Z","end":"2026-06-02T22:15:35.734255Z","steps":["trace[1717873916] 'agreement among raft nodes before linearized reading' (duration: 251.754555ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:35.734697Z","caller":"traceutil/trace.go:171","msg":"trace[880534736] transaction","detail":"{read_only:false; response_revision:22788; number_of_response:1; }","duration":"274.437777ms","start":"2026-06-02T22:15:35.460127Z","end":"2026-06-02T22:15:35.734564Z","steps":["trace[880534736] 'process raft request' (duration: 273.550766ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:35.866469Z","caller":"traceutil/trace.go:171","msg":"trace[782356103] transaction","detail":"{read_only:false; response_revision:22789; number_of_response:1; }","duration":"368.930719ms","start":"2026-06-02T22:15:35.49752Z","end":"2026-06-02T22:15:35.866451Z","steps":["trace[782356103] 'process raft request' (duration: 368.748095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:35.866581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:35.497509Z","time spent":"369.016281ms","remote":"127.0.0.1:44402","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-06-02T22:15:35.866858Z","caller":"traceutil/trace.go:171","msg":"trace[2044486579] transaction","detail":"{read_only:false; response_revision:22790; number_of_response:1; }","duration":"367.492234ms","start":"2026-06-02T22:15:35.499329Z","end":"2026-06-02T22:15:35.866822Z","steps":["trace[2044486579] 'process raft request' (duration: 367.090885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:35.867035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:35.499312Z","time spent":"367.623828ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:15:35.867535Z","caller":"traceutil/trace.go:171","msg":"trace[731891716] transaction","detail":"{read_only:false; response_revision:22791; number_of_response:1; }","duration":"191.262436ms","start":"2026-06-02T22:15:35.676201Z","end":"2026-06-02T22:15:35.867463Z","steps":["trace[731891716] 'process raft request' (duration: 190.932849ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:35.867669Z","caller":"traceutil/trace.go:171","msg":"trace[410150076] linearizableReadLoop","detail":"{readStateIndex:23542; appliedIndex:23539; }","duration":"133.622648ms","start":"2026-06-02T22:15:35.734032Z","end":"2026-06-02T22:15:35.867655Z","steps":["trace[410150076] 'read index received' (duration: 132.274965ms)","trace[410150076] 'applied index is now lower than readState.Index' (duration: 1.345443ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:15:35.86767Z","caller":"traceutil/trace.go:171","msg":"trace[1944659898] transaction","detail":"{read_only:false; response_revision:22793; number_of_response:1; }","duration":"102.511463ms","start":"2026-06-02T22:15:35.765136Z","end":"2026-06-02T22:15:35.867648Z","steps":["trace[1944659898] 'process raft request' (duration: 102.419261ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:35.867729Z","caller":"traceutil/trace.go:171","msg":"trace[17986543] transaction","detail":"{read_only:false; response_revision:22792; number_of_response:1; }","duration":"129.546491ms","start":"2026-06-02T22:15:35.738151Z","end":"2026-06-02T22:15:35.867697Z","steps":["trace[17986543] 'process raft request' (duration: 129.262123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:35.869074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.068484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:15:35.86914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.870919ms","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-06-02T22:15:35.869216Z","caller":"traceutil/trace.go:171","msg":"trace[220440035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22793; }","duration":"255.225438ms","start":"2026-06-02T22:15:35.613957Z","end":"2026-06-02T22:15:35.869182Z","steps":["trace[220440035] 'agreement among raft nodes before linearized reading' (duration: 253.791484ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:15:35.869336Z","caller":"traceutil/trace.go:171","msg":"trace[509807145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22793; }","duration":"377.056743ms","start":"2026-06-02T22:15:35.492227Z","end":"2026-06-02T22:15:35.869283Z","steps":["trace[509807145] 'agreement among raft nodes before linearized reading' (duration: 375.430645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:35.869384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:35.492221Z","time spent":"377.153175ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T22:15:35.869156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.06899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:15:35.869586Z","caller":"traceutil/trace.go:171","msg":"trace[391640032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22793; }","duration":"381.479839ms","start":"2026-06-02T22:15:35.488036Z","end":"2026-06-02T22:15:35.869516Z","steps":["trace[391640032] 'agreement among raft nodes before linearized reading' (duration: 379.722727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:35.869701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:35.488031Z","time spent":"381.650803ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T22:15:35.869749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.728315ms","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-06-02T22:15:35.86986Z","caller":"traceutil/trace.go:171","msg":"trace[1194104660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22793; }","duration":"381.863478ms","start":"2026-06-02T22:15:35.487952Z","end":"2026-06-02T22:15:35.869815Z","steps":["trace[1194104660] 'agreement among raft nodes before linearized reading' (duration: 379.82134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:35.869905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:35.487926Z","time spent":"381.968921ms","remote":"127.0.0.1:44402","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-06-02T22:15:35.873039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.600821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:15:35.873139Z","caller":"traceutil/trace.go:171","msg":"trace[1255325709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22793; }","duration":"386.717265ms","start":"2026-06-02T22:15:35.486399Z","end":"2026-06-02T22:15:35.873116Z","steps":["trace[1255325709] 'agreement among raft nodes before linearized reading' (duration: 381.315636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:15:35.873171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:15:35.486388Z","time spent":"386.768196ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T22:16:02.911412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20150} {"level":"info","ts":"2026-06-02T22:16:03.020377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20150,"took":"106.026587ms","hash":1932649815,"current-db-size-bytes":59322368,"current-db-size":"59 MB","current-db-size-in-use-bytes":41312256,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-06-02T22:16:03.020445Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1932649815,"revision":20150,"compact-revision":17752} {"level":"info","ts":"2026-06-02T22:18:53.042825Z","caller":"traceutil/trace.go:171","msg":"trace[1579494884] linearizableReadLoop","detail":"{readStateIndex:25606; appliedIndex:25605; }","duration":"122.789146ms","start":"2026-06-02T22:18:52.920018Z","end":"2026-06-02T22:18:53.042807Z","steps":["trace[1579494884] 'read index received' (duration: 122.734415ms)","trace[1579494884] 'applied index is now lower than readState.Index' (duration: 53.611µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:53.042898Z","caller":"traceutil/trace.go:171","msg":"trace[1031747700] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"130.362729ms","start":"2026-06-02T22:18:52.9125Z","end":"2026-06-02T22:18:53.042863Z","steps":["trace[1031747700] 'process raft request' (duration: 130.183245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:53.044552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.492496ms","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-06-02T22:18:53.044696Z","caller":"traceutil/trace.go:171","msg":"trace[707313175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24802; }","duration":"124.688381ms","start":"2026-06-02T22:18:52.919988Z","end":"2026-06-02T22:18:53.044677Z","steps":["trace[707313175] 'agreement among raft nodes before linearized reading' (duration: 122.91396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:53.066435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.925607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-02T22:18:53.066506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.051603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-02T22:18:53.06654Z","caller":"traceutil/trace.go:171","msg":"trace[1587261950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24803; }","duration":"145.07041ms","start":"2026-06-02T22:18:52.921445Z","end":"2026-06-02T22:18:53.066515Z","steps":["trace[1587261950] 'agreement among raft nodes before linearized reading' (duration: 144.835855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:53.06655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.224995ms","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-06-02T22:18:53.066558Z","caller":"traceutil/trace.go:171","msg":"trace[2019841953] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24803; }","duration":"123.138985ms","start":"2026-06-02T22:18:52.943405Z","end":"2026-06-02T22:18:53.066544Z","steps":["trace[2019841953] 'agreement among raft nodes before linearized reading' (duration: 123.009352ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:53.06659Z","caller":"traceutil/trace.go:171","msg":"trace[1326949921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24803; }","duration":"143.297298ms","start":"2026-06-02T22:18:52.923281Z","end":"2026-06-02T22:18:53.066579Z","steps":["trace[1326949921] 'agreement among raft nodes before linearized reading' (duration: 143.155174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:53.224368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.493745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:18:53.224504Z","caller":"traceutil/trace.go:171","msg":"trace[1732138235] transaction","detail":"{read_only:false; response_revision:24804; number_of_response:1; }","duration":"173.991851ms","start":"2026-06-02T22:18:53.050487Z","end":"2026-06-02T22:18:53.224479Z","steps":["trace[1732138235] 'process raft request' (duration: 47.26576ms)","trace[1732138235] 'compare' (duration: 126.315301ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:53.245986Z","caller":"traceutil/trace.go:171","msg":"trace[411008209] transaction","detail":"{read_only:false; response_revision:24806; number_of_response:1; }","duration":"164.274379ms","start":"2026-06-02T22:18:53.081689Z","end":"2026-06-02T22:18:53.245963Z","steps":["trace[411008209] 'process raft request' (duration: 164.227218ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:53.246072Z","caller":"traceutil/trace.go:171","msg":"trace[152880886] transaction","detail":"{read_only:false; response_revision:24805; number_of_response:1; }","duration":"171.632074ms","start":"2026-06-02T22:18:53.074392Z","end":"2026-06-02T22:18:53.246024Z","steps":["trace[152880886] 'process raft request' (duration: 171.394159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:53.706565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.150579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:18:53.706666Z","caller":"traceutil/trace.go:171","msg":"trace[2139532931] linearizableReadLoop","detail":"{readStateIndex:25612; appliedIndex:25611; }","duration":"184.690117ms","start":"2026-06-02T22:18:53.521961Z","end":"2026-06-02T22:18:53.706651Z","steps":["trace[2139532931] 'read index received' (duration: 75.27814ms)","trace[2139532931] 'applied index is now lower than readState.Index' (duration: 109.410517ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:53.706787Z","caller":"traceutil/trace.go:171","msg":"trace[1615631509] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"241.729142ms","start":"2026-06-02T22:18:53.465002Z","end":"2026-06-02T22:18:53.706732Z","steps":["trace[1615631509] 'process raft request' (duration: 132.225713ms)","trace[1615631509] 'compare' (duration: 108.74799ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:18:53.706944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.981514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-06-02T22:18:53.706977Z","caller":"traceutil/trace.go:171","msg":"trace[1203030871] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:24808; }","duration":"185.053595ms","start":"2026-06-02T22:18:53.521915Z","end":"2026-06-02T22:18:53.706969Z","steps":["trace[1203030871] 'agreement among raft nodes before linearized reading' (duration: 184.956703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:53.966462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.173309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:18:53.966535Z","caller":"traceutil/trace.go:171","msg":"trace[999138610] linearizableReadLoop","detail":"{readStateIndex:25613; appliedIndex:25612; }","duration":"259.817274ms","start":"2026-06-02T22:18:53.706704Z","end":"2026-06-02T22:18:53.966521Z","steps":["trace[999138610] 'read index received' (duration: 125.569913ms)","trace[999138610] 'applied index is now lower than readState.Index' (duration: 134.246631ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:53.966584Z","caller":"traceutil/trace.go:171","msg":"trace[754488755] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"284.037623ms","start":"2026-06-02T22:18:53.682517Z","end":"2026-06-02T22:18:53.966555Z","steps":["trace[754488755] 'process raft request' (duration: 149.715691ms)","trace[754488755] 'compare' (duration: 134.092207ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:18:53.966641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.193437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:18:53.966663Z","caller":"traceutil/trace.go:171","msg":"trace[159426563] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24809; }","duration":"279.245358ms","start":"2026-06-02T22:18:53.687411Z","end":"2026-06-02T22:18:53.966656Z","steps":["trace[159426563] 'agreement among raft nodes before linearized reading' (duration: 279.183936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:53.966828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.350481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:18:53.966904Z","caller":"traceutil/trace.go:171","msg":"trace[1481028264] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:24809; }","duration":"302.465003ms","start":"2026-06-02T22:18:53.66443Z","end":"2026-06-02T22:18:53.966895Z","steps":["trace[1481028264] 'agreement among raft nodes before linearized reading' (duration: 302.374171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:53.966937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:18:53.664405Z","time spent":"302.523275ms","remote":"127.0.0.1:43800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:18:53.967142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.898799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-bc06e26c-552d-4d65-b787-3ef45666b3f9\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-06-02T22:18:53.967168Z","caller":"traceutil/trace.go:171","msg":"trace[955694500] range","detail":"{range_begin:/registry/persistentvolumes/pvc-bc06e26c-552d-4d65-b787-3ef45666b3f9; range_end:; response_count:1; response_revision:24809; }","duration":"257.961351ms","start":"2026-06-02T22:18:53.7092Z","end":"2026-06-02T22:18:53.967162Z","steps":["trace[955694500] 'agreement among raft nodes before linearized reading' (duration: 257.878699ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:54.74789Z","caller":"traceutil/trace.go:171","msg":"trace[1277923572] linearizableReadLoop","detail":"{readStateIndex:25615; appliedIndex:25614; }","duration":"134.107388ms","start":"2026-06-02T22:18:54.613765Z","end":"2026-06-02T22:18:54.747872Z","steps":["trace[1277923572] 'read index received' (duration: 134.076367ms)","trace[1277923572] 'applied index is now lower than readState.Index' (duration: 29.911µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:18:54.748026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.257991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:18:54.747992Z","caller":"traceutil/trace.go:171","msg":"trace[864995306] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"145.49366ms","start":"2026-06-02T22:18:54.60246Z","end":"2026-06-02T22:18:54.747954Z","steps":["trace[864995306] 'process raft request' (duration: 145.303055ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:54.748049Z","caller":"traceutil/trace.go:171","msg":"trace[1218288898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24811; }","duration":"134.364324ms","start":"2026-06-02T22:18:54.613678Z","end":"2026-06-02T22:18:54.748042Z","steps":["trace[1218288898] 'agreement among raft nodes before linearized reading' (duration: 134.265882ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:54.781656Z","caller":"traceutil/trace.go:171","msg":"trace[651523331] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"165.213291ms","start":"2026-06-02T22:18:54.616408Z","end":"2026-06-02T22:18:54.781621Z","steps":["trace[651523331] 'process raft request' (duration: 164.440363ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:54.781714Z","caller":"traceutil/trace.go:171","msg":"trace[994093109] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"160.513269ms","start":"2026-06-02T22:18:54.621165Z","end":"2026-06-02T22:18:54.781678Z","steps":["trace[994093109] 'process raft request' (duration: 160.303764ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:54.969103Z","caller":"traceutil/trace.go:171","msg":"trace[1323116552] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"119.195961ms","start":"2026-06-02T22:18:54.849878Z","end":"2026-06-02T22:18:54.969074Z","steps":["trace[1323116552] 'process raft request' (duration: 56.041481ms)","trace[1323116552] 'compare' (duration: 63.058818ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:55.181592Z","caller":"traceutil/trace.go:171","msg":"trace[1694577022] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"107.324376ms","start":"2026-06-02T22:18:55.074247Z","end":"2026-06-02T22:18:55.181571Z","steps":["trace[1694577022] 'process raft request' (duration: 106.779784ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:55.486149Z","caller":"traceutil/trace.go:171","msg":"trace[744905430] linearizableReadLoop","detail":"{readStateIndex:25621; appliedIndex:25620; }","duration":"236.363954ms","start":"2026-06-02T22:18:55.249766Z","end":"2026-06-02T22:18:55.48613Z","steps":["trace[744905430] 'read index received' (duration: 236.19903ms)","trace[744905430] 'applied index is now lower than readState.Index' (duration: 164.224µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:55.486416Z","caller":"traceutil/trace.go:171","msg":"trace[1036027500] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"254.421825ms","start":"2026-06-02T22:18:55.231981Z","end":"2026-06-02T22:18:55.486403Z","steps":["trace[1036027500] 'process raft request' (duration: 254.034236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:55.486684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.905436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:18:55.486722Z","caller":"traceutil/trace.go:171","msg":"trace[48951995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24817; }","duration":"236.991119ms","start":"2026-06-02T22:18:55.249719Z","end":"2026-06-02T22:18:55.48671Z","steps":["trace[48951995] 'agreement among raft nodes before linearized reading' (duration: 236.873856ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:55.501704Z","caller":"traceutil/trace.go:171","msg":"trace[788434929] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"110.071152ms","start":"2026-06-02T22:18:55.391612Z","end":"2026-06-02T22:18:55.501683Z","steps":["trace[788434929] 'process raft request' (duration: 109.919919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:55.501806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.73461ms","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-06-02T22:18:55.5019Z","caller":"traceutil/trace.go:171","msg":"trace[37656713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24818; }","duration":"251.850023ms","start":"2026-06-02T22:18:55.250025Z","end":"2026-06-02T22:18:55.501875Z","steps":["trace[37656713] 'agreement among raft nodes before linearized reading' (duration: 251.634088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:55.502033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.869424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:18:55.502099Z","caller":"traceutil/trace.go:171","msg":"trace[536390081] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:24818; }","duration":"126.973937ms","start":"2026-06-02T22:18:55.3751Z","end":"2026-06-02T22:18:55.502074Z","steps":["trace[536390081] 'agreement among raft nodes before linearized reading' (duration: 126.862504ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:55.650923Z","caller":"traceutil/trace.go:171","msg":"trace[1671571142] linearizableReadLoop","detail":"{readStateIndex:25625; appliedIndex:25622; }","duration":"140.945902ms","start":"2026-06-02T22:18:55.509948Z","end":"2026-06-02T22:18:55.650894Z","steps":["trace[1671571142] 'read index received' (duration: 32.034687ms)","trace[1671571142] 'applied index is now lower than readState.Index' (duration: 108.909905ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:55.651004Z","caller":"traceutil/trace.go:171","msg":"trace[1502802840] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"144.566458ms","start":"2026-06-02T22:18:55.506423Z","end":"2026-06-02T22:18:55.650989Z","steps":["trace[1502802840] 'process raft request' (duration: 130.957833ms)","trace[1502802840] 'compare' (duration: 13.141963ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:55.651075Z","caller":"traceutil/trace.go:171","msg":"trace[1140894381] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"144.307542ms","start":"2026-06-02T22:18:55.50675Z","end":"2026-06-02T22:18:55.651057Z","steps":["trace[1140894381] 'process raft request' (duration: 144.060996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:55.651317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.361902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-06-02T22:18:55.651383Z","caller":"traceutil/trace.go:171","msg":"trace[1368596668] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24821; }","duration":"141.447184ms","start":"2026-06-02T22:18:55.509914Z","end":"2026-06-02T22:18:55.651361Z","steps":["trace[1368596668] 'agreement among raft nodes before linearized reading' (duration: 141.28589ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:56.02Z","caller":"traceutil/trace.go:171","msg":"trace[308761204] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"307.537195ms","start":"2026-06-02T22:18:55.71244Z","end":"2026-06-02T22:18:56.019977Z","steps":["trace[308761204] 'process raft request' (duration: 307.348771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:56.020146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:18:55.71242Z","time spent":"307.650268ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:18:56.382414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.801755ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-06-02T22:18:56.382494Z","caller":"traceutil/trace.go:171","msg":"trace[1101334406] linearizableReadLoop","detail":"{readStateIndex:25628; appliedIndex:25627; }","duration":"193.521669ms","start":"2026-06-02T22:18:56.18896Z","end":"2026-06-02T22:18:56.382481Z","steps":["trace[1101334406] 'read index received' (duration: 45.665092ms)","trace[1101334406] 'applied index is now lower than readState.Index' (duration: 147.855597ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:18:56.38256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:18:56.068068Z","time spent":"314.490411ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-02T22:18:56.382734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.774274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:18:56.382761Z","caller":"traceutil/trace.go:171","msg":"trace[337748478] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24823; }","duration":"193.845136ms","start":"2026-06-02T22:18:56.188908Z","end":"2026-06-02T22:18:56.382753Z","steps":["trace[337748478] 'agreement among raft nodes before linearized reading' (duration: 193.789185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:56.384596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.955374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:18:56.384691Z","caller":"traceutil/trace.go:171","msg":"trace[580853586] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:24824; }","duration":"134.097707ms","start":"2026-06-02T22:18:56.250581Z","end":"2026-06-02T22:18:56.384678Z","steps":["trace[580853586] 'agreement among raft nodes before linearized reading' (duration: 133.952433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:56.663207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.669386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:18:56.663287Z","caller":"traceutil/trace.go:171","msg":"trace[862125190] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24825; }","duration":"171.762128ms","start":"2026-06-02T22:18:56.491507Z","end":"2026-06-02T22:18:56.663269Z","steps":["trace[862125190] 'count revisions from in-memory index tree' (duration: 171.577673ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:57.097351Z","caller":"traceutil/trace.go:171","msg":"trace[28341735] linearizableReadLoop","detail":"{readStateIndex:25633; appliedIndex:25631; }","duration":"123.903413ms","start":"2026-06-02T22:18:56.973424Z","end":"2026-06-02T22:18:57.097328Z","steps":["trace[28341735] 'read index received' (duration: 113.472704ms)","trace[28341735] 'applied index is now lower than readState.Index' (duration: 10.429779ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:57.097515Z","caller":"traceutil/trace.go:171","msg":"trace[671356825] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"309.803681ms","start":"2026-06-02T22:18:56.787656Z","end":"2026-06-02T22:18:57.09746Z","steps":["trace[671356825] 'process raft request' (duration: 309.565925ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:57.097604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.153619ms","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-06-02T22:18:57.097646Z","caller":"traceutil/trace.go:171","msg":"trace[1557115146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24828; }","duration":"124.245342ms","start":"2026-06-02T22:18:56.973389Z","end":"2026-06-02T22:18:57.097635Z","steps":["trace[1557115146] 'agreement among raft nodes before linearized reading' (duration: 124.068758ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:57.097734Z","caller":"traceutil/trace.go:171","msg":"trace[784994413] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"310.528467ms","start":"2026-06-02T22:18:56.787194Z","end":"2026-06-02T22:18:57.097722Z","steps":["trace[784994413] 'process raft request' (duration: 299.678877ms)","trace[784994413] 'compare' (duration: 10.137413ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:18:57.097801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:18:56.787166Z","time spent":"310.595429ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:18:57.098068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:18:56.787631Z","time spent":"309.943093ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:18:57.276573Z","caller":"traceutil/trace.go:171","msg":"trace[1749211255] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"174.909013ms","start":"2026-06-02T22:18:57.101626Z","end":"2026-06-02T22:18:57.276535Z","steps":["trace[1749211255] 'process raft request' (duration: 126.055705ms)","trace[1749211255] 'compare' (duration: 48.249494ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:57.276585Z","caller":"traceutil/trace.go:171","msg":"trace[1237067567] linearizableReadLoop","detail":"{readStateIndex:25635; appliedIndex:25633; }","duration":"160.241882ms","start":"2026-06-02T22:18:57.116306Z","end":"2026-06-02T22:18:57.276547Z","steps":["trace[1237067567] 'read index received' (duration: 111.410893ms)","trace[1237067567] 'applied index is now lower than readState.Index' (duration: 48.829839ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:57.276605Z","caller":"traceutil/trace.go:171","msg":"trace[1913612766] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"173.050978ms","start":"2026-06-02T22:18:57.103527Z","end":"2026-06-02T22:18:57.276578Z","steps":["trace[1913612766] 'process raft request' (duration: 172.924665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:57.276834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.509099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:18:57.276869Z","caller":"traceutil/trace.go:171","msg":"trace[64758271] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:24831; }","duration":"160.57972ms","start":"2026-06-02T22:18:57.116278Z","end":"2026-06-02T22:18:57.276858Z","steps":["trace[64758271] 'agreement among raft nodes before linearized reading' (duration: 160.496848ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:59.26599Z","caller":"traceutil/trace.go:171","msg":"trace[1349309196] transaction","detail":"{read_only:false; response_revision:24846; number_of_response:1; }","duration":"163.402628ms","start":"2026-06-02T22:18:59.102568Z","end":"2026-06-02T22:18:59.265971Z","steps":["trace[1349309196] 'process raft request' (duration: 163.279315ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:59.422573Z","caller":"traceutil/trace.go:171","msg":"trace[1093049104] linearizableReadLoop","detail":"{readStateIndex:25652; appliedIndex:25651; }","duration":"136.077255ms","start":"2026-06-02T22:18:59.286476Z","end":"2026-06-02T22:18:59.422554Z","steps":["trace[1093049104] 'read index received' (duration: 75.790432ms)","trace[1093049104] 'applied index is now lower than readState.Index' (duration: 60.286143ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:59.422785Z","caller":"traceutil/trace.go:171","msg":"trace[391366021] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"318.768105ms","start":"2026-06-02T22:18:59.104006Z","end":"2026-06-02T22:18:59.422774Z","steps":["trace[391366021] 'process raft request' (duration: 258.198916ms)","trace[391366021] 'compare' (duration: 60.255081ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:18:59.422867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:18:59.10398Z","time spent":"318.826836ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:18:59.423063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.569326ms","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-06-02T22:18:59.423127Z","caller":"traceutil/trace.go:171","msg":"trace[1923603446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24847; }","duration":"136.669629ms","start":"2026-06-02T22:18:59.286441Z","end":"2026-06-02T22:18:59.42311Z","steps":["trace[1923603446] 'agreement among raft nodes before linearized reading' (duration: 136.455444ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:59.520006Z","caller":"traceutil/trace.go:171","msg":"trace[866604732] transaction","detail":"{read_only:false; response_revision:24848; number_of_response:1; }","duration":"232.095841ms","start":"2026-06-02T22:18:59.287882Z","end":"2026-06-02T22:18:59.519978Z","steps":["trace[866604732] 'process raft request' (duration: 231.889316ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:59.520046Z","caller":"traceutil/trace.go:171","msg":"trace[198560441] transaction","detail":"{read_only:false; response_revision:24849; number_of_response:1; }","duration":"187.258959ms","start":"2026-06-02T22:18:59.332763Z","end":"2026-06-02T22:18:59.520022Z","steps":["trace[198560441] 'process raft request' (duration: 187.139876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:59.520296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.749452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-02T22:18:59.520355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.266405ms","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-06-02T22:18:59.520368Z","caller":"traceutil/trace.go:171","msg":"trace[1223353969] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:24849; }","duration":"213.832294ms","start":"2026-06-02T22:18:59.306523Z","end":"2026-06-02T22:18:59.520355Z","steps":["trace[1223353969] 'agreement among raft nodes before linearized reading' (duration: 213.629249ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:59.520393Z","caller":"traceutil/trace.go:171","msg":"trace[1561084289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24849; }","duration":"232.386238ms","start":"2026-06-02T22:18:59.287996Z","end":"2026-06-02T22:18:59.520382Z","steps":["trace[1561084289] 'agreement among raft nodes before linearized reading' (duration: 232.218084ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:59.572992Z","caller":"traceutil/trace.go:171","msg":"trace[1918032157] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"146.370221ms","start":"2026-06-02T22:18:59.426599Z","end":"2026-06-02T22:18:59.572969Z","steps":["trace[1918032157] 'process raft request' (duration: 138.831571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:59.842525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.913997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:18:59.842705Z","caller":"traceutil/trace.go:171","msg":"trace[417072663] linearizableReadLoop","detail":"{readStateIndex:25657; appliedIndex:25655; }","duration":"269.898795ms","start":"2026-06-02T22:18:59.572795Z","end":"2026-06-02T22:18:59.842693Z","steps":["trace[417072663] 'read index received' (duration: 116.845074ms)","trace[417072663] 'applied index is now lower than readState.Index' (duration: 153.053041ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:18:59.842705Z","caller":"traceutil/trace.go:171","msg":"trace[1651994215] transaction","detail":"{read_only:false; response_revision:24852; number_of_response:1; }","duration":"314.154634ms","start":"2026-06-02T22:18:59.528532Z","end":"2026-06-02T22:18:59.842687Z","steps":["trace[1651994215] 'process raft request' (duration: 314.065532ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:59.84278Z","caller":"traceutil/trace.go:171","msg":"trace[1837046447] transaction","detail":"{read_only:false; response_revision:24851; number_of_response:1; }","duration":"315.265171ms","start":"2026-06-02T22:18:59.527504Z","end":"2026-06-02T22:18:59.842769Z","steps":["trace[1837046447] 'process raft request' (duration: 162.042956ms)","trace[1837046447] 'compare' (duration: 152.498918ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:18:59.842801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:18:59.528525Z","time spent":"314.223855ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:18:59.842822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:18:59.527468Z","time spent":"315.333393ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:18:59.842934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.775617ms","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:6"} {"level":"info","ts":"2026-06-02T22:18:59.842959Z","caller":"traceutil/trace.go:171","msg":"trace[758592055] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:24853; }","duration":"311.830158ms","start":"2026-06-02T22:18:59.531122Z","end":"2026-06-02T22:18:59.842952Z","steps":["trace[758592055] 'agreement among raft nodes before linearized reading' (duration: 311.633704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:59.842957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.630121ms","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-06-02T22:18:59.842979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:18:59.531108Z","time spent":"311.865299ms","remote":"127.0.0.1:33614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-06-02T22:18:59.843009Z","caller":"traceutil/trace.go:171","msg":"trace[937063450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24853; }","duration":"175.740113ms","start":"2026-06-02T22:18:59.667254Z","end":"2026-06-02T22:18:59.842994Z","steps":["trace[937063450] 'agreement among raft nodes before linearized reading' (duration: 175.570569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:18:59.842997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.150396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:18:59.843043Z","caller":"traceutil/trace.go:171","msg":"trace[501456470] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"265.546861ms","start":"2026-06-02T22:18:59.57749Z","end":"2026-06-02T22:18:59.843037Z","steps":["trace[501456470] 'process raft request' (duration: 265.181423ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:18:59.843076Z","caller":"traceutil/trace.go:171","msg":"trace[2070201366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24853; }","duration":"289.236498ms","start":"2026-06-02T22:18:59.553818Z","end":"2026-06-02T22:18:59.843055Z","steps":["trace[2070201366] 'agreement among raft nodes before linearized reading' (duration: 288.960521ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:13.007164Z","caller":"traceutil/trace.go:171","msg":"trace[1882282165] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"135.901319ms","start":"2026-06-02T22:19:12.871234Z","end":"2026-06-02T22:19:13.007136Z","steps":["trace[1882282165] 'process raft request' (duration: 135.744436ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:13.847431Z","caller":"traceutil/trace.go:171","msg":"trace[1035410635] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"159.058614ms","start":"2026-06-02T22:19:13.688331Z","end":"2026-06-02T22:19:13.84739Z","steps":["trace[1035410635] 'process raft request' (duration: 135.957882ms)","trace[1035410635] 'compare' (duration: 22.750104ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:14.045922Z","caller":"traceutil/trace.go:171","msg":"trace[188002542] linearizableReadLoop","detail":"{readStateIndex:25762; appliedIndex:25761; }","duration":"146.690008ms","start":"2026-06-02T22:19:13.899212Z","end":"2026-06-02T22:19:14.045902Z","steps":["trace[188002542] 'read index received' (duration: 146.550325ms)","trace[188002542] 'applied index is now lower than readState.Index' (duration: 138.753µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:14.045991Z","caller":"traceutil/trace.go:171","msg":"trace[1970780563] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"154.66123ms","start":"2026-06-02T22:19:13.891294Z","end":"2026-06-02T22:19:14.045955Z","steps":["trace[1970780563] 'process raft request' (duration: 154.473255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:14.046109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.890653ms","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-06-02T22:19:14.046136Z","caller":"traceutil/trace.go:171","msg":"trace[277109556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24954; }","duration":"146.971195ms","start":"2026-06-02T22:19:13.899156Z","end":"2026-06-02T22:19:14.046127Z","steps":["trace[277109556] 'agreement among raft nodes before linearized reading' (duration: 146.857132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:14.046194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.434032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:19:14.046246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.004439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:19:14.046273Z","caller":"traceutil/trace.go:171","msg":"trace[1594376267] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:24954; }","duration":"105.569086ms","start":"2026-06-02T22:19:13.940685Z","end":"2026-06-02T22:19:14.046254Z","steps":["trace[1594376267] 'agreement among raft nodes before linearized reading' (duration: 105.436693ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:14.046332Z","caller":"traceutil/trace.go:171","msg":"trace[1263398723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24954; }","duration":"145.129132ms","start":"2026-06-02T22:19:13.90118Z","end":"2026-06-02T22:19:14.046309Z","steps":["trace[1263398723] 'agreement among raft nodes before linearized reading' (duration: 144.883626ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:14.251879Z","caller":"traceutil/trace.go:171","msg":"trace[342570464] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"200.771992ms","start":"2026-06-02T22:19:14.051075Z","end":"2026-06-02T22:19:14.251847Z","steps":["trace[342570464] 'process raft request' (duration: 134.423415ms)","trace[342570464] 'compare' (duration: 66.215903ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:14.253529Z","caller":"traceutil/trace.go:171","msg":"trace[1396523138] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"202.143914ms","start":"2026-06-02T22:19:14.051356Z","end":"2026-06-02T22:19:14.253499Z","steps":["trace[1396523138] 'process raft request' (duration: 202.077683ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:14.25356Z","caller":"traceutil/trace.go:171","msg":"trace[499362126] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"202.320699ms","start":"2026-06-02T22:19:14.051203Z","end":"2026-06-02T22:19:14.253524Z","steps":["trace[499362126] 'process raft request' (duration: 202.014242ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:14.253849Z","caller":"traceutil/trace.go:171","msg":"trace[751995579] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"201.644643ms","start":"2026-06-02T22:19:14.052176Z","end":"2026-06-02T22:19:14.25382Z","steps":["trace[751995579] 'process raft request' (duration: 201.292644ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:14.254169Z","caller":"traceutil/trace.go:171","msg":"trace[1742803397] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"110.196606ms","start":"2026-06-02T22:19:14.143959Z","end":"2026-06-02T22:19:14.254156Z","steps":["trace[1742803397] 'process raft request' (duration: 109.9395ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:14.254047Z","caller":"traceutil/trace.go:171","msg":"trace[1002194229] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"162.939296ms","start":"2026-06-02T22:19:14.091081Z","end":"2026-06-02T22:19:14.254021Z","steps":["trace[1002194229] 'process raft request' (duration: 162.65667ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:14.656438Z","caller":"traceutil/trace.go:171","msg":"trace[180677436] linearizableReadLoop","detail":"{readStateIndex:25769; appliedIndex:25768; }","duration":"154.843333ms","start":"2026-06-02T22:19:14.50157Z","end":"2026-06-02T22:19:14.656413Z","steps":["trace[180677436] 'read index received' (duration: 154.518715ms)","trace[180677436] 'applied index is now lower than readState.Index' (duration: 323.578µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:14.656541Z","caller":"traceutil/trace.go:171","msg":"trace[1905611310] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"204.709016ms","start":"2026-06-02T22:19:14.451801Z","end":"2026-06-02T22:19:14.65651Z","steps":["trace[1905611310] 'process raft request' (duration: 204.394509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:14.656645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.083015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:14.656686Z","caller":"traceutil/trace.go:171","msg":"trace[1237010879] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:24961; }","duration":"103.151217ms","start":"2026-06-02T22:19:14.553522Z","end":"2026-06-02T22:19:14.656673Z","steps":["trace[1237010879] 'agreement among raft nodes before linearized reading' (duration: 103.052085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:14.656724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.135ms","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-06-02T22:19:14.656808Z","caller":"traceutil/trace.go:171","msg":"trace[1461207627] 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:24961; }","duration":"155.270103ms","start":"2026-06-02T22:19:14.501516Z","end":"2026-06-02T22:19:14.656786Z","steps":["trace[1461207627] 'agreement among raft nodes before linearized reading' (duration: 155.028677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:15.87607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.437024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:19:15.876585Z","caller":"traceutil/trace.go:171","msg":"trace[2049992192] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"289.665097ms","start":"2026-06-02T22:19:15.586833Z","end":"2026-06-02T22:19:15.876498Z","steps":["trace[2049992192] 'process raft request' (duration: 149.721731ms)","trace[2049992192] 'compare' (duration: 138.82742ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:15.876728Z","caller":"traceutil/trace.go:171","msg":"trace[1515626037] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"187.453063ms","start":"2026-06-02T22:19:15.689242Z","end":"2026-06-02T22:19:15.876695Z","steps":["trace[1515626037] 'process raft request' (duration: 187.211336ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:16.046856Z","caller":"traceutil/trace.go:171","msg":"trace[1562539411] linearizableReadLoop","detail":"{readStateIndex:25776; appliedIndex:25775; }","duration":"164.32907ms","start":"2026-06-02T22:19:15.882501Z","end":"2026-06-02T22:19:16.04683Z","steps":["trace[1562539411] 'read index received' (duration: 148.821869ms)","trace[1562539411] 'applied index is now lower than readState.Index' (duration: 15.505861ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:16.047094Z","caller":"traceutil/trace.go:171","msg":"trace[120787246] transaction","detail":"{read_only:false; response_revision:24968; number_of_response:1; }","duration":"165.641691ms","start":"2026-06-02T22:19:15.881419Z","end":"2026-06-02T22:19:16.04706Z","steps":["trace[120787246] 'process raft request' (duration: 149.442164ms)","trace[120787246] 'compare' (duration: 15.738916ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:16.047205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.679748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-06-02T22:19:16.047798Z","caller":"traceutil/trace.go:171","msg":"trace[244103296] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24968; }","duration":"165.292233ms","start":"2026-06-02T22:19:15.882484Z","end":"2026-06-02T22:19:16.047776Z","steps":["trace[244103296] 'agreement among raft nodes before linearized reading' (duration: 164.545944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.047308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.770226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1651"} {"level":"info","ts":"2026-06-02T22:19:16.047906Z","caller":"traceutil/trace.go:171","msg":"trace[2054870373] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24968; }","duration":"164.385772ms","start":"2026-06-02T22:19:15.883502Z","end":"2026-06-02T22:19:16.047888Z","steps":["trace[2054870373] 'agreement among raft nodes before linearized reading' (duration: 163.684764ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:16.398125Z","caller":"traceutil/trace.go:171","msg":"trace[873560037] linearizableReadLoop","detail":"{readStateIndex:25779; appliedIndex:25776; }","duration":"321.151372ms","start":"2026-06-02T22:19:16.07695Z","end":"2026-06-02T22:19:16.398101Z","steps":["trace[873560037] 'read index received' (duration: 75.743522ms)","trace[873560037] 'applied index is now lower than readState.Index' (duration: 245.40677ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:16.398154Z","caller":"traceutil/trace.go:171","msg":"trace[1291771901] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"347.000839ms","start":"2026-06-02T22:19:16.051112Z","end":"2026-06-02T22:19:16.398113Z","steps":["trace[1291771901] 'process raft request' (duration: 346.721502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.398284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.311875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:16.398326Z","caller":"traceutil/trace.go:171","msg":"trace[420297329] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:24970; }","duration":"321.450269ms","start":"2026-06-02T22:19:16.076863Z","end":"2026-06-02T22:19:16.398314Z","steps":["trace[420297329] 'agreement among raft nodes before linearized reading' (duration: 321.355396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.398344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:16.05109Z","time spent":"347.149823ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:19:16.398399Z","caller":"traceutil/trace.go:171","msg":"trace[1768498064] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"343.892145ms","start":"2026-06-02T22:19:16.054469Z","end":"2026-06-02T22:19:16.398361Z","steps":["trace[1768498064] 'process raft request' (duration: 343.531646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.398374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:16.07685Z","time spent":"321.51027ms","remote":"127.0.0.1:47546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:19:16.398552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:16.054446Z","time spent":"344.026848ms","remote":"127.0.0.1:59122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1946,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:19:16.465065Z","caller":"traceutil/trace.go:171","msg":"trace[653211026] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"225.960374ms","start":"2026-06-02T22:19:16.239061Z","end":"2026-06-02T22:19:16.465021Z","steps":["trace[653211026] 'process raft request' (duration: 225.673307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.465214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.168199ms","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-06-02T22:19:16.465908Z","caller":"traceutil/trace.go:171","msg":"trace[440062854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24973; }","duration":"208.907607ms","start":"2026-06-02T22:19:16.256979Z","end":"2026-06-02T22:19:16.465886Z","steps":["trace[440062854] 'agreement among raft nodes before linearized reading' (duration: 208.096268ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:16.465231Z","caller":"traceutil/trace.go:171","msg":"trace[1065382421] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"204.866621ms","start":"2026-06-02T22:19:16.260305Z","end":"2026-06-02T22:19:16.465172Z","steps":["trace[1065382421] 'process raft request' (duration: 204.625495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.465226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.500513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:19:16.466098Z","caller":"traceutil/trace.go:171","msg":"trace[974942582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24973; }","duration":"208.423274ms","start":"2026-06-02T22:19:16.257661Z","end":"2026-06-02T22:19:16.466084Z","steps":["trace[974942582] 'agreement among raft nodes before linearized reading' (duration: 207.38699ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:16.465283Z","caller":"traceutil/trace.go:171","msg":"trace[1151979499] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"206.170251ms","start":"2026-06-02T22:19:16.258853Z","end":"2026-06-02T22:19:16.465023Z","steps":["trace[1151979499] 'process raft request' (duration: 206.029428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.465279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.035983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:16.466308Z","caller":"traceutil/trace.go:171","msg":"trace[1816605123] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:24973; }","duration":"129.127198ms","start":"2026-06-02T22:19:16.337163Z","end":"2026-06-02T22:19:16.466291Z","steps":["trace[1816605123] 'agreement among raft nodes before linearized reading' (duration: 128.030982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.465318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.898334ms","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-06-02T22:19:16.466406Z","caller":"traceutil/trace.go:171","msg":"trace[862763433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24973; }","duration":"209.03244ms","start":"2026-06-02T22:19:16.257344Z","end":"2026-06-02T22:19:16.466376Z","steps":["trace[862763433] 'agreement among raft nodes before linearized reading' (duration: 207.801751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.465407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.527652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:19:16.466624Z","caller":"traceutil/trace.go:171","msg":"trace[2077360100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24973; }","duration":"210.778421ms","start":"2026-06-02T22:19:16.255823Z","end":"2026-06-02T22:19:16.466601Z","steps":["trace[2077360100] 'agreement among raft nodes before linearized reading' (duration: 209.42683ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:16.603563Z","caller":"traceutil/trace.go:171","msg":"trace[487966244] linearizableReadLoop","detail":"{readStateIndex:25783; appliedIndex:25782; }","duration":"138.552034ms","start":"2026-06-02T22:19:16.464985Z","end":"2026-06-02T22:19:16.603537Z","steps":["trace[487966244] 'read index received' (duration: 100.477673ms)","trace[487966244] 'applied index is now lower than readState.Index' (duration: 38.073171ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:16.603624Z","caller":"traceutil/trace.go:171","msg":"trace[508094671] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"196.866428ms","start":"2026-06-02T22:19:16.406719Z","end":"2026-06-02T22:19:16.603585Z","steps":["trace[508094671] 'process raft request' (duration: 158.742076ms)","trace[508094671] 'compare' (duration: 37.909937ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:16.60369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.49174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:16.603746Z","caller":"traceutil/trace.go:171","msg":"trace[590622070] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:24974; }","duration":"145.591242ms","start":"2026-06-02T22:19:16.458136Z","end":"2026-06-02T22:19:16.603727Z","steps":["trace[590622070] 'agreement among raft nodes before linearized reading' (duration: 145.492ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:16.664516Z","caller":"traceutil/trace.go:171","msg":"trace[1312204595] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"193.918798ms","start":"2026-06-02T22:19:16.470556Z","end":"2026-06-02T22:19:16.664475Z","steps":["trace[1312204595] 'process raft request' (duration: 193.728403ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:16.664778Z","caller":"traceutil/trace.go:171","msg":"trace[1115845894] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"194.054501ms","start":"2026-06-02T22:19:16.470698Z","end":"2026-06-02T22:19:16.664752Z","steps":["trace[1115845894] 'process raft request' (duration: 193.724323ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:16.664819Z","caller":"traceutil/trace.go:171","msg":"trace[641596684] transaction","detail":"{read_only:false; response_revision:24978; number_of_response:1; }","duration":"193.460767ms","start":"2026-06-02T22:19:16.471343Z","end":"2026-06-02T22:19:16.664804Z","steps":["trace[641596684] 'process raft request' (duration: 193.362505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.664912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.59068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:16.664977Z","caller":"traceutil/trace.go:171","msg":"trace[1060852649] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24978; }","duration":"180.715113ms","start":"2026-06-02T22:19:16.484243Z","end":"2026-06-02T22:19:16.664958Z","steps":["trace[1060852649] 'agreement among raft nodes before linearized reading' (duration: 180.56981ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:16.664981Z","caller":"traceutil/trace.go:171","msg":"trace[1998819612] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"194.215535ms","start":"2026-06-02T22:19:16.470733Z","end":"2026-06-02T22:19:16.664949Z","steps":["trace[1998819612] 'process raft request' (duration: 193.874877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.665038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.778071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:16.665086Z","caller":"traceutil/trace.go:171","msg":"trace[195831993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24978; }","duration":"197.857783ms","start":"2026-06-02T22:19:16.467214Z","end":"2026-06-02T22:19:16.665072Z","steps":["trace[195831993] 'agreement among raft nodes before linearized reading' (duration: 197.766201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.665088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.331126ms","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-06-02T22:19:16.665135Z","caller":"traceutil/trace.go:171","msg":"trace[1042947] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24978; }","duration":"196.383007ms","start":"2026-06-02T22:19:16.468739Z","end":"2026-06-02T22:19:16.665122Z","steps":["trace[1042947] 'agreement among raft nodes before linearized reading' (duration: 196.224963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.900903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.425629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:19:16.901003Z","caller":"traceutil/trace.go:171","msg":"trace[609033062] linearizableReadLoop","detail":"{readStateIndex:25788; appliedIndex:25787; }","duration":"236.198639ms","start":"2026-06-02T22:19:16.664787Z","end":"2026-06-02T22:19:16.900985Z","steps":["trace[609033062] 'read index received' (duration: 100.544945ms)","trace[609033062] 'applied index is now lower than readState.Index' (duration: 135.652224ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:16.901179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.368702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:16.901215Z","caller":"traceutil/trace.go:171","msg":"trace[220249165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24979; }","duration":"287.424234ms","start":"2026-06-02T22:19:16.613781Z","end":"2026-06-02T22:19:16.901205Z","steps":["trace[220249165] 'agreement among raft nodes before linearized reading' (duration: 287.291761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.90119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.981151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:16.901307Z","caller":"traceutil/trace.go:171","msg":"trace[462137828] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:24979; }","duration":"271.149255ms","start":"2026-06-02T22:19:16.630134Z","end":"2026-06-02T22:19:16.901283Z","steps":["trace[462137828] 'agreement among raft nodes before linearized reading' (duration: 270.921079ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:16.901356Z","caller":"traceutil/trace.go:171","msg":"trace[825511626] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"238.828022ms","start":"2026-06-02T22:19:16.662505Z","end":"2026-06-02T22:19:16.901333Z","steps":["trace[825511626] 'process raft request' (duration: 102.890771ms)","trace[825511626] 'compare' (duration: 135.167883ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:16.901381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.16667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-02T22:19:16.901412Z","caller":"traceutil/trace.go:171","msg":"trace[731877809] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24979; }","duration":"234.250282ms","start":"2026-06-02T22:19:16.667154Z","end":"2026-06-02T22:19:16.901405Z","steps":["trace[731877809] 'agreement among raft nodes before linearized reading' (duration: 234.147979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.901413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.599347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:16.901512Z","caller":"traceutil/trace.go:171","msg":"trace[442934645] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:0; response_revision:24979; }","duration":"292.728471ms","start":"2026-06-02T22:19:16.608766Z","end":"2026-06-02T22:19:16.901494Z","steps":["trace[442934645] 'agreement among raft nodes before linearized reading' (duration: 292.584657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:16.901643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.466392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:16.901756Z","caller":"traceutil/trace.go:171","msg":"trace[1460169770] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24979; }","duration":"230.661426ms","start":"2026-06-02T22:19:16.671076Z","end":"2026-06-02T22:19:16.901738Z","steps":["trace[1460169770] 'agreement among raft nodes before linearized reading' (duration: 230.282778ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:17.223001Z","caller":"traceutil/trace.go:171","msg":"trace[281739398] linearizableReadLoop","detail":"{readStateIndex:25789; appliedIndex:25788; }","duration":"316.09061ms","start":"2026-06-02T22:19:16.906884Z","end":"2026-06-02T22:19:17.222975Z","steps":["trace[281739398] 'read index received' (duration: 273.999803ms)","trace[281739398] 'applied index is now lower than readState.Index' (duration: 42.089937ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:17.223106Z","caller":"traceutil/trace.go:171","msg":"trace[988238925] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"317.31243ms","start":"2026-06-02T22:19:16.90577Z","end":"2026-06-02T22:19:17.223083Z","steps":["trace[988238925] 'process raft request' (duration: 275.064239ms)","trace[988238925] 'compare' (duration: 41.913923ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:17.223133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.233473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:17.223157Z","caller":"traceutil/trace.go:171","msg":"trace[156160200] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24980; }","duration":"316.285375ms","start":"2026-06-02T22:19:16.906866Z","end":"2026-06-02T22:19:17.223151Z","steps":["trace[156160200] 'agreement among raft nodes before linearized reading' (duration: 316.183133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:17.223179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:16.906858Z","time spent":"316.315806ms","remote":"127.0.0.1:44408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-06-02T22:19:17.223195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:16.905752Z","time spent":"317.402951ms","remote":"127.0.0.1:44334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1240,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T22:19:17.224408Z","caller":"traceutil/trace.go:171","msg":"trace[1320664019] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"111.622109ms","start":"2026-06-02T22:19:17.112766Z","end":"2026-06-02T22:19:17.224388Z","steps":["trace[1320664019] 'process raft request' (duration: 111.492646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:18.502174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.559084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c913444-8291-4127-bb6c-9a2932cfea43\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-06-02T22:19:18.502258Z","caller":"traceutil/trace.go:171","msg":"trace[1528565566] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c913444-8291-4127-bb6c-9a2932cfea43; range_end:; response_count:1; response_revision:25042; }","duration":"125.685426ms","start":"2026-06-02T22:19:18.376549Z","end":"2026-06-02T22:19:18.502234Z","steps":["trace[1528565566] 'range keys from in-memory index tree' (duration: 125.397119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:18.502432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.367673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c913444-8291-4127-bb6c-9a2932cfea43\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-06-02T22:19:18.502522Z","caller":"traceutil/trace.go:171","msg":"trace[1967746753] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c913444-8291-4127-bb6c-9a2932cfea43; range_end:; response_count:1; response_revision:25042; }","duration":"121.454555ms","start":"2026-06-02T22:19:18.381036Z","end":"2026-06-02T22:19:18.502491Z","steps":["trace[1967746753] 'range keys from in-memory index tree' (duration: 121.25042ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:20.671582Z","caller":"traceutil/trace.go:171","msg":"trace[577073146] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"164.126545ms","start":"2026-06-02T22:19:20.507441Z","end":"2026-06-02T22:19:20.671567Z","steps":["trace[577073146] 'process raft request' (duration: 164.005582ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:20.671682Z","caller":"traceutil/trace.go:171","msg":"trace[1102650808] linearizableReadLoop","detail":"{readStateIndex:25868; appliedIndex:25868; }","duration":"160.401466ms","start":"2026-06-02T22:19:20.511251Z","end":"2026-06-02T22:19:20.671652Z","steps":["trace[1102650808] 'read index received' (duration: 160.389416ms)","trace[1102650808] 'applied index is now lower than readState.Index' (duration: 9.69µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:20.671849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.59322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c913444-8291-4127-bb6c-9a2932cfea43\" ","response":"range_response_count:1 size:3946"} {"level":"info","ts":"2026-06-02T22:19:20.671884Z","caller":"traceutil/trace.go:171","msg":"trace[2019388828] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c913444-8291-4127-bb6c-9a2932cfea43; range_end:; response_count:1; response_revision:25059; }","duration":"160.668432ms","start":"2026-06-02T22:19:20.511209Z","end":"2026-06-02T22:19:20.671877Z","steps":["trace[2019388828] 'agreement among raft nodes before linearized reading' (duration: 160.547189ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:20.814466Z","caller":"traceutil/trace.go:171","msg":"trace[2047698162] linearizableReadLoop","detail":"{readStateIndex:25870; appliedIndex:25868; }","duration":"142.690543ms","start":"2026-06-02T22:19:20.671752Z","end":"2026-06-02T22:19:20.814443Z","steps":["trace[2047698162] 'read index received' (duration: 87.401011ms)","trace[2047698162] 'applied index is now lower than readState.Index' (duration: 55.288492ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:20.814643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.327695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:20.814623Z","caller":"traceutil/trace.go:171","msg":"trace[1705566332] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"247.778605ms","start":"2026-06-02T22:19:20.566803Z","end":"2026-06-02T22:19:20.814581Z","steps":["trace[1705566332] 'process raft request' (duration: 192.313119ms)","trace[1705566332] 'compare' (duration: 55.128148ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:20.814691Z","caller":"traceutil/trace.go:171","msg":"trace[2049638734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25061; }","duration":"201.427898ms","start":"2026-06-02T22:19:20.613253Z","end":"2026-06-02T22:19:20.814681Z","steps":["trace[2049638734] 'agreement among raft nodes before linearized reading' (duration: 201.336946ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:20.814717Z","caller":"traceutil/trace.go:171","msg":"trace[456102728] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"247.071749ms","start":"2026-06-02T22:19:20.567628Z","end":"2026-06-02T22:19:20.8147Z","steps":["trace[456102728] 'process raft request' (duration: 246.755982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:20.814745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.926379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:20.814772Z","caller":"traceutil/trace.go:171","msg":"trace[944050886] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25061; }","duration":"285.98721ms","start":"2026-06-02T22:19:20.528778Z","end":"2026-06-02T22:19:20.814765Z","steps":["trace[944050886] 'agreement among raft nodes before linearized reading' (duration: 285.927678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:20.814652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.394967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-02T22:19:20.814868Z","caller":"traceutil/trace.go:171","msg":"trace[1000158744] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:25061; }","duration":"145.610642ms","start":"2026-06-02T22:19:20.669226Z","end":"2026-06-02T22:19:20.814836Z","steps":["trace[1000158744] 'agreement among raft nodes before linearized reading' (duration: 145.271254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:20.814922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.420805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-02T22:19:20.814931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.925855ms","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-06-02T22:19:20.814937Z","caller":"traceutil/trace.go:171","msg":"trace[2145328684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25061; }","duration":"134.461666ms","start":"2026-06-02T22:19:20.680471Z","end":"2026-06-02T22:19:20.814932Z","steps":["trace[2145328684] 'agreement among raft nodes before linearized reading' (duration: 134.389004ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:20.81496Z","caller":"traceutil/trace.go:171","msg":"trace[1052323512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25061; }","duration":"136.965656ms","start":"2026-06-02T22:19:20.677979Z","end":"2026-06-02T22:19:20.814945Z","steps":["trace[1052323512] 'agreement among raft nodes before linearized reading' (duration: 136.869824ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:20.814927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.364468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:19:20.814963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.731064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:19:20.81501Z","caller":"traceutil/trace.go:171","msg":"trace[1322044450] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25061; }","duration":"278.49757ms","start":"2026-06-02T22:19:20.53649Z","end":"2026-06-02T22:19:20.814988Z","steps":["trace[1322044450] 'agreement among raft nodes before linearized reading' (duration: 278.329306ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:20.815043Z","caller":"traceutil/trace.go:171","msg":"trace[767253722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25061; }","duration":"137.828396ms","start":"2026-06-02T22:19:20.677196Z","end":"2026-06-02T22:19:20.815024Z","steps":["trace[767253722] 'agreement among raft nodes before linearized reading' (duration: 137.58358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:20.815054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.507008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:19:20.815151Z","caller":"traceutil/trace.go:171","msg":"trace[2076365223] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25061; }","duration":"137.615181ms","start":"2026-06-02T22:19:20.677517Z","end":"2026-06-02T22:19:20.815132Z","steps":["trace[2076365223] 'agreement among raft nodes before linearized reading' (duration: 137.502219ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:24.943647Z","caller":"traceutil/trace.go:171","msg":"trace[464888850] linearizableReadLoop","detail":"{readStateIndex:25923; appliedIndex:25921; }","duration":"110.456991ms","start":"2026-06-02T22:19:24.833166Z","end":"2026-06-02T22:19:24.943623Z","steps":["trace[464888850] 'read index received' (duration: 61.671335ms)","trace[464888850] 'applied index is now lower than readState.Index' (duration: 48.784386ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:24.943762Z","caller":"traceutil/trace.go:171","msg":"trace[1958881315] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"114.97009ms","start":"2026-06-02T22:19:24.828779Z","end":"2026-06-02T22:19:24.943749Z","steps":["trace[1958881315] 'process raft request' (duration: 114.659303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:24.943978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.79351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:24.94409Z","caller":"traceutil/trace.go:171","msg":"trace[285548944] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25112; }","duration":"110.941173ms","start":"2026-06-02T22:19:24.833132Z","end":"2026-06-02T22:19:24.944073Z","steps":["trace[285548944] 'agreement among raft nodes before linearized reading' (duration: 110.776939ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:25.153545Z","caller":"traceutil/trace.go:171","msg":"trace[1162313430] linearizableReadLoop","detail":"{readStateIndex:25924; appliedIndex:25923; }","duration":"209.808838ms","start":"2026-06-02T22:19:24.943714Z","end":"2026-06-02T22:19:25.153523Z","steps":["trace[1162313430] 'read index received' (duration: 209.640294ms)","trace[1162313430] 'applied index is now lower than readState.Index' (duration: 167.214µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:19:25.153647Z","caller":"traceutil/trace.go:171","msg":"trace[1272423432] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"220.029912ms","start":"2026-06-02T22:19:24.933587Z","end":"2026-06-02T22:19:25.153617Z","steps":["trace[1272423432] 'process raft request' (duration: 219.793956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:25.153744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.837571ms","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-06-02T22:19:25.153779Z","caller":"traceutil/trace.go:171","msg":"trace[1645116025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25113; }","duration":"304.920293ms","start":"2026-06-02T22:19:24.848849Z","end":"2026-06-02T22:19:25.15377Z","steps":["trace[1645116025] 'agreement among raft nodes before linearized reading' (duration: 304.759629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:25.153768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.354813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:19:25.153852Z","caller":"traceutil/trace.go:171","msg":"trace[1976244153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25113; }","duration":"300.469416ms","start":"2026-06-02T22:19:24.853366Z","end":"2026-06-02T22:19:25.153835Z","steps":["trace[1976244153] 'agreement among raft nodes before linearized reading' (duration: 300.200669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:25.153886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:24.853357Z","time spent":"300.522687ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T22:19:25.153813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:24.848832Z","time spent":"304.972834ms","remote":"127.0.0.1:44402","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-06-02T22:19:25.153982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.932707ms","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-06-02T22:19:25.154057Z","caller":"traceutil/trace.go:171","msg":"trace[563533431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25113; }","duration":"301.025979ms","start":"2026-06-02T22:19:24.853022Z","end":"2026-06-02T22:19:25.154048Z","steps":["trace[563533431] 'agreement among raft nodes before linearized reading' (duration: 300.807254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:25.154062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.424086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-02T22:19:25.154114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:24.853012Z","time spent":"301.093231ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T22:19:25.15414Z","caller":"traceutil/trace.go:171","msg":"trace[1099986918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25113; }","duration":"303.533679ms","start":"2026-06-02T22:19:24.850584Z","end":"2026-06-02T22:19:25.154118Z","steps":["trace[1099986918] 'agreement among raft nodes before linearized reading' (duration: 303.307224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:25.154186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:24.850569Z","time spent":"303.608091ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T22:19:25.47907Z","caller":"traceutil/trace.go:171","msg":"trace[431950675] linearizableReadLoop","detail":"{readStateIndex:25929; appliedIndex:25928; }","duration":"208.848345ms","start":"2026-06-02T22:19:25.270203Z","end":"2026-06-02T22:19:25.479052Z","steps":["trace[431950675] 'read index received' (duration: 208.731572ms)","trace[431950675] 'applied index is now lower than readState.Index' (duration: 115.923µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:25.47918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.965168ms","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-06-02T22:19:25.479202Z","caller":"traceutil/trace.go:171","msg":"trace[1839773870] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25118; }","duration":"209.03767ms","start":"2026-06-02T22:19:25.270158Z","end":"2026-06-02T22:19:25.479195Z","steps":["trace[1839773870] 'agreement among raft nodes before linearized reading' (duration: 208.966038ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:25.479285Z","caller":"traceutil/trace.go:171","msg":"trace[1962775945] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"227.066911ms","start":"2026-06-02T22:19:25.252192Z","end":"2026-06-02T22:19:25.479259Z","steps":["trace[1962775945] 'process raft request' (duration: 226.743333ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:30.522718Z","caller":"traceutil/trace.go:171","msg":"trace[1788113417] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"112.757697ms","start":"2026-06-02T22:19:30.409932Z","end":"2026-06-02T22:19:30.52269Z","steps":["trace[1788113417] 'process raft request' (duration: 112.620654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:30.789143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.638228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:30.789231Z","caller":"traceutil/trace.go:171","msg":"trace[62803365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25161; }","duration":"174.771441ms","start":"2026-06-02T22:19:30.614437Z","end":"2026-06-02T22:19:30.789209Z","steps":["trace[62803365] 'range keys from in-memory index tree' (duration: 174.543575ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:49.840835Z","caller":"traceutil/trace.go:171","msg":"trace[1904901097] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"130.41986ms","start":"2026-06-02T22:19:49.710394Z","end":"2026-06-02T22:19:49.840814Z","steps":["trace[1904901097] 'process raft request' (duration: 130.301708ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:56.031637Z","caller":"traceutil/trace.go:171","msg":"trace[1821018568] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"366.887595ms","start":"2026-06-02T22:19:55.664718Z","end":"2026-06-02T22:19:56.031606Z","steps":["trace[1821018568] 'process raft request' (duration: 354.060288ms)","trace[1821018568] 'compare' (duration: 12.470958ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:56.031767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:55.664694Z","time spent":"366.997957ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:19:56.141437Z","caller":"traceutil/trace.go:171","msg":"trace[45189228] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"474.827907ms","start":"2026-06-02T22:19:55.666589Z","end":"2026-06-02T22:19:56.141417Z","steps":["trace[45189228] 'process raft request' (duration: 469.923329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.14157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:55.666566Z","time spent":"474.93707ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:19:56.141749Z","caller":"traceutil/trace.go:171","msg":"trace[453012694] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"282.670181ms","start":"2026-06-02T22:19:55.859068Z","end":"2026-06-02T22:19:56.141739Z","steps":["trace[453012694] 'process raft request' (duration: 282.243061ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:56.141749Z","caller":"traceutil/trace.go:171","msg":"trace[1762924387] linearizableReadLoop","detail":"{readStateIndex:26176; appliedIndex:26174; }","duration":"456.711264ms","start":"2026-06-02T22:19:55.68501Z","end":"2026-06-02T22:19:56.141721Z","steps":["trace[1762924387] 'read index received' (duration: 333.755283ms)","trace[1762924387] 'applied index is now lower than readState.Index' (duration: 122.954181ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:19:56.142049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.016231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-02T22:19:56.142094Z","caller":"traceutil/trace.go:171","msg":"trace[236877603] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25360; }","duration":"457.107012ms","start":"2026-06-02T22:19:55.684975Z","end":"2026-06-02T22:19:56.142082Z","steps":["trace[236877603] 'agreement among raft nodes before linearized reading' (duration: 456.891567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.142129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:55.684963Z","time spent":"457.157584ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-06-02T22:19:56.149583Z","caller":"traceutil/trace.go:171","msg":"trace[1760569135] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"128.7317ms","start":"2026-06-02T22:19:56.020833Z","end":"2026-06-02T22:19:56.149565Z","steps":["trace[1760569135] 'process raft request' (duration: 128.557666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.149673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.11623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-02T22:19:56.149726Z","caller":"traceutil/trace.go:171","msg":"trace[1511229171] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:25361; }","duration":"137.204661ms","start":"2026-06-02T22:19:56.012511Z","end":"2026-06-02T22:19:56.149716Z","steps":["trace[1511229171] 'agreement among raft nodes before linearized reading' (duration: 137.012557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.1497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.155457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-06-02T22:19:56.149662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.785073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:19:56.149776Z","caller":"traceutil/trace.go:171","msg":"trace[1030240450] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25361; }","duration":"399.25688ms","start":"2026-06-02T22:19:55.750501Z","end":"2026-06-02T22:19:56.149758Z","steps":["trace[1030240450] 'agreement among raft nodes before linearized reading' (duration: 399.015084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:19:56.149794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.247042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-02T22:19:56.149826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:19:55.750492Z","time spent":"399.325892ms","remote":"127.0.0.1:44256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-06-02T22:19:56.149862Z","caller":"traceutil/trace.go:171","msg":"trace[1589294951] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25361; }","duration":"262.384246ms","start":"2026-06-02T22:19:55.88746Z","end":"2026-06-02T22:19:56.149844Z","steps":["trace[1589294951] 'agreement among raft nodes before linearized reading' (duration: 262.195782ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:19:56.149808Z","caller":"traceutil/trace.go:171","msg":"trace[1805828817] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25361; }","duration":"154.990808ms","start":"2026-06-02T22:19:55.994799Z","end":"2026-06-02T22:19:56.14979Z","steps":["trace[1805828817] 'agreement among raft nodes before linearized reading' (duration: 154.788813ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:01.526363Z","caller":"traceutil/trace.go:171","msg":"trace[1825419721] transaction","detail":"{read_only:false; response_revision:25410; number_of_response:1; }","duration":"161.8016ms","start":"2026-06-02T22:20:01.364536Z","end":"2026-06-02T22:20:01.526338Z","steps":["trace[1825419721] 'process raft request' (duration: 161.663967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:02.199977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.997452ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-02T22:20:02.200475Z","caller":"traceutil/trace.go:171","msg":"trace[2012081160] linearizableReadLoop","detail":"{readStateIndex:26231; appliedIndex:26229; }","duration":"605.853721ms","start":"2026-06-02T22:20:01.594607Z","end":"2026-06-02T22:20:02.200461Z","steps":["trace[2012081160] 'read index received' (duration: 40.403367ms)","trace[2012081160] 'applied index is now lower than readState.Index' (duration: 565.449684ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:02.200528Z","caller":"traceutil/trace.go:171","msg":"trace[1396526535] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"702.943172ms","start":"2026-06-02T22:20:01.497546Z","end":"2026-06-02T22:20:02.200489Z","steps":["trace[1396526535] 'process raft request' (duration: 702.506491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:02.201247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.497526Z","time spent":"703.487366ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:02.356211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.594506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:20:02.356306Z","caller":"traceutil/trace.go:171","msg":"trace[26459449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25411; }","duration":"761.725769ms","start":"2026-06-02T22:20:01.594557Z","end":"2026-06-02T22:20:02.356283Z","steps":["trace[26459449] 'agreement among raft nodes before linearized reading' (duration: 605.969394ms)","trace[26459449] 'range keys from in-memory index tree' (duration: 155.569531ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:02.356347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.594537Z","time spent":"761.80176ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T22:20:02.356725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.192425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:02.356813Z","caller":"traceutil/trace.go:171","msg":"trace[946958460] transaction","detail":"{read_only:false; response_revision:25412; number_of_response:1; }","duration":"302.7025ms","start":"2026-06-02T22:20:02.054096Z","end":"2026-06-02T22:20:02.356798Z","steps":["trace[946958460] 'process raft request' (duration: 146.360902ms)","trace[946958460] 'compare' (duration: 155.470667ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:02.356961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:02.05407Z","time spent":"302.767721ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:02.413321Z","caller":"traceutil/trace.go:171","msg":"trace[927306827] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"246.931766ms","start":"2026-06-02T22:20:02.166377Z","end":"2026-06-02T22:20:02.413309Z","steps":["trace[927306827] 'process raft request' (duration: 246.855065ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.431515Z","caller":"traceutil/trace.go:171","msg":"trace[1585400522] linearizableReadLoop","detail":"{readStateIndex:26235; appliedIndex:26233; }","duration":"230.950143ms","start":"2026-06-02T22:20:02.200515Z","end":"2026-06-02T22:20:02.431465Z","steps":["trace[1585400522] 'read index received' (duration: 212.896642ms)","trace[1585400522] 'applied index is now lower than readState.Index' (duration: 18.052281ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:02.431836Z","caller":"traceutil/trace.go:171","msg":"trace[2139215293] transaction","detail":"{read_only:false; response_revision:25414; number_of_response:1; }","duration":"256.541435ms","start":"2026-06-02T22:20:02.175281Z","end":"2026-06-02T22:20:02.431822Z","steps":["trace[2139215293] 'process raft request' (duration: 255.955651ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.432027Z","caller":"traceutil/trace.go:171","msg":"trace[296938490] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"245.932291ms","start":"2026-06-02T22:20:02.186085Z","end":"2026-06-02T22:20:02.432017Z","steps":["trace[296938490] 'process raft request' (duration: 245.282646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:02.432362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.354279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:20:02.432407Z","caller":"traceutil/trace.go:171","msg":"trace[1426001671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25416; }","duration":"812.424951ms","start":"2026-06-02T22:20:01.619971Z","end":"2026-06-02T22:20:02.432396Z","steps":["trace[1426001671] 'agreement among raft nodes before linearized reading' (duration: 812.281778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:02.432438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.619966Z","time spent":"812.463992ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T22:20:02.432587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.895357ms","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-06-02T22:20:02.432659Z","caller":"traceutil/trace.go:171","msg":"trace[612682496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25416; }","duration":"817.003421ms","start":"2026-06-02T22:20:01.615641Z","end":"2026-06-02T22:20:02.432645Z","steps":["trace[612682496] 'agreement among raft nodes before linearized reading' (duration: 816.780595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:02.432703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.615627Z","time spent":"817.065942ms","remote":"127.0.0.1:44402","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-06-02T22:20:02.432731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.240345ms","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-06-02T22:20:02.432811Z","caller":"traceutil/trace.go:171","msg":"trace[1916686096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25416; }","duration":"819.324027ms","start":"2026-06-02T22:20:01.613466Z","end":"2026-06-02T22:20:02.43279Z","steps":["trace[1916686096] 'agreement among raft nodes before linearized reading' (duration: 819.139802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:02.432851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:01.613411Z","time spent":"819.431879ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T22:20:02.60499Z","caller":"traceutil/trace.go:171","msg":"trace[981650573] transaction","detail":"{read_only:false; response_revision:25417; number_of_response:1; }","duration":"168.025849ms","start":"2026-06-02T22:20:02.436925Z","end":"2026-06-02T22:20:02.604951Z","steps":["trace[981650573] 'process raft request' (duration: 80.828783ms)","trace[981650573] 'compare' (duration: 86.846607ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:02.690745Z","caller":"traceutil/trace.go:171","msg":"trace[1346017148] linearizableReadLoop","detail":"{readStateIndex:26240; appliedIndex:26236; }","duration":"247.622723ms","start":"2026-06-02T22:20:02.443074Z","end":"2026-06-02T22:20:02.690697Z","steps":["trace[1346017148] 'read index received' (duration: 74.693597ms)","trace[1346017148] 'applied index is now lower than readState.Index' (duration: 172.928156ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:02.691025Z","caller":"traceutil/trace.go:171","msg":"trace[190988044] transaction","detail":"{read_only:false; response_revision:25418; number_of_response:1; }","duration":"253.973004ms","start":"2026-06-02T22:20:02.437039Z","end":"2026-06-02T22:20:02.691012Z","steps":["trace[190988044] 'process raft request' (duration: 253.415671ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.69123Z","caller":"traceutil/trace.go:171","msg":"trace[1968606131] transaction","detail":"{read_only:false; response_revision:25419; number_of_response:1; }","duration":"254.084887ms","start":"2026-06-02T22:20:02.437135Z","end":"2026-06-02T22:20:02.69122Z","steps":["trace[1968606131] 'process raft request' (duration: 253.439382ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.69132Z","caller":"traceutil/trace.go:171","msg":"trace[1834843511] transaction","detail":"{read_only:false; response_revision:25420; number_of_response:1; }","duration":"253.683438ms","start":"2026-06-02T22:20:02.437629Z","end":"2026-06-02T22:20:02.691312Z","steps":["trace[1834843511] 'process raft request' (duration: 252.983832ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.691525Z","caller":"traceutil/trace.go:171","msg":"trace[503145023] transaction","detail":"{read_only:false; response_revision:25421; number_of_response:1; }","duration":"174.220927ms","start":"2026-06-02T22:20:02.517294Z","end":"2026-06-02T22:20:02.691515Z","steps":["trace[503145023] 'process raft request' (duration: 173.354187ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:02.691734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.643096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:02.691798Z","caller":"traceutil/trace.go:171","msg":"trace[10261286] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:25421; }","duration":"248.760639ms","start":"2026-06-02T22:20:02.443022Z","end":"2026-06-02T22:20:02.691783Z","steps":["trace[10261286] 'agreement among raft nodes before linearized reading' (duration: 248.644197ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:02.912277Z","caller":"traceutil/trace.go:171","msg":"trace[1841294483] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"140.49171ms","start":"2026-06-02T22:20:02.771758Z","end":"2026-06-02T22:20:02.912249Z","steps":["trace[1841294483] 'process raft request' (duration: 87.710517ms)","trace[1841294483] 'compare' (duration: 52.65047ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:03.170161Z","caller":"traceutil/trace.go:171","msg":"trace[1053499905] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"235.411791ms","start":"2026-06-02T22:20:02.934714Z","end":"2026-06-02T22:20:03.170126Z","steps":["trace[1053499905] 'process raft request' (duration: 173.296165ms)","trace[1053499905] 'compare' (duration: 61.45416ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:04.972704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.017622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:04.972821Z","caller":"traceutil/trace.go:171","msg":"trace[1607832059] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25438; }","duration":"169.188966ms","start":"2026-06-02T22:20:04.803605Z","end":"2026-06-02T22:20:04.972794Z","steps":["trace[1607832059] 'count revisions from in-memory index tree' (duration: 168.94454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:05.426187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.298662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:05.426295Z","caller":"traceutil/trace.go:171","msg":"trace[724406219] linearizableReadLoop","detail":"{readStateIndex:26261; appliedIndex:26259; }","duration":"209.09267ms","start":"2026-06-02T22:20:05.217191Z","end":"2026-06-02T22:20:05.426283Z","steps":["trace[724406219] 'read index received' (duration: 61.569351ms)","trace[724406219] 'applied index is now lower than readState.Index' (duration: 147.522619ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:05.426384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.199692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:05.426407Z","caller":"traceutil/trace.go:171","msg":"trace[113145463] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25441; }","duration":"209.255885ms","start":"2026-06-02T22:20:05.217144Z","end":"2026-06-02T22:20:05.4264Z","steps":["trace[113145463] 'agreement among raft nodes before linearized reading' (duration: 209.167243ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:05.426604Z","caller":"traceutil/trace.go:171","msg":"trace[821345816] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"250.298756ms","start":"2026-06-02T22:20:05.176266Z","end":"2026-06-02T22:20:05.426564Z","steps":["trace[821345816] 'process raft request' (duration: 102.563233ms)","trace[821345816] 'compare' (duration: 147.224111ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:05.426654Z","caller":"traceutil/trace.go:171","msg":"trace[1661254666] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"233.450984ms","start":"2026-06-02T22:20:05.193193Z","end":"2026-06-02T22:20:05.426644Z","steps":["trace[1661254666] 'process raft request' (duration: 233.056104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.429146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.424313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:06.429262Z","caller":"traceutil/trace.go:171","msg":"trace[1030700255] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"302.680579ms","start":"2026-06-02T22:20:06.126561Z","end":"2026-06-02T22:20:06.429242Z","steps":["trace[1030700255] 'process raft request' (duration: 148.087422ms)","trace[1030700255] 'compare' (duration: 154.29163ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:06.429322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.12654Z","time spent":"302.75348ms","remote":"127.0.0.1:44184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:06.832395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.204107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:06.832514Z","caller":"traceutil/trace.go:171","msg":"trace[1815185108] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"601.120317ms","start":"2026-06-02T22:20:06.231373Z","end":"2026-06-02T22:20:06.832494Z","steps":["trace[1815185108] 'process raft request' (duration: 316.733596ms)","trace[1815185108] 'compare' (duration: 284.040573ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:06.832577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.231353Z","time spent":"601.193218ms","remote":"127.0.0.1:44402","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-06-02T22:20:06.890174Z","caller":"traceutil/trace.go:171","msg":"trace[900622341] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"463.812573ms","start":"2026-06-02T22:20:06.426342Z","end":"2026-06-02T22:20:06.890154Z","steps":["trace[900622341] 'process raft request' (duration: 463.747922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.890304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.426316Z","time spent":"463.921146ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:06.890315Z","caller":"traceutil/trace.go:171","msg":"trace[1074691124] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"517.972658ms","start":"2026-06-02T22:20:06.372215Z","end":"2026-06-02T22:20:06.890187Z","steps":["trace[1074691124] 'process raft request' (duration: 517.757103ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:06.890493Z","caller":"traceutil/trace.go:171","msg":"trace[350025361] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"447.094094ms","start":"2026-06-02T22:20:06.44339Z","end":"2026-06-02T22:20:06.890484Z","steps":["trace[350025361] 'process raft request' (duration: 446.731896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.89054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.443378Z","time spent":"447.139065ms","remote":"127.0.0.1:44402","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-06-02T22:20:06.890528Z","caller":"traceutil/trace.go:171","msg":"trace[1386170038] linearizableReadLoop","detail":"{readStateIndex:26266; appliedIndex:26263; }","duration":"459.268965ms","start":"2026-06-02T22:20:06.431239Z","end":"2026-06-02T22:20:06.890508Z","steps":["trace[1386170038] 'read index received' (duration: 117.159713ms)","trace[1386170038] 'applied index is now lower than readState.Index' (duration: 342.107992ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:06.890544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.372194Z","time spent":"518.227275ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T22:20:06.890629Z","caller":"traceutil/trace.go:171","msg":"trace[1295696242] transaction","detail":"{read_only:false; response_revision:25447; number_of_response:1; }","duration":"447.080753ms","start":"2026-06-02T22:20:06.443506Z","end":"2026-06-02T22:20:06.890587Z","steps":["trace[1295696242] 'process raft request' (duration: 446.859288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.890792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.566447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-02T22:20:06.890789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.880436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:20:06.890784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.383659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-02T22:20:06.890823Z","caller":"traceutil/trace.go:171","msg":"trace[1149434610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25447; }","duration":"269.621538ms","start":"2026-06-02T22:20:06.621195Z","end":"2026-06-02T22:20:06.890816Z","steps":["trace[1149434610] 'agreement among raft nodes before linearized reading' (duration: 269.558916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.890805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.542584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:06.890847Z","caller":"traceutil/trace.go:171","msg":"trace[591678750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25447; }","duration":"420.952137ms","start":"2026-06-02T22:20:06.469879Z","end":"2026-06-02T22:20:06.890831Z","steps":["trace[591678750] 'agreement among raft nodes before linearized reading' (duration: 420.884186ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:06.890857Z","caller":"traceutil/trace.go:171","msg":"trace[234122563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25447; }","duration":"444.47041ms","start":"2026-06-02T22:20:06.446372Z","end":"2026-06-02T22:20:06.890842Z","steps":["trace[234122563] 'agreement among raft nodes before linearized reading' (duration: 444.285106ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:06.890875Z","caller":"traceutil/trace.go:171","msg":"trace[1990977336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25447; }","duration":"276.658617ms","start":"2026-06-02T22:20:06.614202Z","end":"2026-06-02T22:20:06.890861Z","steps":["trace[1990977336] 'agreement among raft nodes before linearized reading' (duration: 276.548754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.890894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.469868Z","time spent":"421.0189ms","remote":"127.0.0.1:49742","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-06-02T22:20:06.890704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.448669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-06-02T22:20:06.890906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.446366Z","time spent":"444.527702ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T22:20:06.89093Z","caller":"traceutil/trace.go:171","msg":"trace[1658190617] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25447; }","duration":"459.724605ms","start":"2026-06-02T22:20:06.4312Z","end":"2026-06-02T22:20:06.890924Z","steps":["trace[1658190617] 'agreement among raft nodes before linearized reading' (duration: 459.376697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.890726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.072961ms","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-06-02T22:20:06.891005Z","caller":"traceutil/trace.go:171","msg":"trace[752629595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25447; }","duration":"186.370068ms","start":"2026-06-02T22:20:06.704615Z","end":"2026-06-02T22:20:06.890985Z","steps":["trace[752629595] 'agreement among raft nodes before linearized reading' (duration: 186.04444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.890958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.431184Z","time spent":"459.767436ms","remote":"127.0.0.1:44312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-06-02T22:20:06.89081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.658106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T22:20:06.891171Z","caller":"traceutil/trace.go:171","msg":"trace[107432226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25447; }","duration":"185.020155ms","start":"2026-06-02T22:20:06.70614Z","end":"2026-06-02T22:20:06.89116Z","steps":["trace[107432226] 'agreement among raft nodes before linearized reading' (duration: 184.579585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.891461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.140378ms","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-06-02T22:20:06.891541Z","caller":"traceutil/trace.go:171","msg":"trace[1836404690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25447; }","duration":"185.257441ms","start":"2026-06-02T22:20:06.706269Z","end":"2026-06-02T22:20:06.891526Z","steps":["trace[1836404690] 'agreement among raft nodes before linearized reading' (duration: 184.430221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:06.891624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:06.443501Z","time spent":"447.325359ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:07.054111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.231354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:07.05457Z","caller":"traceutil/trace.go:171","msg":"trace[743165536] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"155.847296ms","start":"2026-06-02T22:20:06.898693Z","end":"2026-06-02T22:20:07.05454Z","steps":["trace[743165536] 'process raft request' (duration: 155.602191ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:07.054698Z","caller":"traceutil/trace.go:171","msg":"trace[2131393800] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"153.774697ms","start":"2026-06-02T22:20:06.900899Z","end":"2026-06-02T22:20:07.054674Z","steps":["trace[2131393800] 'process raft request' (duration: 153.430578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:07.054793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.391639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-02T22:20:07.054824Z","caller":"traceutil/trace.go:171","msg":"trace[782485467] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"156.44161ms","start":"2026-06-02T22:20:06.898355Z","end":"2026-06-02T22:20:07.054797Z","steps":["trace[782485467] 'process raft request' (duration: 155.872847ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:07.054843Z","caller":"traceutil/trace.go:171","msg":"trace[441931808] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25452; }","duration":"156.503672ms","start":"2026-06-02T22:20:06.898329Z","end":"2026-06-02T22:20:07.054833Z","steps":["trace[441931808] 'agreement among raft nodes before linearized reading' (duration: 156.289077ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:07.054565Z","caller":"traceutil/trace.go:171","msg":"trace[881406371] linearizableReadLoop","detail":"{readStateIndex:26269; appliedIndex:26268; }","duration":"156.167124ms","start":"2026-06-02T22:20:06.898364Z","end":"2026-06-02T22:20:07.054531Z","steps":["trace[881406371] 'read index received' (duration: 43.452909ms)","trace[881406371] 'applied index is now lower than readState.Index' (duration: 112.710825ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:07.055066Z","caller":"traceutil/trace.go:171","msg":"trace[115717848] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"157.868597ms","start":"2026-06-02T22:20:06.897184Z","end":"2026-06-02T22:20:07.055052Z","steps":["trace[115717848] 'process raft request' (duration: 44.618857ms)","trace[115717848] 'compare' (duration: 111.841075ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:07.055106Z","caller":"traceutil/trace.go:171","msg":"trace[1281218809] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"154.099615ms","start":"2026-06-02T22:20:06.900986Z","end":"2026-06-02T22:20:07.055086Z","steps":["trace[1281218809] 'process raft request' (duration: 153.377667ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:07.541276Z","caller":"traceutil/trace.go:171","msg":"trace[100132462] transaction","detail":"{read_only:false; response_revision:25454; number_of_response:1; }","duration":"109.486988ms","start":"2026-06-02T22:20:07.431768Z","end":"2026-06-02T22:20:07.541255Z","steps":["trace[100132462] 'process raft request' (duration: 109.322684ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:07.550976Z","caller":"traceutil/trace.go:171","msg":"trace[1408729936] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"117.50186ms","start":"2026-06-02T22:20:07.433451Z","end":"2026-06-02T22:20:07.550953Z","steps":["trace[1408729936] 'process raft request' (duration: 117.392337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:08.778048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.816061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:08.778125Z","caller":"traceutil/trace.go:171","msg":"trace[1074157145] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25457; }","duration":"110.942604ms","start":"2026-06-02T22:20:08.667159Z","end":"2026-06-02T22:20:08.778102Z","steps":["trace[1074157145] 'count revisions from in-memory index tree' (duration: 110.696658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:08.778358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.863642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-06-02T22:20:08.778411Z","caller":"traceutil/trace.go:171","msg":"trace[1957459955] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25457; }","duration":"299.939714ms","start":"2026-06-02T22:20:08.478455Z","end":"2026-06-02T22:20:08.778394Z","steps":["trace[1957459955] 'range keys from in-memory index tree' (duration: 299.696868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:08.778451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:08.478424Z","time spent":"300.015196ms","remote":"127.0.0.1:44300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" "} {"level":"info","ts":"2026-06-02T22:20:08.985246Z","caller":"traceutil/trace.go:171","msg":"trace[1205064855] linearizableReadLoop","detail":"{readStateIndex:26279; appliedIndex:26278; }","duration":"108.124877ms","start":"2026-06-02T22:20:08.877099Z","end":"2026-06-02T22:20:08.985224Z","steps":["trace[1205064855] 'read index received' (duration: 107.968293ms)","trace[1205064855] 'applied index is now lower than readState.Index' (duration: 155.664µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:08.985391Z","caller":"traceutil/trace.go:171","msg":"trace[401694558] transaction","detail":"{read_only:false; response_revision:25458; number_of_response:1; }","duration":"146.74319ms","start":"2026-06-02T22:20:08.838619Z","end":"2026-06-02T22:20:08.985362Z","steps":["trace[401694558] 'process raft request' (duration: 146.452293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:08.985466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.357082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T22:20:08.985505Z","caller":"traceutil/trace.go:171","msg":"trace[1786993454] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25458; }","duration":"108.442934ms","start":"2026-06-02T22:20:08.877048Z","end":"2026-06-02T22:20:08.985491Z","steps":["trace[1786993454] 'agreement among raft nodes before linearized reading' (duration: 108.27524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.340633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.551724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:13.340843Z","caller":"traceutil/trace.go:171","msg":"trace[1698061781] linearizableReadLoop","detail":"{readStateIndex:26328; appliedIndex:26326; }","duration":"253.527283ms","start":"2026-06-02T22:20:13.0873Z","end":"2026-06-02T22:20:13.340828Z","steps":["trace[1698061781] 'read index received' (duration: 55.710032ms)","trace[1698061781] 'applied index is now lower than readState.Index' (duration: 197.816171ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:13.34097Z","caller":"traceutil/trace.go:171","msg":"trace[1924058467] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"256.259189ms","start":"2026-06-02T22:20:13.084683Z","end":"2026-06-02T22:20:13.340942Z","steps":["trace[1924058467] 'process raft request' (duration: 256.059614ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:13.341006Z","caller":"traceutil/trace.go:171","msg":"trace[333761657] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"256.456464ms","start":"2026-06-02T22:20:13.084529Z","end":"2026-06-02T22:20:13.340985Z","steps":["trace[333761657] 'process raft request' (duration: 58.49425ms)","trace[333761657] 'compare' (duration: 197.069533ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:13.341273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.918583ms","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-06-02T22:20:13.341358Z","caller":"traceutil/trace.go:171","msg":"trace[1861997943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25506; }","duration":"254.060357ms","start":"2026-06-02T22:20:13.087275Z","end":"2026-06-02T22:20:13.341335Z","steps":["trace[1861997943] 'agreement among raft nodes before linearized reading' (duration: 253.701137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.801565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.918415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:13.801856Z","caller":"traceutil/trace.go:171","msg":"trace[1067592380] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"455.74043ms","start":"2026-06-02T22:20:13.346096Z","end":"2026-06-02T22:20:13.801836Z","steps":["trace[1067592380] 'process raft request' (duration: 455.574297ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:13.801844Z","caller":"traceutil/trace.go:171","msg":"trace[2022388342] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"501.630398ms","start":"2026-06-02T22:20:13.30017Z","end":"2026-06-02T22:20:13.801801Z","steps":["trace[2022388342] 'process raft request' (duration: 198.396485ms)","trace[2022388342] 'compare' (duration: 302.776812ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:13.801876Z","caller":"traceutil/trace.go:171","msg":"trace[22175228] linearizableReadLoop","detail":"{readStateIndex:26329; appliedIndex:26328; }","duration":"460.949695ms","start":"2026-06-02T22:20:13.340877Z","end":"2026-06-02T22:20:13.801827Z","steps":["trace[22175228] 'read index received' (duration: 157.769194ms)","trace[22175228] 'applied index is now lower than readState.Index' (duration: 303.175091ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T22:20:13.802049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.300145Z","time spent":"501.7715ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T22:20:13.802143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.638025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:13.802197Z","caller":"traceutil/trace.go:171","msg":"trace[1292340955] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25508; }","duration":"661.773048ms","start":"2026-06-02T22:20:13.140408Z","end":"2026-06-02T22:20:13.802181Z","steps":["trace[1292340955] 'agreement among raft nodes before linearized reading' (duration: 661.633384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.802248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.140396Z","time spent":"661.841169ms","remote":"127.0.0.1:55628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:20:13.802388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.346075Z","time spent":"455.826382ms","remote":"127.0.0.1:44402","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-06-02T22:20:13.802479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.058897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-02T22:20:13.802539Z","caller":"traceutil/trace.go:171","msg":"trace[152469384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25508; }","duration":"711.151069ms","start":"2026-06-02T22:20:13.09137Z","end":"2026-06-02T22:20:13.802521Z","steps":["trace[152469384] 'agreement among raft nodes before linearized reading' (duration: 710.916363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.802577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.09136Z","time spent":"711.20853ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T22:20:13.802545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.11598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-02T22:20:13.802566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.717224ms","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-06-02T22:20:13.802641Z","caller":"traceutil/trace.go:171","msg":"trace[1202019488] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25508; }","duration":"681.356256ms","start":"2026-06-02T22:20:13.121261Z","end":"2026-06-02T22:20:13.802618Z","steps":["trace[1202019488] 'agreement among raft nodes before linearized reading' (duration: 681.065149ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:20:13.80265Z","caller":"traceutil/trace.go:171","msg":"trace[1702136841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25508; }","duration":"714.834726ms","start":"2026-06-02T22:20:13.087794Z","end":"2026-06-02T22:20:13.802629Z","steps":["trace[1702136841] 'agreement among raft nodes before linearized reading' (duration: 714.547569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.802691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.121241Z","time spent":"681.438177ms","remote":"127.0.0.1:55550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-02T22:20:13.802696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.087781Z","time spent":"714.907629ms","remote":"127.0.0.1:44402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T22:20:13.840127Z","caller":"traceutil/trace.go:171","msg":"trace[955367703] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"281.133905ms","start":"2026-06-02T22:20:13.558946Z","end":"2026-06-02T22:20:13.84008Z","steps":["trace[955367703] 'process raft request' (duration: 280.92713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.840181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.110033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-xctc7\" ","response":"range_response_count:1 size:3719"} {"level":"info","ts":"2026-06-02T22:20:13.840234Z","caller":"traceutil/trace.go:171","msg":"trace[704342637] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-xctc7; range_end:; response_count:1; response_revision:25510; }","duration":"444.184544ms","start":"2026-06-02T22:20:13.396036Z","end":"2026-06-02T22:20:13.840221Z","steps":["trace[704342637] 'agreement among raft nodes before linearized reading' (duration: 444.00964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.840272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T22:20:13.396022Z","time spent":"444.241156ms","remote":"127.0.0.1:44328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":3742,"request content":"key:\"/registry/pods/openstack/magnum-registry-c45778976-xctc7\" "} {"level":"info","ts":"2026-06-02T22:20:13.840149Z","caller":"traceutil/trace.go:171","msg":"trace[1615962864] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"255.178034ms","start":"2026-06-02T22:20:13.58494Z","end":"2026-06-02T22:20:13.840118Z","steps":["trace[1615962864] 'process raft request' (duration: 255.057511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:13.84047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.151269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:13.840641Z","caller":"traceutil/trace.go:171","msg":"trace[52358820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25510; }","duration":"226.429026ms","start":"2026-06-02T22:20:13.614172Z","end":"2026-06-02T22:20:13.840601Z","steps":["trace[52358820] 'agreement among raft nodes before linearized reading' (duration: 226.047867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:14.143823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.606456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-02T22:20:14.144024Z","caller":"traceutil/trace.go:171","msg":"trace[246554733] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"296.030881ms","start":"2026-06-02T22:20:13.847967Z","end":"2026-06-02T22:20:14.143998Z","steps":["trace[246554733] 'process raft request' (duration: 172.141118ms)","trace[246554733] 'compare' (duration: 123.444293ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:14.152506Z","caller":"traceutil/trace.go:171","msg":"trace[2003471562] linearizableReadLoop","detail":"{readStateIndex:26336; appliedIndex:26335; }","duration":"117.619543ms","start":"2026-06-02T22:20:14.034866Z","end":"2026-06-02T22:20:14.152486Z","steps":["trace[2003471562] 'read index received' (duration: 117.459079ms)","trace[2003471562] 'applied index is now lower than readState.Index' (duration: 159.674µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:20:14.152526Z","caller":"traceutil/trace.go:171","msg":"trace[376360327] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"196.140202ms","start":"2026-06-02T22:20:13.956368Z","end":"2026-06-02T22:20:14.152509Z","steps":["trace[376360327] 'process raft request' (duration: 195.991598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:14.152764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.88714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-02T22:20:14.152797Z","caller":"traceutil/trace.go:171","msg":"trace[629292016] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25514; }","duration":"117.939431ms","start":"2026-06-02T22:20:14.034849Z","end":"2026-06-02T22:20:14.152789Z","steps":["trace[629292016] 'agreement among raft nodes before linearized reading' (duration: 117.721596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:14.224889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-02T22:20:14.224971Z","caller":"traceutil/trace.go:171","msg":"trace[1902464314] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25516; }","duration":"130.549693ms","start":"2026-06-02T22:20:14.094399Z","end":"2026-06-02T22:20:14.224948Z","steps":["trace[1902464314] 'agreement among raft nodes before linearized reading' (duration: 130.196185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T22:20:14.225321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.777423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-02T22:20:14.225385Z","caller":"traceutil/trace.go:171","msg":"trace[1068687937] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25516; }","duration":"129.912027ms","start":"2026-06-02T22:20:14.095454Z","end":"2026-06-02T22:20:14.225366Z","steps":["trace[1068687937] 'agreement among raft nodes before linearized reading' (duration: 129.732183ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:02.922523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23104} {"level":"info","ts":"2026-06-02T22:21:03.054637Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23104,"took":"128.153495ms","hash":1923681385,"current-db-size-bytes":60174336,"current-db-size":"60 MB","current-db-size-in-use-bytes":35561472,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-02T22:21:03.054713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1923681385,"revision":23104,"compact-revision":20150} {"level":"info","ts":"2026-06-02T22:21:18.20798Z","caller":"traceutil/trace.go:171","msg":"trace[782073757] transaction","detail":"{read_only:false; response_revision:26171; number_of_response:1; }","duration":"140.0566ms","start":"2026-06-02T22:21:18.067892Z","end":"2026-06-02T22:21:18.207949Z","steps":["trace[782073757] 'process raft request' (duration: 63.258922ms)","trace[782073757] 'compare' (duration: 76.676735ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:21:18.213207Z","caller":"traceutil/trace.go:171","msg":"trace[306052605] transaction","detail":"{read_only:false; response_revision:26172; number_of_response:1; }","duration":"105.32299ms","start":"2026-06-02T22:21:18.107853Z","end":"2026-06-02T22:21:18.213176Z","steps":["trace[306052605] 'process raft request' (duration: 105.172416ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T22:21:18.408079Z","caller":"traceutil/trace.go:171","msg":"trace[1267805668] transaction","detail":"{read_only:false; response_revision:26176; number_of_response:1; }","duration":"141.631497ms","start":"2026-06-02T22:21:18.266408Z","end":"2026-06-02T22:21:18.40804Z","steps":["trace[1267805668] 'process raft request' (duration: 60.447245ms)","trace[1267805668] 'compare' (duration: 80.765302ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T22:21:31.017207Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a06.wal"} {"level":"info","ts":"2026-06-02T22:26:02.928478Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26058} {"level":"info","ts":"2026-06-02T22:26:03.069956Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26058,"took":"137.226955ms","hash":3173080959,"current-db-size-bytes":60174336,"current-db-size":"60 MB","current-db-size-in-use-bytes":34570240,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-02T22:26:03.070101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3173080959,"revision":26058,"compact-revision":23104} {"level":"info","ts":"2026-06-02T22:26:46.943647Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T22:26:46.949414Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-02T22:26:46.949555Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-02T22:31:02.934662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28763} {"level":"info","ts":"2026-06-02T22:31:03.074204Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28763,"took":"136.383225ms","hash":1345375897,"current-db-size-bytes":60174336,"current-db-size":"60 MB","current-db-size-in-use-bytes":30248960,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-02T22:31:03.074257Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1345375897,"revision":28763,"compact-revision":26058} {"level":"info","ts":"2026-06-02T22:36:02.940254Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30984} {"level":"info","ts":"2026-06-02T22:36:03.056009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30984,"took":"111.331885ms","hash":204387858,"current-db-size-bytes":60174336,"current-db-size":"60 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T22:36:03.056105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":204387858,"revision":30984,"compact-revision":28763} {"level":"info","ts":"2026-06-02T22:41:02.946392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33257} {"level":"info","ts":"2026-06-02T22:41:03.094262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33257,"took":"143.515969ms","hash":2947072322,"current-db-size-bytes":61214720,"current-db-size":"61 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-02T22:41:03.094341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2947072322,"revision":33257,"compact-revision":30984} {"level":"info","ts":"2026-06-02T22:46:02.95306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35549} {"level":"info","ts":"2026-06-02T22:46:03.093346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35549,"took":"135.965076ms","hash":418555219,"current-db-size-bytes":62955520,"current-db-size":"63 MB","current-db-size-in-use-bytes":31440896,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-02T22:46:03.093421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":418555219,"revision":35549,"compact-revision":33257} {"level":"info","ts":"2026-06-02T22:48:15.508745Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-02T22:48:15.513767Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-02T22:48:15.513867Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}