{"level":"warn","ts":"2026-05-06T05:03:29.304172Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T05:03:29.304264Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.139: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.19.213.139:2380","--initial-cluster=instance=https://199.19.213.139:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.139:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.139: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-05-06T05:03:29.304321Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T05:03:29.304332Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.139:2380"]} {"level":"info","ts":"2026-05-06T05:03:29.30435Z","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-05-06T05:03:29.305002Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.139:2379"]} {"level":"info","ts":"2026-05-06T05:03:29.305096Z","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.19.213.139:2380"],"listen-peer-urls":["https://199.19.213.139:2380"],"advertise-client-urls":["https://199.19.213.139:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.139:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.139: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-05-06T05:03:29.310598Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.210135ms"} {"level":"info","ts":"2026-05-06T05:03:29.320958Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb257ce137899862","cluster-id":"9913588efaa998c1"} {"level":"info","ts":"2026-05-06T05:03:29.321112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T05:03:29.321161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became follower at term 0"} {"level":"info","ts":"2026-05-06T05:03:29.321193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb257ce137899862 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T05:03:29.321216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became follower at term 1"} {"level":"info","ts":"2026-05-06T05:03:29.321287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 switched to configuration voters=(14638243470578915426)"} {"level":"warn","ts":"2026-05-06T05:03:29.335481Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T05:03:29.337578Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T05:03:29.339248Z","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-05-06T05:03:29.344456Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb257ce137899862","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T05:03:29.344655Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb257ce137899862","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T05:03:29.344771Z","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-05-06T05:03:29.344816Z","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-05-06T05:03:29.344826Z","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-05-06T05:03:29.3457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 switched to configuration voters=(14638243470578915426)"} {"level":"info","ts":"2026-05-06T05:03:29.34584Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9913588efaa998c1","local-member-id":"cb257ce137899862","added-peer-id":"cb257ce137899862","added-peer-peer-urls":["https://199.19.213.139:2380"]} {"level":"info","ts":"2026-05-06T05:03:29.348455Z","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-05-06T05:03:29.348584Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.139:2380"} {"level":"info","ts":"2026-05-06T05:03:29.348622Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.139:2380"} {"level":"info","ts":"2026-05-06T05:03:29.348741Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb257ce137899862","initial-advertise-peer-urls":["https://199.19.213.139:2380"],"listen-peer-urls":["https://199.19.213.139:2380"],"advertise-client-urls":["https://199.19.213.139:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.139:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T05:03:29.348783Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T05:03:29.422186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T05:03:29.422247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T05:03:29.422284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 received MsgPreVoteResp from cb257ce137899862 at term 1"} {"level":"info","ts":"2026-05-06T05:03:29.422302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became candidate at term 2"} {"level":"info","ts":"2026-05-06T05:03:29.422317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 received MsgVoteResp from cb257ce137899862 at term 2"} {"level":"info","ts":"2026-05-06T05:03:29.422332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became leader at term 2"} {"level":"info","ts":"2026-05-06T05:03:29.422345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb257ce137899862 elected leader cb257ce137899862 at term 2"} {"level":"info","ts":"2026-05-06T05:03:29.423433Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb257ce137899862","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.139:2379]}","request-path":"/0/members/cb257ce137899862/attributes","cluster-id":"9913588efaa998c1","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T05:03:29.423475Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T05:03:29.423444Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T05:03:29.423722Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T05:03:29.423769Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T05:03:29.423594Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:29.424883Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9913588efaa998c1","local-member-id":"cb257ce137899862","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:29.424999Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:29.425046Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:29.427195Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T05:03:29.427261Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.139:2379"} {"level":"info","ts":"2026-05-06T05:03:46.63091Z","caller":"traceutil/trace.go:171","msg":"trace[1248462139] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"285.548295ms","start":"2026-05-06T05:03:46.345318Z","end":"2026-05-06T05:03:46.630866Z","steps":["trace[1248462139] 'process raft request' (duration: 230.286117ms)","trace[1248462139] 'compare' (duration: 55.140115ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:03:46.703694Z","caller":"traceutil/trace.go:171","msg":"trace[940837396] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"355.321221ms","start":"2026-05-06T05:03:46.348323Z","end":"2026-05-06T05:03:46.703644Z","steps":["trace[940837396] 'process raft request' (duration: 355.112326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:03:46.704445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:03:46.348305Z","time spent":"355.547517ms","remote":"127.0.0.1:41278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:04:01.042955Z","caller":"traceutil/trace.go:171","msg":"trace[1227352337] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"136.401636ms","start":"2026-05-06T05:04:00.906525Z","end":"2026-05-06T05:04:01.042927Z","steps":["trace[1227352337] 'process raft request' (duration: 136.284623ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:01.043118Z","caller":"traceutil/trace.go:171","msg":"trace[572610146] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"125.748967ms","start":"2026-05-06T05:04:00.91736Z","end":"2026-05-06T05:04:01.043109Z","steps":["trace[572610146] 'process raft request' (duration: 125.644455ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:01.043121Z","caller":"traceutil/trace.go:171","msg":"trace[735235512] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"126.587277ms","start":"2026-05-06T05:04:00.916508Z","end":"2026-05-06T05:04:01.043095Z","steps":["trace[735235512] 'process raft request' (duration: 126.350171ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:01.042955Z","caller":"traceutil/trace.go:171","msg":"trace[1363547189] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"136.449577ms","start":"2026-05-06T05:04:00.906476Z","end":"2026-05-06T05:04:01.042926Z","steps":["trace[1363547189] 'process raft request' (duration: 135.760631ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:01.043254Z","caller":"traceutil/trace.go:171","msg":"trace[1529584365] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"125.882471ms","start":"2026-05-06T05:04:00.91736Z","end":"2026-05-06T05:04:01.043243Z","steps":["trace[1529584365] 'process raft request' (duration: 125.690466ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:01.043346Z","caller":"traceutil/trace.go:171","msg":"trace[555116630] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"122.97129ms","start":"2026-05-06T05:04:00.920362Z","end":"2026-05-06T05:04:01.043333Z","steps":["trace[555116630] 'process raft request' (duration: 122.729244ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:01.043312Z","caller":"traceutil/trace.go:171","msg":"trace[1454394502] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"117.982299ms","start":"2026-05-06T05:04:00.9253Z","end":"2026-05-06T05:04:01.043283Z","steps":["trace[1454394502] 'process raft request' (duration: 117.917567ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:01.043383Z","caller":"traceutil/trace.go:171","msg":"trace[460729003] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"122.936359ms","start":"2026-05-06T05:04:00.920438Z","end":"2026-05-06T05:04:01.043374Z","steps":["trace[460729003] 'process raft request' (duration: 122.688973ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:01.048274Z","caller":"traceutil/trace.go:171","msg":"trace[97302161] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"105.777263ms","start":"2026-05-06T05:04:00.942468Z","end":"2026-05-06T05:04:01.048245Z","steps":["trace[97302161] 'process raft request' (duration: 105.359933ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:01.049269Z","caller":"traceutil/trace.go:171","msg":"trace[1508357173] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"101.851858ms","start":"2026-05-06T05:04:00.947396Z","end":"2026-05-06T05:04:01.049247Z","steps":["trace[1508357173] 'process raft request' (duration: 100.733901ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:01.049573Z","caller":"traceutil/trace.go:171","msg":"trace[718990504] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"102.478473ms","start":"2026-05-06T05:04:00.946647Z","end":"2026-05-06T05:04:01.049125Z","steps":["trace[718990504] 'process raft request' (duration: 101.366627ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:07.271097Z","caller":"traceutil/trace.go:171","msg":"trace[41090668] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"151.110922ms","start":"2026-05-06T05:04:07.119969Z","end":"2026-05-06T05:04:07.27108Z","steps":["trace[41090668] 'process raft request' (duration: 150.994509ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:07.393589Z","caller":"traceutil/trace.go:171","msg":"trace[1943508790] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"130.151903ms","start":"2026-05-06T05:04:07.263341Z","end":"2026-05-06T05:04:07.393493Z","steps":["trace[1943508790] 'process raft request' (duration: 122.221471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:07.896795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.519349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:04:07.896936Z","caller":"traceutil/trace.go:171","msg":"trace[951141178] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"371.512093ms","start":"2026-05-06T05:04:07.525412Z","end":"2026-05-06T05:04:07.896924Z","steps":["trace[951141178] 'read index received' (duration: 182.758518ms)","trace[951141178] 'applied index is now lower than readState.Index' (duration: 188.752355ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:07.896998Z","caller":"traceutil/trace.go:171","msg":"trace[1525976987] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"442.755579ms","start":"2026-05-06T05:04:07.454236Z","end":"2026-05-06T05:04:07.896991Z","steps":["trace[1525976987] 'process raft request' (duration: 442.653617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:07.897037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:07.454221Z","time spent":"442.79946ms","remote":"127.0.0.1:41026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:04:07.897031Z","caller":"traceutil/trace.go:171","msg":"trace[735361455] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"447.314709ms","start":"2026-05-06T05:04:07.449679Z","end":"2026-05-06T05:04:07.896994Z","steps":["trace[735361455] 'process raft request' (duration: 258.420161ms)","trace[735361455] 'compare' (duration: 188.423207ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:04:07.897167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:07.449655Z","time spent":"447.442683ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:04:07.897173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.934619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-06T05:04:07.897188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.756768ms","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-05-06T05:04:07.897243Z","caller":"traceutil/trace.go:171","msg":"trace[444139480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"123.054702ms","start":"2026-05-06T05:04:07.774168Z","end":"2026-05-06T05:04:07.897223Z","steps":["trace[444139480] 'agreement among raft nodes before linearized reading' (duration: 122.854197ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:07.897255Z","caller":"traceutil/trace.go:171","msg":"trace[514429115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"371.865591ms","start":"2026-05-06T05:04:07.52537Z","end":"2026-05-06T05:04:07.897236Z","steps":["trace[514429115] 'agreement among raft nodes before linearized reading' (duration: 371.665396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:07.897299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:07.52535Z","time spent":"371.938172ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T05:04:23.343593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.572432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-06T05:04:23.343675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.031156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wqcj8\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-05-06T05:04:23.343692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.253118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T05:04:23.343777Z","caller":"traceutil/trace.go:171","msg":"trace[455893371] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wqcj8; range_end:; response_count:1; response_revision:745; }","duration":"143.174529ms","start":"2026-05-06T05:04:23.200579Z","end":"2026-05-06T05:04:23.343754Z","steps":["trace[455893371] 'range keys from in-memory index tree' (duration: 142.833091ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:23.3437Z","caller":"traceutil/trace.go:171","msg":"trace[195408672] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:745; }","duration":"198.730196ms","start":"2026-05-06T05:04:23.144949Z","end":"2026-05-06T05:04:23.34368Z","steps":["trace[195408672] 'range keys from in-memory index tree' (duration: 198.305125ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:23.343817Z","caller":"traceutil/trace.go:171","msg":"trace[2018345743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"161.430962ms","start":"2026-05-06T05:04:23.182357Z","end":"2026-05-06T05:04:23.343788Z","steps":["trace[2018345743] 'range keys from in-memory index tree' (duration: 160.864219ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:23.601722Z","caller":"traceutil/trace.go:171","msg":"trace[201482968] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"253.195537ms","start":"2026-05-06T05:04:23.348502Z","end":"2026-05-06T05:04:23.601698Z","steps":["trace[201482968] 'process raft request' (duration: 253.043693ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:23.610945Z","caller":"traceutil/trace.go:171","msg":"trace[702427117] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"258.532365ms","start":"2026-05-06T05:04:23.35239Z","end":"2026-05-06T05:04:23.610922Z","steps":["trace[702427117] 'process raft request' (duration: 258.468233ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:23.611096Z","caller":"traceutil/trace.go:171","msg":"trace[399187652] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"262.495261ms","start":"2026-05-06T05:04:23.34858Z","end":"2026-05-06T05:04:23.611075Z","steps":["trace[399187652] 'process raft request' (duration: 262.138333ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:34.237138Z","caller":"traceutil/trace.go:171","msg":"trace[1812669847] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"122.703691ms","start":"2026-05-06T05:05:34.114405Z","end":"2026-05-06T05:05:34.237109Z","steps":["trace[1812669847] 'process raft request' (duration: 94.393988ms)","trace[1812669847] 'compare' (duration: 28.081838ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:34.641317Z","caller":"traceutil/trace.go:171","msg":"trace[988578596] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"159.206992ms","start":"2026-05-06T05:05:34.482085Z","end":"2026-05-06T05:05:34.641292Z","steps":["trace[988578596] 'process raft request' (duration: 159.032548ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:34.650537Z","caller":"traceutil/trace.go:171","msg":"trace[1359396080] linearizableReadLoop","detail":"{readStateIndex:1869; appliedIndex:1866; }","duration":"100.945827ms","start":"2026-05-06T05:05:34.549566Z","end":"2026-05-06T05:05:34.650512Z","steps":["trace[1359396080] 'read index received' (duration: 91.578741ms)","trace[1359396080] 'applied index is now lower than readState.Index' (duration: 9.365346ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:34.650585Z","caller":"traceutil/trace.go:171","msg":"trace[1042384754] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"167.330249ms","start":"2026-05-06T05:05:34.483226Z","end":"2026-05-06T05:05:34.650556Z","steps":["trace[1042384754] 'process raft request' (duration: 167.200946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:34.650748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.170241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-74b44ae6-1fb5-4496-b653-57b751ba9e80\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-06T05:05:34.650793Z","caller":"traceutil/trace.go:171","msg":"trace[1693741656] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-74b44ae6-1fb5-4496-b653-57b751ba9e80; range_end:; response_count:1; response_revision:1800; }","duration":"101.283664ms","start":"2026-05-06T05:05:34.549497Z","end":"2026-05-06T05:05:34.65078Z","steps":["trace[1693741656] 'agreement among raft nodes before linearized reading' (duration: 101.11391ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:34.650655Z","caller":"traceutil/trace.go:171","msg":"trace[1286113662] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"167.464812ms","start":"2026-05-06T05:05:34.483137Z","end":"2026-05-06T05:05:34.650602Z","steps":["trace[1286113662] 'process raft request' (duration: 167.158194ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:19.487983Z","caller":"traceutil/trace.go:171","msg":"trace[1994599170] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"166.820589ms","start":"2026-05-06T05:06:19.321136Z","end":"2026-05-06T05:06:19.487957Z","steps":["trace[1994599170] 'process raft request' (duration: 166.660175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:19.724785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.042654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:19.724878Z","caller":"traceutil/trace.go:171","msg":"trace[1956592311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2091; }","duration":"109.181487ms","start":"2026-05-06T05:06:19.615675Z","end":"2026-05-06T05:06:19.724857Z","steps":["trace[1956592311] 'range keys from in-memory index tree' (duration: 108.954453ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:13.48909Z","caller":"traceutil/trace.go:171","msg":"trace[1399513007] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"208.996982ms","start":"2026-05-06T05:07:13.280066Z","end":"2026-05-06T05:07:13.489063Z","steps":["trace[1399513007] 'process raft request' (duration: 146.637621ms)","trace[1399513007] 'compare' (duration: 62.144626ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:47.977491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.400859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:47.977908Z","caller":"traceutil/trace.go:171","msg":"trace[606228147] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"164.873259ms","start":"2026-05-06T05:07:47.812794Z","end":"2026-05-06T05:07:47.977667Z","steps":["trace[606228147] 'process raft request' (duration: 34.177683ms)","trace[606228147] 'compare' (duration: 129.780454ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:10:13.154677Z","caller":"traceutil/trace.go:171","msg":"trace[88224378] transaction","detail":"{read_only:false; response_revision:3725; number_of_response:1; }","duration":"109.710644ms","start":"2026-05-06T05:10:13.044945Z","end":"2026-05-06T05:10:13.154656Z","steps":["trace[88224378] 'process raft request' (duration: 104.931469ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:10:13.158325Z","caller":"traceutil/trace.go:171","msg":"trace[2069479610] transaction","detail":"{read_only:false; response_revision:3726; number_of_response:1; }","duration":"110.110684ms","start":"2026-05-06T05:10:13.048193Z","end":"2026-05-06T05:10:13.158304Z","steps":["trace[2069479610] 'process raft request' (duration: 109.910899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:11:36.066363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.083984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-06T05:11:36.066422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.962618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-06T05:11:36.066465Z","caller":"traceutil/trace.go:171","msg":"trace[6065122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4811; }","duration":"160.228828ms","start":"2026-05-06T05:11:35.906217Z","end":"2026-05-06T05:11:36.066446Z","steps":["trace[6065122] 'range keys from in-memory index tree' (duration: 159.9271ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:36.066497Z","caller":"traceutil/trace.go:171","msg":"trace[888888851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4811; }","duration":"169.091051ms","start":"2026-05-06T05:11:35.897388Z","end":"2026-05-06T05:11:36.066479Z","steps":["trace[888888851] 'range keys from in-memory index tree' (duration: 168.772422ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:38.59016Z","caller":"traceutil/trace.go:171","msg":"trace[1262767961] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"227.158604ms","start":"2026-05-06T05:11:38.362965Z","end":"2026-05-06T05:11:38.590124Z","steps":["trace[1262767961] 'process raft request' (duration: 226.926039ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:38.600232Z","caller":"traceutil/trace.go:171","msg":"trace[1592842257] linearizableReadLoop","detail":"{readStateIndex:5123; appliedIndex:5121; }","duration":"114.502239ms","start":"2026-05-06T05:11:38.485697Z","end":"2026-05-06T05:11:38.600199Z","steps":["trace[1592842257] 'read index received' (duration: 104.12167ms)","trace[1592842257] 'applied index is now lower than readState.Index' (duration: 10.378929ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:11:38.601081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.35972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:720056"} {"level":"info","ts":"2026-05-06T05:11:38.601167Z","caller":"traceutil/trace.go:171","msg":"trace[1206515843] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4934; }","duration":"115.480693ms","start":"2026-05-06T05:11:38.485664Z","end":"2026-05-06T05:11:38.601144Z","steps":["trace[1206515843] 'agreement among raft nodes before linearized reading' (duration: 114.666374ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:40.755017Z","caller":"traceutil/trace.go:171","msg":"trace[1881869501] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"126.391264ms","start":"2026-05-06T05:11:40.628606Z","end":"2026-05-06T05:11:40.754997Z","steps":["trace[1881869501] 'process raft request' (duration: 126.268392ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:40.818918Z","caller":"traceutil/trace.go:171","msg":"trace[476519560] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"190.235108ms","start":"2026-05-06T05:11:40.62866Z","end":"2026-05-06T05:11:40.818895Z","steps":["trace[476519560] 'process raft request' (duration: 190.030443ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:49.666671Z","caller":"traceutil/trace.go:171","msg":"trace[1908844641] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"208.556578ms","start":"2026-05-06T05:11:49.458085Z","end":"2026-05-06T05:11:49.666642Z","steps":["trace[1908844641] 'process raft request' (duration: 207.902062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:11:57.058129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.002689ms","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-05-06T05:11:57.058228Z","caller":"traceutil/trace.go:171","msg":"trace[761519534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5323; }","duration":"229.166963ms","start":"2026-05-06T05:11:56.829041Z","end":"2026-05-06T05:11:57.058208Z","steps":["trace[761519534] 'range keys from in-memory index tree' (duration: 228.767684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:11:57.058362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.779396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:11:57.058569Z","caller":"traceutil/trace.go:171","msg":"trace[263561648] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"183.548917ms","start":"2026-05-06T05:11:56.874971Z","end":"2026-05-06T05:11:57.05852Z","steps":["trace[263561648] 'process raft request' (duration: 38.488314ms)","trace[263561648] 'compare' (duration: 144.615183ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:11:57.058658Z","caller":"traceutil/trace.go:171","msg":"trace[1607843939] linearizableReadLoop","detail":"{readStateIndex:5523; appliedIndex:5522; }","duration":"169.8849ms","start":"2026-05-06T05:11:56.888735Z","end":"2026-05-06T05:11:57.05862Z","steps":["trace[1607843939] 'read index received' (duration: 24.711673ms)","trace[1607843939] 'applied index is now lower than readState.Index' (duration: 145.168226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:11:57.05887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.901611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-06T05:11:57.058884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.145876ms","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-05-06T05:11:57.05891Z","caller":"traceutil/trace.go:171","msg":"trace[1185065763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5325; }","duration":"142.969463ms","start":"2026-05-06T05:11:56.915929Z","end":"2026-05-06T05:11:57.058898Z","steps":["trace[1185065763] 'agreement among raft nodes before linearized reading' (duration: 142.83584ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:57.058953Z","caller":"traceutil/trace.go:171","msg":"trace[1288604744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5325; }","duration":"170.247058ms","start":"2026-05-06T05:11:56.888684Z","end":"2026-05-06T05:11:57.058931Z","steps":["trace[1288604744] 'agreement among raft nodes before linearized reading' (duration: 170.063934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:11:57.401672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.129243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:11:57.401847Z","caller":"traceutil/trace.go:171","msg":"trace[1597164865] linearizableReadLoop","detail":"{readStateIndex:5526; appliedIndex:5524; }","duration":"337.32141ms","start":"2026-05-06T05:11:57.064508Z","end":"2026-05-06T05:11:57.40183Z","steps":["trace[1597164865] 'read index received' (duration: 20.613325ms)","trace[1597164865] 'applied index is now lower than readState.Index' (duration: 316.706375ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:11:57.401944Z","caller":"traceutil/trace.go:171","msg":"trace[1729791863] transaction","detail":"{read_only:false; response_revision:5328; number_of_response:1; }","duration":"336.685496ms","start":"2026-05-06T05:11:57.065249Z","end":"2026-05-06T05:11:57.401935Z","steps":["trace[1729791863] 'process raft request' (duration: 336.524152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:11:57.402009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:11:57.065241Z","time spent":"336.736747ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:11:57.402025Z","caller":"traceutil/trace.go:171","msg":"trace[1605913526] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"337.902264ms","start":"2026-05-06T05:11:57.06409Z","end":"2026-05-06T05:11:57.401992Z","steps":["trace[1605913526] 'process raft request' (duration: 215.366441ms)","trace[1605913526] 'compare' (duration: 121.99832ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:11:57.4022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:11:57.064079Z","time spent":"338.040697ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:11:57.40231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.789331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-05-06T05:11:57.402362Z","caller":"traceutil/trace.go:171","msg":"trace[66403494] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:5328; }","duration":"337.876464ms","start":"2026-05-06T05:11:57.064471Z","end":"2026-05-06T05:11:57.402347Z","steps":["trace[66403494] 'agreement among raft nodes before linearized reading' (duration: 337.692539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:11:57.402397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:11:57.064453Z","time spent":"337.937085ms","remote":"127.0.0.1:40934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1657,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-05-06T05:11:57.402409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.169327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sx7vp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-06T05:11:57.402483Z","caller":"traceutil/trace.go:171","msg":"trace[1173489161] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sx7vp; range_end:; response_count:1; response_revision:5328; }","duration":"275.261929ms","start":"2026-05-06T05:11:57.127202Z","end":"2026-05-06T05:11:57.402464Z","steps":["trace[1173489161] 'agreement among raft nodes before linearized reading' (duration: 275.003853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:11:58.730089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.364482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-05-06T05:11:58.730191Z","caller":"traceutil/trace.go:171","msg":"trace[834329206] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5343; }","duration":"108.559297ms","start":"2026-05-06T05:11:58.621608Z","end":"2026-05-06T05:11:58.730167Z","steps":["trace[834329206] 'range keys from in-memory index tree' (duration: 108.161188ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:07.215337Z","caller":"traceutil/trace.go:171","msg":"trace[958811482] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"102.815309ms","start":"2026-05-06T05:12:07.112497Z","end":"2026-05-06T05:12:07.215313Z","steps":["trace[958811482] 'process raft request' (duration: 81.575078ms)","trace[958811482] 'compare' (duration: 21.028296ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:13:29.928816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-05-06T05:13:30.005506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"75.067333ms","hash":4080105085,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":34381824,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-06T05:13:30.005578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4080105085,"revision":2991,"compact-revision":-1} {"level":"info","ts":"2026-05-06T05:17:52.538703Z","caller":"traceutil/trace.go:171","msg":"trace[46679487] transaction","detail":"{read_only:false; response_revision:8105; number_of_response:1; }","duration":"152.330134ms","start":"2026-05-06T05:17:52.386345Z","end":"2026-05-06T05:17:52.538675Z","steps":["trace[46679487] 'process raft request' (duration: 150.031889ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:01.264106Z","caller":"traceutil/trace.go:171","msg":"trace[1943627120] transaction","detail":"{read_only:false; response_revision:8147; number_of_response:1; }","duration":"241.655228ms","start":"2026-05-06T05:18:01.022421Z","end":"2026-05-06T05:18:01.264076Z","steps":["trace[1943627120] 'process raft request' (duration: 241.475333ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:01.342612Z","caller":"traceutil/trace.go:171","msg":"trace[144995042] linearizableReadLoop","detail":"{readStateIndex:8433; appliedIndex:8431; }","duration":"202.898837ms","start":"2026-05-06T05:18:01.13969Z","end":"2026-05-06T05:18:01.342589Z","steps":["trace[144995042] 'read index received' (duration: 124.288678ms)","trace[144995042] 'applied index is now lower than readState.Index' (duration: 78.609059ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:18:01.342724Z","caller":"traceutil/trace.go:171","msg":"trace[1212432902] transaction","detail":"{read_only:false; response_revision:8148; number_of_response:1; }","duration":"318.771791ms","start":"2026-05-06T05:18:01.023913Z","end":"2026-05-06T05:18:01.342685Z","steps":["trace[1212432902] 'process raft request' (duration: 295.472731ms)","trace[1212432902] 'compare' (duration: 23.034464ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:18:01.342771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.06408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:18:01.342805Z","caller":"traceutil/trace.go:171","msg":"trace[1927495382] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8148; }","duration":"203.157623ms","start":"2026-05-06T05:18:01.139638Z","end":"2026-05-06T05:18:01.342795Z","steps":["trace[1927495382] 'agreement among raft nodes before linearized reading' (duration: 203.077601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:18:01.342894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:18:01.023895Z","time spent":"318.912384ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:18:02.287782Z","caller":"traceutil/trace.go:171","msg":"trace[525284510] transaction","detail":"{read_only:false; response_revision:8156; number_of_response:1; }","duration":"274.565518ms","start":"2026-05-06T05:18:02.013185Z","end":"2026-05-06T05:18:02.28775Z","steps":["trace[525284510] 'process raft request' (duration: 221.330569ms)","trace[525284510] 'compare' (duration: 52.813669ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:18:29.605956Z","caller":"traceutil/trace.go:171","msg":"trace[1292060754] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"133.166081ms","start":"2026-05-06T05:18:29.472762Z","end":"2026-05-06T05:18:29.605928Z","steps":["trace[1292060754] 'process raft request' (duration: 132.918195ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:29.934636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6161} {"level":"info","ts":"2026-05-06T05:18:30.033506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6161,"took":"96.949999ms","hash":3064017946,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":23244800,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-06T05:18:30.033577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3064017946,"revision":6161,"compact-revision":2991} {"level":"info","ts":"2026-05-06T05:18:41.359662Z","caller":"traceutil/trace.go:171","msg":"trace[1575706610] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"185.122628ms","start":"2026-05-06T05:18:41.174517Z","end":"2026-05-06T05:18:41.35964Z","steps":["trace[1575706610] 'process raft request' (duration: 169.33988ms)","trace[1575706610] 'compare' (duration: 15.45699ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:18:41.869117Z","caller":"traceutil/trace.go:171","msg":"trace[1270415687] linearizableReadLoop","detail":"{readStateIndex:8760; appliedIndex:8759; }","duration":"103.258101ms","start":"2026-05-06T05:18:41.765833Z","end":"2026-05-06T05:18:41.869091Z","steps":["trace[1270415687] 'read index received' (duration: 91.96768ms)","trace[1270415687] 'applied index is now lower than readState.Index' (duration: 11.288291ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:18:41.86926Z","caller":"traceutil/trace.go:171","msg":"trace[874197950] transaction","detail":"{read_only:false; response_revision:8460; number_of_response:1; }","duration":"103.484447ms","start":"2026-05-06T05:18:41.765765Z","end":"2026-05-06T05:18:41.869249Z","steps":["trace[874197950] 'process raft request' (duration: 92.024941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:18:41.87002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.161863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-h8w4k\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-05-06T05:18:41.870144Z","caller":"traceutil/trace.go:171","msg":"trace[1856136171] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-h8w4k; range_end:; response_count:1; response_revision:8460; }","duration":"104.315507ms","start":"2026-05-06T05:18:41.765808Z","end":"2026-05-06T05:18:41.870124Z","steps":["trace[1856136171] 'agreement among raft nodes before linearized reading' (duration: 104.100681ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:42.062136Z","caller":"traceutil/trace.go:171","msg":"trace[1634379281] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"176.910681ms","start":"2026-05-06T05:18:41.885169Z","end":"2026-05-06T05:18:42.06208Z","steps":["trace[1634379281] 'process raft request' (duration: 146.179662ms)","trace[1634379281] 'compare' (duration: 30.370451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:18:42.683545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.207004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:18:42.683632Z","caller":"traceutil/trace.go:171","msg":"trace[516373503] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:8467; }","duration":"109.325987ms","start":"2026-05-06T05:18:42.574275Z","end":"2026-05-06T05:18:42.683601Z","steps":["trace[516373503] 'count revisions from in-memory index tree' (duration: 109.062751ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:19:30.667171Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002434.wal"} {"level":"info","ts":"2026-05-06T05:19:52.606434Z","caller":"traceutil/trace.go:171","msg":"trace[1475712676] transaction","detail":"{read_only:false; response_revision:9118; number_of_response:1; }","duration":"163.778905ms","start":"2026-05-06T05:19:52.442633Z","end":"2026-05-06T05:19:52.606412Z","steps":["trace[1475712676] 'process raft request' (duration: 163.277143ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:20:56.095623Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cb257ce137899862","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T05:20:56.10141Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-06T05:20:56.101519Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-06T05:23:29.940929Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8355} {"level":"info","ts":"2026-05-06T05:23:30.031688Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8355,"took":"88.126699ms","hash":2480584209,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":26984448,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-06T05:23:30.031778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2480584209,"revision":8355,"compact-revision":6161} {"level":"info","ts":"2026-05-06T05:23:31.885286Z","caller":"traceutil/trace.go:171","msg":"trace[1825741266] transaction","detail":"{read_only:false; response_revision:11045; number_of_response:1; }","duration":"119.43255ms","start":"2026-05-06T05:23:31.765781Z","end":"2026-05-06T05:23:31.885214Z","steps":["trace[1825741266] 'process raft request' (duration: 109.882711ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:28:29.947466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11034} {"level":"info","ts":"2026-05-06T05:28:30.028565Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11034,"took":"78.548158ms","hash":2678310534,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":27914240,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-06T05:28:30.028653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2678310534,"revision":11034,"compact-revision":8355} {"level":"info","ts":"2026-05-06T05:30:00.128873Z","caller":"traceutil/trace.go:171","msg":"trace[1655845641] transaction","detail":"{read_only:false; response_revision:13637; number_of_response:1; }","duration":"126.836966ms","start":"2026-05-06T05:30:00.002012Z","end":"2026-05-06T05:30:00.128849Z","steps":["trace[1655845641] 'process raft request' (duration: 126.683853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:30:08.446248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.076925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:30:08.446339Z","caller":"traceutil/trace.go:171","msg":"trace[356928742] transaction","detail":"{read_only:false; response_revision:13696; number_of_response:1; }","duration":"206.735426ms","start":"2026-05-06T05:30:08.239589Z","end":"2026-05-06T05:30:08.446324Z","steps":["trace[356928742] 'process raft request' (duration: 19.509788ms)","trace[356928742] 'compare' (duration: 186.953882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:30:08.684194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.175533ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-06T05:30:08.684321Z","caller":"traceutil/trace.go:171","msg":"trace[1800189135] linearizableReadLoop","detail":"{readStateIndex:14166; appliedIndex:14165; }","duration":"414.630752ms","start":"2026-05-06T05:30:08.269672Z","end":"2026-05-06T05:30:08.684302Z","steps":["trace[1800189135] 'read index received' (duration: 186.310866ms)","trace[1800189135] 'applied index is now lower than readState.Index' (duration: 228.317426ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:30:08.68454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.023382ms","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:5"} {"level":"info","ts":"2026-05-06T05:30:08.684644Z","caller":"traceutil/trace.go:171","msg":"trace[480298878] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:13696; }","duration":"209.184866ms","start":"2026-05-06T05:30:08.475431Z","end":"2026-05-06T05:30:08.684615Z","steps":["trace[480298878] 'agreement among raft nodes before linearized reading' (duration: 209.011142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:30:08.684935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.882948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-5dxdj\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-05-06T05:30:08.684979Z","caller":"traceutil/trace.go:171","msg":"trace[2102550862] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-5dxdj; range_end:; response_count:1; response_revision:13696; }","duration":"415.331408ms","start":"2026-05-06T05:30:08.269635Z","end":"2026-05-06T05:30:08.684967Z","steps":["trace[2102550862] 'agreement among raft nodes before linearized reading' (duration: 414.763354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:30:08.685011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:30:08.269619Z","time spent":"415.3835ms","remote":"127.0.0.1:40966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12388,"request content":"key:\"/registry/pods/openstack/openvswitch-5dxdj\" "} {"level":"info","ts":"2026-05-06T05:30:29.725665Z","caller":"traceutil/trace.go:171","msg":"trace[1211560648] transaction","detail":"{read_only:false; response_revision:13999; number_of_response:1; }","duration":"149.83388ms","start":"2026-05-06T05:30:29.575799Z","end":"2026-05-06T05:30:29.725633Z","steps":["trace[1211560648] 'process raft request' (duration: 137.072433ms)","trace[1211560648] 'compare' (duration: 12.643794ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:30:30.010484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.965027ms","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-05-06T05:30:30.010586Z","caller":"traceutil/trace.go:171","msg":"trace[477637012] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13999; }","duration":"161.131221ms","start":"2026-05-06T05:30:29.849429Z","end":"2026-05-06T05:30:30.010561Z","steps":["trace[477637012] 'range keys from in-memory index tree' (duration: 160.768413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:31:55.927744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.36487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-06T05:31:55.927838Z","caller":"traceutil/trace.go:171","msg":"trace[132317608] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14809; }","duration":"127.509182ms","start":"2026-05-06T05:31:55.800309Z","end":"2026-05-06T05:31:55.927818Z","steps":["trace[132317608] 'range keys from in-memory index tree' (duration: 127.234736ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:33:29.953938Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13066} {"level":"info","ts":"2026-05-06T05:33:30.033247Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13066,"took":"75.313111ms","hash":2755096483,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":30531584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-06T05:33:30.033324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2755096483,"revision":13066,"compact-revision":11034} {"level":"info","ts":"2026-05-06T05:37:08.55687Z","caller":"traceutil/trace.go:171","msg":"trace[1866582811] transaction","detail":"{read_only:false; response_revision:17197; number_of_response:1; }","duration":"158.242517ms","start":"2026-05-06T05:37:08.398595Z","end":"2026-05-06T05:37:08.556838Z","steps":["trace[1866582811] 'process raft request' (duration: 82.880824ms)","trace[1866582811] 'compare' (duration: 74.911081ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:37:14.103138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.553695ms","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-05-06T05:37:14.103196Z","caller":"traceutil/trace.go:171","msg":"trace[457916257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17233; }","duration":"109.688718ms","start":"2026-05-06T05:37:13.993495Z","end":"2026-05-06T05:37:14.103183Z","steps":["trace[457916257] 'range keys from in-memory index tree' (duration: 109.33601ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:37:14.268796Z","caller":"traceutil/trace.go:171","msg":"trace[274704451] transaction","detail":"{read_only:false; response_revision:17234; number_of_response:1; }","duration":"161.839963ms","start":"2026-05-06T05:37:14.106937Z","end":"2026-05-06T05:37:14.268777Z","steps":["trace[274704451] 'process raft request' (duration: 161.72146ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:37:14.268941Z","caller":"traceutil/trace.go:171","msg":"trace[418026898] linearizableReadLoop","detail":"{readStateIndex:17819; appliedIndex:17819; }","duration":"161.451124ms","start":"2026-05-06T05:37:14.10746Z","end":"2026-05-06T05:37:14.268911Z","steps":["trace[418026898] 'read index received' (duration: 161.441934ms)","trace[418026898] 'applied index is now lower than readState.Index' (duration: 7µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:37:14.269126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.660439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18622"} {"level":"info","ts":"2026-05-06T05:37:14.269159Z","caller":"traceutil/trace.go:171","msg":"trace[1011601519] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:17234; }","duration":"161.72456ms","start":"2026-05-06T05:37:14.107426Z","end":"2026-05-06T05:37:14.26915Z","steps":["trace[1011601519] 'agreement among raft nodes before linearized reading' (duration: 161.611417ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:38:29.960601Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15426} {"level":"info","ts":"2026-05-06T05:38:30.053186Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15426,"took":"89.369359ms","hash":3992882483,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":33841152,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-06T05:38:30.053261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3992882483,"revision":15426,"compact-revision":13066} {"level":"info","ts":"2026-05-06T05:41:16.393342Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cb257ce137899862","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T05:41:16.399193Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-06T05:41:16.399372Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-06T05:42:34.169066Z","caller":"traceutil/trace.go:171","msg":"trace[567888529] transaction","detail":"{read_only:false; response_revision:19784; number_of_response:1; }","duration":"251.17691ms","start":"2026-05-06T05:42:33.917851Z","end":"2026-05-06T05:42:34.169028Z","steps":["trace[567888529] 'process raft request' (duration: 250.947364ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:42:34.768109Z","caller":"traceutil/trace.go:171","msg":"trace[838263051] transaction","detail":"{read_only:false; response_revision:19785; number_of_response:1; }","duration":"147.523366ms","start":"2026-05-06T05:42:34.620562Z","end":"2026-05-06T05:42:34.768085Z","steps":["trace[838263051] 'process raft request' (duration: 147.346102ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:42:35.080055Z","caller":"traceutil/trace.go:171","msg":"trace[566412904] transaction","detail":"{read_only:false; response_revision:19788; number_of_response:1; }","duration":"144.759981ms","start":"2026-05-06T05:42:34.93525Z","end":"2026-05-06T05:42:35.08001Z","steps":["trace[566412904] 'process raft request' (duration: 144.692089ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:42:35.080102Z","caller":"traceutil/trace.go:171","msg":"trace[580133555] transaction","detail":"{read_only:false; response_revision:19787; number_of_response:1; }","duration":"145.56077ms","start":"2026-05-06T05:42:34.934491Z","end":"2026-05-06T05:42:35.080052Z","steps":["trace[580133555] 'process raft request' (duration: 128.004497ms)","trace[580133555] 'compare' (duration: 17.261416ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:42:35.630732Z","caller":"traceutil/trace.go:171","msg":"trace[2096665934] linearizableReadLoop","detail":"{readStateIndex:20457; appliedIndex:20455; }","duration":"301.606622ms","start":"2026-05-06T05:42:35.329098Z","end":"2026-05-06T05:42:35.630704Z","steps":["trace[2096665934] 'read index received' (duration: 46.090178ms)","trace[2096665934] 'applied index is now lower than readState.Index' (duration: 255.514854ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:42:35.630794Z","caller":"traceutil/trace.go:171","msg":"trace[1321270181] transaction","detail":"{read_only:false; response_revision:19790; number_of_response:1; }","duration":"306.718336ms","start":"2026-05-06T05:42:35.32406Z","end":"2026-05-06T05:42:35.630779Z","steps":["trace[1321270181] 'process raft request' (duration: 306.442659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:42:35.631038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.91621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:42:35.631075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:42:35.324032Z","time spent":"306.859249ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:42:35.631295Z","caller":"traceutil/trace.go:171","msg":"trace[1128221005] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:19790; }","duration":"302.156735ms","start":"2026-05-06T05:42:35.329069Z","end":"2026-05-06T05:42:35.631225Z","steps":["trace[1128221005] 'agreement among raft nodes before linearized reading' (duration: 301.797887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:42:35.631553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:42:35.329061Z","time spent":"302.453503ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:42:35.944014Z","caller":"traceutil/trace.go:171","msg":"trace[2044134874] transaction","detail":"{read_only:false; response_revision:19792; number_of_response:1; }","duration":"105.643511ms","start":"2026-05-06T05:42:35.838342Z","end":"2026-05-06T05:42:35.943986Z","steps":["trace[2044134874] 'process raft request' (duration: 52.716448ms)","trace[2044134874] 'compare' (duration: 52.751289ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:43:29.966872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18070} {"level":"info","ts":"2026-05-06T05:43:30.081564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18070,"took":"111.08009ms","hash":3919513563,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":30318592,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-06T05:43:30.081687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3919513563,"revision":18070,"compact-revision":15426} {"level":"info","ts":"2026-05-06T05:46:08.996237Z","caller":"traceutil/trace.go:171","msg":"trace[1411453766] transaction","detail":"{read_only:false; response_revision:22103; number_of_response:1; }","duration":"300.6527ms","start":"2026-05-06T05:46:08.695555Z","end":"2026-05-06T05:46:08.996208Z","steps":["trace[1411453766] 'process raft request' (duration: 218.900474ms)","trace[1411453766] 'compare' (duration: 81.283695ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:46:08.996398Z","caller":"traceutil/trace.go:171","msg":"trace[1544340493] transaction","detail":"{read_only:false; response_revision:22104; number_of_response:1; }","duration":"190.105141ms","start":"2026-05-06T05:46:08.806239Z","end":"2026-05-06T05:46:08.996344Z","steps":["trace[1544340493] 'process raft request' (duration: 189.863935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:46:08.996545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:46:08.695527Z","time spent":"300.802564ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:29.973054Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20335} {"level":"info","ts":"2026-05-06T05:48:30.079053Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20335,"took":"102.099246ms","hash":3754010371,"current-db-size-bytes":58941440,"current-db-size":"59 MB","current-db-size-in-use-bytes":42565632,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-06T05:48:30.079119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3754010371,"revision":20335,"compact-revision":18070} {"level":"info","ts":"2026-05-06T05:49:15.491596Z","caller":"traceutil/trace.go:171","msg":"trace[2105885502] transaction","detail":"{read_only:false; response_revision:24030; number_of_response:1; }","duration":"136.967794ms","start":"2026-05-06T05:49:15.354601Z","end":"2026-05-06T05:49:15.491569Z","steps":["trace[2105885502] 'process raft request' (duration: 136.824281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:15.799094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.978861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:15.799192Z","caller":"traceutil/trace.go:171","msg":"trace[773583914] linearizableReadLoop","detail":"{readStateIndex:24822; appliedIndex:24821; }","duration":"182.890468ms","start":"2026-05-06T05:49:15.616283Z","end":"2026-05-06T05:49:15.799174Z","steps":["trace[773583914] 'read index received' (duration: 53.795614ms)","trace[773583914] 'applied index is now lower than readState.Index' (duration: 129.093454ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:15.799259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.9846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:15.799286Z","caller":"traceutil/trace.go:171","msg":"trace[674926438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24033; }","duration":"183.051942ms","start":"2026-05-06T05:49:15.616223Z","end":"2026-05-06T05:49:15.799275Z","steps":["trace[674926438] 'agreement among raft nodes before linearized reading' (duration: 183.00185ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:15.79942Z","caller":"traceutil/trace.go:171","msg":"trace[1633216567] transaction","detail":"{read_only:false; response_revision:24033; number_of_response:1; }","duration":"266.873297ms","start":"2026-05-06T05:49:15.532498Z","end":"2026-05-06T05:49:15.799372Z","steps":["trace[1633216567] 'process raft request' (duration: 137.557847ms)","trace[1633216567] 'compare' (duration: 128.820718ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:15.800147Z","caller":"traceutil/trace.go:171","msg":"trace[633663317] transaction","detail":"{read_only:false; response_revision:24034; number_of_response:1; }","duration":"132.809725ms","start":"2026-05-06T05:49:15.667309Z","end":"2026-05-06T05:49:15.800119Z","steps":["trace[633663317] 'process raft request' (duration: 132.673301ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:16.22627Z","caller":"traceutil/trace.go:171","msg":"trace[676387144] transaction","detail":"{read_only:false; response_revision:24036; number_of_response:1; }","duration":"359.064254ms","start":"2026-05-06T05:49:15.867181Z","end":"2026-05-06T05:49:16.226245Z","steps":["trace[676387144] 'process raft request' (duration: 358.923151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:16.226409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:15.867158Z","time spent":"359.177877ms","remote":"127.0.0.1:57374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2561,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:16.22844Z","caller":"traceutil/trace.go:171","msg":"trace[1739224893] linearizableReadLoop","detail":"{readStateIndex:24826; appliedIndex:24824; }","duration":"336.976874ms","start":"2026-05-06T05:49:15.891442Z","end":"2026-05-06T05:49:16.228419Z","steps":["trace[1739224893] 'read index received' (duration: 334.715709ms)","trace[1739224893] 'applied index is now lower than readState.Index' (duration: 2.259985ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:16.228487Z","caller":"traceutil/trace.go:171","msg":"trace[1659325431] transaction","detail":"{read_only:false; response_revision:24037; number_of_response:1; }","duration":"352.274441ms","start":"2026-05-06T05:49:15.876194Z","end":"2026-05-06T05:49:16.228469Z","steps":["trace[1659325431] 'process raft request' (duration: 352.081676ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:16.228527Z","caller":"traceutil/trace.go:171","msg":"trace[1267221242] transaction","detail":"{read_only:false; response_revision:24038; number_of_response:1; }","duration":"138.694686ms","start":"2026-05-06T05:49:16.089805Z","end":"2026-05-06T05:49:16.2285Z","steps":["trace[1267221242] 'process raft request' (duration: 138.580553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:16.228593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:15.876133Z","time spent":"352.387674ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:16.228705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.24862ms","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-05-06T05:49:16.228739Z","caller":"traceutil/trace.go:171","msg":"trace[901486818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24038; }","duration":"337.323762ms","start":"2026-05-06T05:49:15.891407Z","end":"2026-05-06T05:49:16.228731Z","steps":["trace[901486818] 'agreement among raft nodes before linearized reading' (duration: 337.119357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:16.228764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:15.891393Z","time spent":"337.365693ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T05:49:16.228851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.06268ms","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-05-06T05:49:16.228931Z","caller":"traceutil/trace.go:171","msg":"trace[2035441930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24038; }","duration":"227.189303ms","start":"2026-05-06T05:49:16.001724Z","end":"2026-05-06T05:49:16.228913Z","steps":["trace[2035441930] 'agreement among raft nodes before linearized reading' (duration: 226.961778ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:16.394711Z","caller":"traceutil/trace.go:171","msg":"trace[1681788258] transaction","detail":"{read_only:false; response_revision:24039; number_of_response:1; }","duration":"163.117502ms","start":"2026-05-06T05:49:16.23157Z","end":"2026-05-06T05:49:16.394688Z","steps":["trace[1681788258] 'process raft request' (duration: 162.976559ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:16.407307Z","caller":"traceutil/trace.go:171","msg":"trace[858855346] transaction","detail":"{read_only:false; response_revision:24040; number_of_response:1; }","duration":"175.430278ms","start":"2026-05-06T05:49:16.231853Z","end":"2026-05-06T05:49:16.407284Z","steps":["trace[858855346] 'process raft request' (duration: 174.781143ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:16.407603Z","caller":"traceutil/trace.go:171","msg":"trace[1474307347] transaction","detail":"{read_only:false; response_revision:24041; number_of_response:1; }","duration":"175.712975ms","start":"2026-05-06T05:49:16.231879Z","end":"2026-05-06T05:49:16.407592Z","steps":["trace[1474307347] 'process raft request' (duration: 174.869644ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:21.17767Z","caller":"traceutil/trace.go:171","msg":"trace[173121678] linearizableReadLoop","detail":"{readStateIndex:24869; appliedIndex:24868; }","duration":"105.925086ms","start":"2026-05-06T05:49:21.071718Z","end":"2026-05-06T05:49:21.177644Z","steps":["trace[173121678] 'read index received' (duration: 105.67463ms)","trace[173121678] 'applied index is now lower than readState.Index' (duration: 249.296µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:21.17787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.137741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:21.177951Z","caller":"traceutil/trace.go:171","msg":"trace[953450472] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:24079; }","duration":"106.271495ms","start":"2026-05-06T05:49:21.071665Z","end":"2026-05-06T05:49:21.177937Z","steps":["trace[953450472] 'agreement among raft nodes before linearized reading' (duration: 106.115701ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:21.17825Z","caller":"traceutil/trace.go:171","msg":"trace[2107016519] transaction","detail":"{read_only:false; response_revision:24079; number_of_response:1; }","duration":"139.643838ms","start":"2026-05-06T05:49:21.038571Z","end":"2026-05-06T05:49:21.178215Z","steps":["trace[2107016519] 'process raft request' (duration: 138.93553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:21.441004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.655167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:49:21.44109Z","caller":"traceutil/trace.go:171","msg":"trace[1724282097] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24080; }","duration":"144.788801ms","start":"2026-05-06T05:49:21.296281Z","end":"2026-05-06T05:49:21.44107Z","steps":["trace[1724282097] 'count revisions from in-memory index tree' (duration: 144.566335ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:21.579712Z","caller":"traceutil/trace.go:171","msg":"trace[2092721620] transaction","detail":"{read_only:false; response_revision:24081; number_of_response:1; }","duration":"133.067861ms","start":"2026-05-06T05:49:21.446623Z","end":"2026-05-06T05:49:21.579691Z","steps":["trace[2092721620] 'process raft request' (duration: 132.855066ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:22.054268Z","caller":"traceutil/trace.go:171","msg":"trace[1281080321] transaction","detail":"{read_only:false; response_revision:24084; number_of_response:1; }","duration":"238.654299ms","start":"2026-05-06T05:49:21.815587Z","end":"2026-05-06T05:49:22.054242Z","steps":["trace[1281080321] 'process raft request' (duration: 238.519726ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:22.06259Z","caller":"traceutil/trace.go:171","msg":"trace[1406148318] transaction","detail":"{read_only:false; response_revision:24085; number_of_response:1; }","duration":"235.462852ms","start":"2026-05-06T05:49:21.827111Z","end":"2026-05-06T05:49:22.062574Z","steps":["trace[1406148318] 'process raft request' (duration: 235.37828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:22.560196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.714442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:22.560309Z","caller":"traceutil/trace.go:171","msg":"trace[1551618657] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24087; }","duration":"152.885526ms","start":"2026-05-06T05:49:22.407396Z","end":"2026-05-06T05:49:22.560281Z","steps":["trace[1551618657] 'count revisions from in-memory index tree' (duration: 152.641491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:22.560635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.728146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:22.560705Z","caller":"traceutil/trace.go:171","msg":"trace[490694363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24087; }","duration":"205.846779ms","start":"2026-05-06T05:49:22.354842Z","end":"2026-05-06T05:49:22.560689Z","steps":["trace[490694363] 'range keys from in-memory index tree' (duration: 205.636505ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:22.560908Z","caller":"traceutil/trace.go:171","msg":"trace[1989228619] transaction","detail":"{read_only:false; response_revision:24088; number_of_response:1; }","duration":"136.072052ms","start":"2026-05-06T05:49:22.424819Z","end":"2026-05-06T05:49:22.560891Z","steps":["trace[1989228619] 'process raft request' (duration: 135.961889ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:22.56127Z","caller":"traceutil/trace.go:171","msg":"trace[801588200] linearizableReadLoop","detail":"{readStateIndex:24878; appliedIndex:24878; }","duration":"132.337662ms","start":"2026-05-06T05:49:22.428924Z","end":"2026-05-06T05:49:22.561262Z","steps":["trace[801588200] 'read index received' (duration: 132.333272ms)","trace[801588200] 'applied index is now lower than readState.Index' (duration: 3.72µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:22.561395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.458134ms","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-05-06T05:49:22.561485Z","caller":"traceutil/trace.go:171","msg":"trace[215487054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24088; }","duration":"132.524956ms","start":"2026-05-06T05:49:22.42889Z","end":"2026-05-06T05:49:22.561415Z","steps":["trace[215487054] 'agreement among raft nodes before linearized reading' (duration: 132.423134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:22.915469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.204539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:22.915688Z","caller":"traceutil/trace.go:171","msg":"trace[335619149] transaction","detail":"{read_only:false; response_revision:24089; number_of_response:1; }","duration":"392.429536ms","start":"2026-05-06T05:49:22.523223Z","end":"2026-05-06T05:49:22.915653Z","steps":["trace[335619149] 'process raft request' (duration: 212.959081ms)","trace[335619149] 'compare' (duration: 178.996284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:22.915887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:22.523204Z","time spent":"392.56413ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:22.915911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.515235ms","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-05-06T05:49:22.91595Z","caller":"traceutil/trace.go:171","msg":"trace[743811906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24090; }","duration":"485.585277ms","start":"2026-05-06T05:49:22.430356Z","end":"2026-05-06T05:49:22.915941Z","steps":["trace[743811906] 'agreement among raft nodes before linearized reading' (duration: 485.388032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:22.915982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:22.430349Z","time spent":"485.627289ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T05:49:22.915995Z","caller":"traceutil/trace.go:171","msg":"trace[1246038224] transaction","detail":"{read_only:false; response_revision:24090; number_of_response:1; }","duration":"349.71788ms","start":"2026-05-06T05:49:22.566256Z","end":"2026-05-06T05:49:22.915973Z","steps":["trace[1246038224] 'process raft request' (duration: 349.32083ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:22.915692Z","caller":"traceutil/trace.go:171","msg":"trace[413315593] linearizableReadLoop","detail":"{readStateIndex:24879; appliedIndex:24878; }","duration":"354.368212ms","start":"2026-05-06T05:49:22.561289Z","end":"2026-05-06T05:49:22.915657Z","steps":["trace[413315593] 'read index received' (duration: 174.905857ms)","trace[413315593] 'applied index is now lower than readState.Index' (duration: 179.457055ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:22.916083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:22.566229Z","time spent":"349.810871ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:22.916274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.423001ms","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-05-06T05:49:22.916373Z","caller":"traceutil/trace.go:171","msg":"trace[1127223602] 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:24090; }","duration":"230.578564ms","start":"2026-05-06T05:49:22.685773Z","end":"2026-05-06T05:49:22.916352Z","steps":["trace[1127223602] 'agreement among raft nodes before linearized reading' (duration: 230.375999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:22.916295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.495664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:22.916505Z","caller":"traceutil/trace.go:171","msg":"trace[423344107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24090; }","duration":"302.707179ms","start":"2026-05-06T05:49:22.613767Z","end":"2026-05-06T05:49:22.916474Z","steps":["trace[423344107] 'agreement among raft nodes before linearized reading' (duration: 302.33841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:22.916615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:22.613749Z","time spent":"302.832532ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T05:49:23.443563Z","caller":"traceutil/trace.go:171","msg":"trace[92982656] linearizableReadLoop","detail":"{readStateIndex:24885; appliedIndex:24884; }","duration":"167.144558ms","start":"2026-05-06T05:49:23.276394Z","end":"2026-05-06T05:49:23.443539Z","steps":["trace[92982656] 'read index received' (duration: 114.357079ms)","trace[92982656] 'applied index is now lower than readState.Index' (duration: 52.786549ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:23.443592Z","caller":"traceutil/trace.go:171","msg":"trace[1068365486] transaction","detail":"{read_only:false; response_revision:24094; number_of_response:1; }","duration":"228.257788ms","start":"2026-05-06T05:49:23.215312Z","end":"2026-05-06T05:49:23.44357Z","steps":["trace[1068365486] 'process raft request' (duration: 175.428888ms)","trace[1068365486] 'compare' (duration: 52.675847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:23.443912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.503377ms","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-05-06T05:49:23.444003Z","caller":"traceutil/trace.go:171","msg":"trace[1328018079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24094; }","duration":"167.637891ms","start":"2026-05-06T05:49:23.276343Z","end":"2026-05-06T05:49:23.44398Z","steps":["trace[1328018079] 'agreement among raft nodes before linearized reading' (duration: 167.306123ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:23.731789Z","caller":"traceutil/trace.go:171","msg":"trace[1649335806] linearizableReadLoop","detail":"{readStateIndex:24886; appliedIndex:24885; }","duration":"282.423982ms","start":"2026-05-06T05:49:23.449345Z","end":"2026-05-06T05:49:23.731769Z","steps":["trace[1649335806] 'read index received' (duration: 282.130005ms)","trace[1649335806] 'applied index is now lower than readState.Index' (duration: 292.487µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:23.7319Z","caller":"traceutil/trace.go:171","msg":"trace[640917759] transaction","detail":"{read_only:false; response_revision:24095; number_of_response:1; }","duration":"283.857535ms","start":"2026-05-06T05:49:23.448018Z","end":"2026-05-06T05:49:23.731876Z","steps":["trace[640917759] 'process raft request' (duration: 283.459686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:23.731981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.619296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-06T05:49:23.732018Z","caller":"traceutil/trace.go:171","msg":"trace[1769245625] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:24095; }","duration":"282.692478ms","start":"2026-05-06T05:49:23.449315Z","end":"2026-05-06T05:49:23.732008Z","steps":["trace[1769245625] 'agreement among raft nodes before linearized reading' (duration: 282.561144ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:23.750334Z","caller":"traceutil/trace.go:171","msg":"trace[804325351] transaction","detail":"{read_only:false; response_revision:24096; number_of_response:1; }","duration":"139.31194ms","start":"2026-05-06T05:49:23.610997Z","end":"2026-05-06T05:49:23.750309Z","steps":["trace[804325351] 'process raft request' (duration: 139.010833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:23.750548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.644527ms","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-05-06T05:49:23.750568Z","caller":"traceutil/trace.go:171","msg":"trace[1774975899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24096; }","duration":"166.715059ms","start":"2026-05-06T05:49:23.583848Z","end":"2026-05-06T05:49:23.750563Z","steps":["trace[1774975899] 'agreement among raft nodes before linearized reading' (duration: 166.611756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:23.750561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.567716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-05-06T05:49:23.750571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.216562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:23.7507Z","caller":"traceutil/trace.go:171","msg":"trace[108467064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24096; }","duration":"135.373776ms","start":"2026-05-06T05:49:23.615284Z","end":"2026-05-06T05:49:23.750658Z","steps":["trace[108467064] 'agreement among raft nodes before linearized reading' (duration: 135.167591ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:23.750622Z","caller":"traceutil/trace.go:171","msg":"trace[1587474732] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24096; }","duration":"124.672048ms","start":"2026-05-06T05:49:23.625935Z","end":"2026-05-06T05:49:23.750607Z","steps":["trace[1587474732] 'agreement among raft nodes before linearized reading' (duration: 124.467673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:23.75068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.935028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:23.750886Z","caller":"traceutil/trace.go:171","msg":"trace[2089732592] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:24096; }","duration":"284.171894ms","start":"2026-05-06T05:49:23.466693Z","end":"2026-05-06T05:49:23.750865Z","steps":["trace[2089732592] 'agreement among raft nodes before linearized reading' (duration: 283.915288ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:24.032909Z","caller":"traceutil/trace.go:171","msg":"trace[902322184] linearizableReadLoop","detail":"{readStateIndex:24889; appliedIndex:24888; }","duration":"201.015084ms","start":"2026-05-06T05:49:23.831866Z","end":"2026-05-06T05:49:24.032881Z","steps":["trace[902322184] 'read index received' (duration: 150.617872ms)","trace[902322184] 'applied index is now lower than readState.Index' (duration: 50.395532ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:24.032999Z","caller":"traceutil/trace.go:171","msg":"trace[2018543489] transaction","detail":"{read_only:false; response_revision:24098; number_of_response:1; }","duration":"276.376316ms","start":"2026-05-06T05:49:23.756589Z","end":"2026-05-06T05:49:24.032965Z","steps":["trace[2018543489] 'process raft request' (duration: 225.958404ms)","trace[2018543489] 'compare' (duration: 49.982451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:24.033136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.250429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18368"} {"level":"info","ts":"2026-05-06T05:49:24.033239Z","caller":"traceutil/trace.go:171","msg":"trace[1385636624] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24098; }","duration":"201.368972ms","start":"2026-05-06T05:49:23.831832Z","end":"2026-05-06T05:49:24.033201Z","steps":["trace[1385636624] 'agreement among raft nodes before linearized reading' (duration: 201.130107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:24.333146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.236415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:24.33342Z","caller":"traceutil/trace.go:171","msg":"trace[214911021] linearizableReadLoop","detail":"{readStateIndex:24891; appliedIndex:24889; }","duration":"175.403898ms","start":"2026-05-06T05:49:24.157998Z","end":"2026-05-06T05:49:24.333402Z","steps":["trace[214911021] 'read index received' (duration: 26.887217ms)","trace[214911021] 'applied index is now lower than readState.Index' (duration: 148.515421ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:24.333494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.49212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:24.333518Z","caller":"traceutil/trace.go:171","msg":"trace[1868175769] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24100; }","duration":"175.516921ms","start":"2026-05-06T05:49:24.157991Z","end":"2026-05-06T05:49:24.333508Z","steps":["trace[1868175769] 'agreement among raft nodes before linearized reading' (duration: 175.46457ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:24.333519Z","caller":"traceutil/trace.go:171","msg":"trace[322809553] transaction","detail":"{read_only:false; response_revision:24099; number_of_response:1; }","duration":"274.620473ms","start":"2026-05-06T05:49:24.058869Z","end":"2026-05-06T05:49:24.33349Z","steps":["trace[322809553] 'process raft request' (duration: 125.950048ms)","trace[322809553] 'compare' (duration: 147.983239ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:24.333681Z","caller":"traceutil/trace.go:171","msg":"trace[168362657] transaction","detail":"{read_only:false; response_revision:24100; number_of_response:1; }","duration":"267.651076ms","start":"2026-05-06T05:49:24.066004Z","end":"2026-05-06T05:49:24.333655Z","steps":["trace[168362657] 'process raft request' (duration: 267.244017ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:24.416931Z","caller":"traceutil/trace.go:171","msg":"trace[1024203649] transaction","detail":"{read_only:false; response_revision:24101; number_of_response:1; }","duration":"158.536793ms","start":"2026-05-06T05:49:24.258377Z","end":"2026-05-06T05:49:24.416914Z","steps":["trace[1024203649] 'process raft request' (duration: 158.404239ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:24.417435Z","caller":"traceutil/trace.go:171","msg":"trace[930818304] transaction","detail":"{read_only:false; response_revision:24102; number_of_response:1; }","duration":"157.895497ms","start":"2026-05-06T05:49:24.259522Z","end":"2026-05-06T05:49:24.417418Z","steps":["trace[930818304] 'process raft request' (duration: 157.358855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:24.665527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.657534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-06T05:49:24.665605Z","caller":"traceutil/trace.go:171","msg":"trace[1531386378] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24102; }","duration":"232.758696ms","start":"2026-05-06T05:49:24.432828Z","end":"2026-05-06T05:49:24.665587Z","steps":["trace[1531386378] 'range keys from in-memory index tree' (duration: 232.522801ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:24.667423Z","caller":"traceutil/trace.go:171","msg":"trace[1070508] transaction","detail":"{read_only:false; response_revision:24103; number_of_response:1; }","duration":"101.423068ms","start":"2026-05-06T05:49:24.565967Z","end":"2026-05-06T05:49:24.66739Z","steps":["trace[1070508] 'process raft request' (duration: 101.271315ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:24.752951Z","caller":"traceutil/trace.go:171","msg":"trace[821199037] linearizableReadLoop","detail":"{readStateIndex:24895; appliedIndex:24893; }","duration":"137.297072ms","start":"2026-05-06T05:49:24.61563Z","end":"2026-05-06T05:49:24.752927Z","steps":["trace[821199037] 'read index received' (duration: 51.708193ms)","trace[821199037] 'applied index is now lower than readState.Index' (duration: 85.587489ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:24.752987Z","caller":"traceutil/trace.go:171","msg":"trace[1945160746] transaction","detail":"{read_only:false; response_revision:24104; number_of_response:1; }","duration":"170.875739ms","start":"2026-05-06T05:49:24.582085Z","end":"2026-05-06T05:49:24.752961Z","steps":["trace[1945160746] 'process raft request' (duration: 170.716516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:24.753131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.493316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:24.753186Z","caller":"traceutil/trace.go:171","msg":"trace[956836789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24104; }","duration":"137.584648ms","start":"2026-05-06T05:49:24.61558Z","end":"2026-05-06T05:49:24.753165Z","steps":["trace[956836789] 'agreement among raft nodes before linearized reading' (duration: 137.479496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:25.192395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.056161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-06T05:49:25.192488Z","caller":"traceutil/trace.go:171","msg":"trace[56531639] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:24107; }","duration":"116.160763ms","start":"2026-05-06T05:49:25.076307Z","end":"2026-05-06T05:49:25.192468Z","steps":["trace[56531639] 'range keys from in-memory index tree' (duration: 115.906107ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:25.371718Z","caller":"traceutil/trace.go:171","msg":"trace[2042105635] transaction","detail":"{read_only:false; response_revision:24108; number_of_response:1; }","duration":"108.944469ms","start":"2026-05-06T05:49:25.262748Z","end":"2026-05-06T05:49:25.371693Z","steps":["trace[2042105635] 'process raft request' (duration: 108.776615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:25.534904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.863518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:25.534983Z","caller":"traceutil/trace.go:171","msg":"trace[1960473589] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24108; }","duration":"113.997522ms","start":"2026-05-06T05:49:25.420967Z","end":"2026-05-06T05:49:25.534965Z","steps":["trace[1960473589] 'count revisions from in-memory index tree' (duration: 113.759516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:25.974453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.861004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:25.974579Z","caller":"traceutil/trace.go:171","msg":"trace[650250071] linearizableReadLoop","detail":"{readStateIndex:24901; appliedIndex:24900; }","duration":"139.195018ms","start":"2026-05-06T05:49:25.835367Z","end":"2026-05-06T05:49:25.974562Z","steps":["trace[650250071] 'read index received' (duration: 21.18159ms)","trace[650250071] 'applied index is now lower than readState.Index' (duration: 118.012048ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:25.974603Z","caller":"traceutil/trace.go:171","msg":"trace[1863591170] transaction","detail":"{read_only:false; response_revision:24110; number_of_response:1; }","duration":"210.877971ms","start":"2026-05-06T05:49:25.763693Z","end":"2026-05-06T05:49:25.974571Z","steps":["trace[1863591170] 'process raft request' (duration: 92.818522ms)","trace[1863591170] 'compare' (duration: 117.732421ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:25.974712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.350901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-06T05:49:25.974752Z","caller":"traceutil/trace.go:171","msg":"trace[1606584541] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24110; }","duration":"139.424463ms","start":"2026-05-06T05:49:25.835315Z","end":"2026-05-06T05:49:25.974739Z","steps":["trace[1606584541] 'agreement among raft nodes before linearized reading' (duration: 139.29814ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:26.318391Z","caller":"traceutil/trace.go:171","msg":"trace[1837328052] transaction","detail":"{read_only:false; response_revision:24112; number_of_response:1; }","duration":"278.555397ms","start":"2026-05-06T05:49:26.039814Z","end":"2026-05-06T05:49:26.31837Z","steps":["trace[1837328052] 'process raft request' (duration: 278.418374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:26.719423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.461116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:26.719581Z","caller":"traceutil/trace.go:171","msg":"trace[1272675029] linearizableReadLoop","detail":"{readStateIndex:24907; appliedIndex:24905; }","duration":"218.625468ms","start":"2026-05-06T05:49:26.500943Z","end":"2026-05-06T05:49:26.719569Z","steps":["trace[1272675029] 'read index received' (duration: 34.032339ms)","trace[1272675029] 'applied index is now lower than readState.Index' (duration: 184.591989ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:26.719636Z","caller":"traceutil/trace.go:171","msg":"trace[4836329] transaction","detail":"{read_only:false; response_revision:24116; number_of_response:1; }","duration":"296.484049ms","start":"2026-05-06T05:49:26.423145Z","end":"2026-05-06T05:49:26.719629Z","steps":["trace[4836329] 'process raft request' (duration: 296.365496ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:26.719721Z","caller":"traceutil/trace.go:171","msg":"trace[1554427036] transaction","detail":"{read_only:false; response_revision:24115; number_of_response:1; }","duration":"296.858588ms","start":"2026-05-06T05:49:26.422762Z","end":"2026-05-06T05:49:26.719621Z","steps":["trace[1554427036] 'process raft request' (duration: 112.135766ms)","trace[1554427036] 'compare' (duration: 184.24605ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:26.71975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.782511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:49:26.71983Z","caller":"traceutil/trace.go:171","msg":"trace[1633598612] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24116; }","duration":"218.884424ms","start":"2026-05-06T05:49:26.500928Z","end":"2026-05-06T05:49:26.719812Z","steps":["trace[1633598612] 'agreement among raft nodes before linearized reading' (duration: 218.756741ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:26.84078Z","caller":"traceutil/trace.go:171","msg":"trace[105891128] linearizableReadLoop","detail":"{readStateIndex:24908; appliedIndex:24907; }","duration":"121.150032ms","start":"2026-05-06T05:49:26.719603Z","end":"2026-05-06T05:49:26.840753Z","steps":["trace[105891128] 'read index received' (duration: 120.575399ms)","trace[105891128] 'applied index is now lower than readState.Index' (duration: 572.523µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:26.840881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.1126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:26.840847Z","caller":"traceutil/trace.go:171","msg":"trace[741503731] transaction","detail":"{read_only:false; response_revision:24117; number_of_response:1; }","duration":"167.824686ms","start":"2026-05-06T05:49:26.672981Z","end":"2026-05-06T05:49:26.840806Z","steps":["trace[741503731] 'process raft request' (duration: 167.271573ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:26.840955Z","caller":"traceutil/trace.go:171","msg":"trace[572311789] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:24117; }","duration":"150.244963ms","start":"2026-05-06T05:49:26.690692Z","end":"2026-05-06T05:49:26.840937Z","steps":["trace[572311789] 'agreement among raft nodes before linearized reading' (duration: 150.104399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:26.841054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.827696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:26.841219Z","caller":"traceutil/trace.go:171","msg":"trace[1190361310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24117; }","duration":"225.024631ms","start":"2026-05-06T05:49:26.616135Z","end":"2026-05-06T05:49:26.841159Z","steps":["trace[1190361310] 'agreement among raft nodes before linearized reading' (duration: 224.830056ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:34.580957Z","caller":"traceutil/trace.go:171","msg":"trace[956334117] linearizableReadLoop","detail":"{readStateIndex:25018; appliedIndex:25017; }","duration":"237.720447ms","start":"2026-05-06T05:49:34.34322Z","end":"2026-05-06T05:49:34.580941Z","steps":["trace[956334117] 'read index received' (duration: 237.634035ms)","trace[956334117] 'applied index is now lower than readState.Index' (duration: 85.422µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:34.58106Z","caller":"traceutil/trace.go:171","msg":"trace[1492079582] transaction","detail":"{read_only:false; response_revision:24224; number_of_response:1; }","duration":"332.046425ms","start":"2026-05-06T05:49:34.248991Z","end":"2026-05-06T05:49:34.581038Z","steps":["trace[1492079582] 'process raft request' (duration: 331.82606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:34.581131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.84658ms","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":"warn","ts":"2026-05-06T05:49:34.581179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:34.24897Z","time spent":"332.139177ms","remote":"127.0.0.1:40846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":729,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:34.581193Z","caller":"traceutil/trace.go:171","msg":"trace[618487809] 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:24224; }","duration":"238.008693ms","start":"2026-05-06T05:49:34.343144Z","end":"2026-05-06T05:49:34.581152Z","steps":["trace[618487809] 'agreement among raft nodes before linearized reading' (duration: 237.89194ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:34.880526Z","caller":"traceutil/trace.go:171","msg":"trace[1117558509] transaction","detail":"{read_only:false; response_revision:24225; number_of_response:1; }","duration":"444.14678ms","start":"2026-05-06T05:49:34.436354Z","end":"2026-05-06T05:49:34.8805Z","steps":["trace[1117558509] 'process raft request' (duration: 443.841753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:34.880675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:34.436327Z","time spent":"444.271124ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:34.880684Z","caller":"traceutil/trace.go:171","msg":"trace[205213588] linearizableReadLoop","detail":"{readStateIndex:25020; appliedIndex:25018; }","duration":"299.634045ms","start":"2026-05-06T05:49:34.581016Z","end":"2026-05-06T05:49:34.88065Z","steps":["trace[205213588] 'read index received' (duration: 299.41328ms)","trace[205213588] 'applied index is now lower than readState.Index' (duration: 214.125µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:34.88078Z","caller":"traceutil/trace.go:171","msg":"trace[459207704] transaction","detail":"{read_only:false; response_revision:24226; number_of_response:1; }","duration":"442.174223ms","start":"2026-05-06T05:49:34.438577Z","end":"2026-05-06T05:49:34.880751Z","steps":["trace[459207704] 'process raft request' (duration: 441.872616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:34.880857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.182467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:49:34.880905Z","caller":"traceutil/trace.go:171","msg":"trace[1582472015] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24226; }","duration":"349.260399ms","start":"2026-05-06T05:49:34.53163Z","end":"2026-05-06T05:49:34.880891Z","steps":["trace[1582472015] 'agreement among raft nodes before linearized reading' (duration: 349.161956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:34.880927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:34.438561Z","time spent":"442.279595ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:34.880942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:34.531615Z","time spent":"349.31853ms","remote":"127.0.0.1:53878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":32,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:49:34.881542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.35969ms","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-05-06T05:49:34.88161Z","caller":"traceutil/trace.go:171","msg":"trace[824174956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24226; }","duration":"527.438092ms","start":"2026-05-06T05:49:34.354155Z","end":"2026-05-06T05:49:34.881593Z","steps":["trace[824174956] 'agreement among raft nodes before linearized reading' (duration: 527.225667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:34.88164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:34.354083Z","time spent":"527.551495ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T05:49:34.88255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.99296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2212892"} {"level":"info","ts":"2026-05-06T05:49:34.882615Z","caller":"traceutil/trace.go:171","msg":"trace[669832887] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:24226; }","duration":"504.061741ms","start":"2026-05-06T05:49:34.378537Z","end":"2026-05-06T05:49:34.882599Z","steps":["trace[669832887] 'agreement among raft nodes before linearized reading' (duration: 502.278088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:34.882699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:34.378471Z","time spent":"504.215665ms","remote":"127.0.0.1:40878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2212916,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-05-06T05:49:34.891245Z","caller":"traceutil/trace.go:171","msg":"trace[965061873] transaction","detail":"{read_only:false; response_revision:24227; number_of_response:1; }","duration":"144.129337ms","start":"2026-05-06T05:49:34.747093Z","end":"2026-05-06T05:49:34.891222Z","steps":["trace[965061873] 'process raft request' (duration: 143.910701ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:34.891247Z","caller":"traceutil/trace.go:171","msg":"trace[1775917125] transaction","detail":"{read_only:false; response_revision:24228; number_of_response:1; }","duration":"127.719672ms","start":"2026-05-06T05:49:34.763511Z","end":"2026-05-06T05:49:34.891231Z","steps":["trace[1775917125] 'process raft request' (duration: 127.64232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:34.891447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.963736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-06T05:49:34.891488Z","caller":"traceutil/trace.go:171","msg":"trace[1440369084] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24229; }","duration":"118.058039ms","start":"2026-05-06T05:49:34.773419Z","end":"2026-05-06T05:49:34.891477Z","steps":["trace[1440369084] 'agreement among raft nodes before linearized reading' (duration: 117.892095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.256922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.8605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:49:35.256965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.724205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8c8e1359-8383-43e9-99a2-5e5daef08b91\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-06T05:49:35.256999Z","caller":"traceutil/trace.go:171","msg":"trace[1691678898] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:24230; }","duration":"126.977373ms","start":"2026-05-06T05:49:35.130001Z","end":"2026-05-06T05:49:35.256979Z","steps":["trace[1691678898] 'count revisions from in-memory index tree' (duration: 126.733378ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:35.257031Z","caller":"traceutil/trace.go:171","msg":"trace[996568478] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8c8e1359-8383-43e9-99a2-5e5daef08b91; range_end:; response_count:1; response_revision:24230; }","duration":"264.828398ms","start":"2026-05-06T05:49:34.992186Z","end":"2026-05-06T05:49:35.257014Z","steps":["trace[996568478] 'range keys from in-memory index tree' (duration: 264.579122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.257114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.056829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-06T05:49:35.257114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.089099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18368"} {"level":"info","ts":"2026-05-06T05:49:35.257174Z","caller":"traceutil/trace.go:171","msg":"trace[1221923157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24230; }","duration":"249.162712ms","start":"2026-05-06T05:49:35.007995Z","end":"2026-05-06T05:49:35.257158Z","steps":["trace[1221923157] 'range keys from in-memory index tree' (duration: 248.912646ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:35.257181Z","caller":"traceutil/trace.go:171","msg":"trace[1880300923] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24230; }","duration":"276.208052ms","start":"2026-05-06T05:49:34.980957Z","end":"2026-05-06T05:49:35.257165Z","steps":["trace[1880300923] 'range keys from in-memory index tree' (duration: 275.892764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.257185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.117986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-05-06T05:49:35.257298Z","caller":"traceutil/trace.go:171","msg":"trace[1451242157] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:24230; }","duration":"253.27465ms","start":"2026-05-06T05:49:35.003996Z","end":"2026-05-06T05:49:35.257271Z","steps":["trace[1451242157] 'range keys from in-memory index tree' (duration: 252.86872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.257351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.608452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-06T05:49:35.257378Z","caller":"traceutil/trace.go:171","msg":"trace[2037051847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24230; }","duration":"175.671844ms","start":"2026-05-06T05:49:35.081699Z","end":"2026-05-06T05:49:35.257371Z","steps":["trace[2037051847] 'range keys from in-memory index tree' (duration: 175.451699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.257498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.644291ms","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":"warn","ts":"2026-05-06T05:49:35.25754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.414695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-06T05:49:35.25759Z","caller":"traceutil/trace.go:171","msg":"trace[648841737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24230; }","duration":"167.489157ms","start":"2026-05-06T05:49:35.090094Z","end":"2026-05-06T05:49:35.257583Z","steps":["trace[648841737] 'range keys from in-memory index tree' (duration: 167.331514ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:35.257637Z","caller":"traceutil/trace.go:171","msg":"trace[779173159] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24230; }","duration":"142.792574ms","start":"2026-05-06T05:49:35.114778Z","end":"2026-05-06T05:49:35.257571Z","steps":["trace[779173159] 'range keys from in-memory index tree' (duration: 142.216749ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:35.365933Z","caller":"traceutil/trace.go:171","msg":"trace[1126143405] transaction","detail":"{read_only:false; response_revision:24231; number_of_response:1; }","duration":"203.980356ms","start":"2026-05-06T05:49:35.161928Z","end":"2026-05-06T05:49:35.365908Z","steps":["trace[1126143405] 'process raft request' (duration: 203.795092ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:35.366348Z","caller":"traceutil/trace.go:171","msg":"trace[978748360] linearizableReadLoop","detail":"{readStateIndex:25025; appliedIndex:25025; }","duration":"104.352689ms","start":"2026-05-06T05:49:35.26198Z","end":"2026-05-06T05:49:35.366333Z","steps":["trace[978748360] 'read index received' (duration: 104.345989ms)","trace[978748360] 'applied index is now lower than readState.Index' (duration: 5.71µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:35.366644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.636986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-035fecca-98ae-4e1a-9d0b-6a6f48182998\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-05-06T05:49:35.36673Z","caller":"traceutil/trace.go:171","msg":"trace[1090275604] range","detail":"{range_begin:/registry/persistentvolumes/pvc-035fecca-98ae-4e1a-9d0b-6a6f48182998; range_end:; response_count:1; response_revision:24231; }","duration":"104.763269ms","start":"2026-05-06T05:49:35.261945Z","end":"2026-05-06T05:49:35.366708Z","steps":["trace[1090275604] 'agreement among raft nodes before linearized reading' (duration: 104.471062ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:35.378162Z","caller":"traceutil/trace.go:171","msg":"trace[451353697] transaction","detail":"{read_only:false; response_revision:24232; number_of_response:1; }","duration":"115.887077ms","start":"2026-05-06T05:49:35.262256Z","end":"2026-05-06T05:49:35.378144Z","steps":["trace[451353697] 'process raft request' (duration: 115.709093ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:35.378439Z","caller":"traceutil/trace.go:171","msg":"trace[1321469060] transaction","detail":"{read_only:false; response_revision:24233; number_of_response:1; }","duration":"115.969109ms","start":"2026-05-06T05:49:35.26245Z","end":"2026-05-06T05:49:35.378419Z","steps":["trace[1321469060] 'process raft request' (duration: 115.654231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.378763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.57713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-05-06T05:49:35.378999Z","caller":"traceutil/trace.go:171","msg":"trace[1782230629] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:24234; }","duration":"115.677792ms","start":"2026-05-06T05:49:35.263122Z","end":"2026-05-06T05:49:35.3788Z","steps":["trace[1782230629] 'agreement among raft nodes before linearized reading' (duration: 115.473307ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:35.379081Z","caller":"traceutil/trace.go:171","msg":"trace[1889078296] transaction","detail":"{read_only:false; response_revision:24234; number_of_response:1; }","duration":"116.472641ms","start":"2026-05-06T05:49:35.262566Z","end":"2026-05-06T05:49:35.379039Z","steps":["trace[1889078296] 'process raft request' (duration: 115.779585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.907754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.543321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:35.907909Z","caller":"traceutil/trace.go:171","msg":"trace[2046081991] transaction","detail":"{read_only:false; response_revision:24235; number_of_response:1; }","duration":"530.816325ms","start":"2026-05-06T05:49:35.377054Z","end":"2026-05-06T05:49:35.907871Z","steps":["trace[2046081991] 'process raft request' (duration: 340.083698ms)","trace[2046081991] 'compare' (duration: 190.315736ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:35.90823Z","caller":"traceutil/trace.go:171","msg":"trace[1445267935] linearizableReadLoop","detail":"{readStateIndex:25029; appliedIndex:25028; }","duration":"526.238545ms","start":"2026-05-06T05:49:35.38196Z","end":"2026-05-06T05:49:35.908199Z","steps":["trace[1445267935] 'read index received' (duration: 335.362746ms)","trace[1445267935] 'applied index is now lower than readState.Index' (duration: 190.872929ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:35.908534Z","caller":"traceutil/trace.go:171","msg":"trace[916039117] transaction","detail":"{read_only:false; response_revision:24236; number_of_response:1; }","duration":"507.445153ms","start":"2026-05-06T05:49:35.401047Z","end":"2026-05-06T05:49:35.908492Z","steps":["trace[916039117] 'process raft request' (duration: 507.109185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.908596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.848076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:49:35.908607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:35.377037Z","time spent":"530.949818ms","remote":"127.0.0.1:57336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:49:35.908637Z","caller":"traceutil/trace.go:171","msg":"trace[605738848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24236; }","duration":"293.944128ms","start":"2026-05-06T05:49:35.614682Z","end":"2026-05-06T05:49:35.908626Z","steps":["trace[605738848] 'agreement among raft nodes before linearized reading' (duration: 293.852096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.908635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.55736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-06T05:49:35.908664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:35.401028Z","time spent":"507.573375ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:35.908714Z","caller":"traceutil/trace.go:171","msg":"trace[1932419734] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24236; }","duration":"135.707234ms","start":"2026-05-06T05:49:35.772984Z","end":"2026-05-06T05:49:35.908691Z","steps":["trace[1932419734] 'agreement among raft nodes before linearized reading' (duration: 135.540691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.908706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.697875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-05-06T05:49:35.908799Z","caller":"traceutil/trace.go:171","msg":"trace[1794820479] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:24236; }","duration":"526.838539ms","start":"2026-05-06T05:49:35.38194Z","end":"2026-05-06T05:49:35.908778Z","steps":["trace[1794820479] 'agreement among raft nodes before linearized reading' (duration: 526.587953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.90866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.673155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:49:35.908869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:35.381923Z","time spent":"526.936101ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1367,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-05-06T05:49:35.908711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.675365ms","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-05-06T05:49:35.909121Z","caller":"traceutil/trace.go:171","msg":"trace[1489529755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24236; }","duration":"112.126456ms","start":"2026-05-06T05:49:35.796961Z","end":"2026-05-06T05:49:35.909087Z","steps":["trace[1489529755] 'agreement among raft nodes before linearized reading' (duration: 111.570513ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:35.908972Z","caller":"traceutil/trace.go:171","msg":"trace[858083502] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24236; }","duration":"527.015773ms","start":"2026-05-06T05:49:35.381923Z","end":"2026-05-06T05:49:35.908938Z","steps":["trace[858083502] 'agreement among raft nodes before linearized reading' (duration: 526.572483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:35.909237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:35.38191Z","time spent":"527.30344ms","remote":"127.0.0.1:40974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-06T05:49:36.419576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.101777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:36.419745Z","caller":"traceutil/trace.go:171","msg":"trace[1373434037] transaction","detail":"{read_only:false; response_revision:24237; number_of_response:1; }","duration":"505.71797ms","start":"2026-05-06T05:49:35.913985Z","end":"2026-05-06T05:49:36.419703Z","steps":["trace[1373434037] 'process raft request' (duration: 232.410148ms)","trace[1373434037] 'compare' (duration: 272.634266ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:36.419821Z","caller":"traceutil/trace.go:171","msg":"trace[1390837260] linearizableReadLoop","detail":"{readStateIndex:25031; appliedIndex:25030; }","duration":"505.797752ms","start":"2026-05-06T05:49:35.914003Z","end":"2026-05-06T05:49:36.419801Z","steps":["trace[1390837260] 'read index received' (duration: 232.404168ms)","trace[1390837260] 'applied index is now lower than readState.Index' (duration: 273.391824ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:36.419946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:35.913973Z","time spent":"505.872484ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:36.419995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.32218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:36.420035Z","caller":"traceutil/trace.go:171","msg":"trace[1088449366] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24238; }","duration":"181.394501ms","start":"2026-05-06T05:49:36.23863Z","end":"2026-05-06T05:49:36.420024Z","steps":["trace[1088449366] 'agreement among raft nodes before linearized reading' (duration: 181.324819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:36.420132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.556068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8c8e1359-8383-43e9-99a2-5e5daef08b91\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-06T05:49:36.419799Z","caller":"traceutil/trace.go:171","msg":"trace[2137282268] transaction","detail":"{read_only:false; response_revision:24238; number_of_response:1; }","duration":"400.637123ms","start":"2026-05-06T05:49:36.019142Z","end":"2026-05-06T05:49:36.419779Z","steps":["trace[2137282268] 'process raft request' (duration: 400.540771ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:36.420166Z","caller":"traceutil/trace.go:171","msg":"trace[498321913] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8c8e1359-8383-43e9-99a2-5e5daef08b91; range_end:; response_count:1; response_revision:24238; }","duration":"157.62677ms","start":"2026-05-06T05:49:36.262527Z","end":"2026-05-06T05:49:36.420154Z","steps":["trace[498321913] 'agreement among raft nodes before linearized reading' (duration: 157.544928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:36.420033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.025848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-05-06T05:49:36.420238Z","caller":"traceutil/trace.go:171","msg":"trace[190600283] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:24238; }","duration":"506.266773ms","start":"2026-05-06T05:49:35.913958Z","end":"2026-05-06T05:49:36.420225Z","steps":["trace[190600283] 'agreement among raft nodes before linearized reading' (duration: 505.947966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:36.420248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:36.019111Z","time spent":"401.087384ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:36.42028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:35.913942Z","time spent":"506.325004ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1367,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-05-06T05:49:36.420122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.540806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18368"} {"level":"warn","ts":"2026-05-06T05:49:36.420556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.979198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-06T05:49:36.420573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.133528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3237"} {"level":"info","ts":"2026-05-06T05:49:36.420585Z","caller":"traceutil/trace.go:171","msg":"trace[1706287297] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24238; }","duration":"506.030999ms","start":"2026-05-06T05:49:35.914536Z","end":"2026-05-06T05:49:36.420567Z","steps":["trace[1706287297] 'agreement among raft nodes before linearized reading' (duration: 505.412763ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:36.420627Z","caller":"traceutil/trace.go:171","msg":"trace[341751824] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24238; }","duration":"441.18568ms","start":"2026-05-06T05:49:35.979424Z","end":"2026-05-06T05:49:36.42061Z","steps":["trace[341751824] 'agreement among raft nodes before linearized reading' (duration: 441.037066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:36.420635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:35.914525Z","time spent":"506.09792ms","remote":"127.0.0.1:40956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18392,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-06T05:49:36.420685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:35.979361Z","time spent":"441.308632ms","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3261,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-06T05:49:36.420723Z","caller":"traceutil/trace.go:171","msg":"trace[400146383] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24238; }","duration":"353.08328ms","start":"2026-05-06T05:49:36.067518Z","end":"2026-05-06T05:49:36.420602Z","steps":["trace[400146383] 'agreement among raft nodes before linearized reading' (duration: 352.898825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:36.420761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:36.067501Z","time spent":"353.251874ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-06T05:49:36.747589Z","caller":"traceutil/trace.go:171","msg":"trace[691320577] linearizableReadLoop","detail":"{readStateIndex:25033; appliedIndex:25032; }","duration":"320.19722ms","start":"2026-05-06T05:49:36.427365Z","end":"2026-05-06T05:49:36.747562Z","steps":["trace[691320577] 'read index received' (duration: 293.215201ms)","trace[691320577] 'applied index is now lower than readState.Index' (duration: 26.980609ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:36.747728Z","caller":"traceutil/trace.go:171","msg":"trace[1879749444] transaction","detail":"{read_only:false; response_revision:24239; number_of_response:1; }","duration":"320.906756ms","start":"2026-05-06T05:49:36.426802Z","end":"2026-05-06T05:49:36.747709Z","steps":["trace[1879749444] 'process raft request' (duration: 293.716363ms)","trace[1879749444] 'compare' (duration: 26.63618ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:36.747882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:36.426788Z","time spent":"320.975969ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:36.747859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.477316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-05-06T05:49:36.747965Z","caller":"traceutil/trace.go:171","msg":"trace[1728115548] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:24239; }","duration":"320.61475ms","start":"2026-05-06T05:49:36.427335Z","end":"2026-05-06T05:49:36.74795Z","steps":["trace[1728115548] 'agreement among raft nodes before linearized reading' (duration: 320.392625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:36.747997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.998952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-06T05:49:36.748068Z","caller":"traceutil/trace.go:171","msg":"trace[2013938066] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24239; }","duration":"317.094455ms","start":"2026-05-06T05:49:36.430955Z","end":"2026-05-06T05:49:36.748049Z","steps":["trace[2013938066] 'agreement among raft nodes before linearized reading' (duration: 316.85156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:36.748083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:36.427322Z","time spent":"320.734152ms","remote":"127.0.0.1:40868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":721,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" "} {"level":"warn","ts":"2026-05-06T05:49:36.748148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:36.430944Z","time spent":"317.194447ms","remote":"127.0.0.1:51670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5708,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-06T05:49:36.748168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.259993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-05-06T05:49:36.748271Z","caller":"traceutil/trace.go:171","msg":"trace[1924440999] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:24239; }","duration":"318.584611ms","start":"2026-05-06T05:49:36.429663Z","end":"2026-05-06T05:49:36.748248Z","steps":["trace[1924440999] 'agreement among raft nodes before linearized reading' (duration: 318.079498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:36.74832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:36.429654Z","time spent":"318.655913ms","remote":"127.0.0.1:40974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1282,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"info","ts":"2026-05-06T05:49:38.547357Z","caller":"traceutil/trace.go:171","msg":"trace[228024248] linearizableReadLoop","detail":"{readStateIndex:25055; appliedIndex:25054; }","duration":"120.729553ms","start":"2026-05-06T05:49:38.426603Z","end":"2026-05-06T05:49:38.547332Z","steps":["trace[228024248] 'read index received' (duration: 120.463887ms)","trace[228024248] 'applied index is now lower than readState.Index' (duration: 264.196µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:38.547419Z","caller":"traceutil/trace.go:171","msg":"trace[1740967557] transaction","detail":"{read_only:false; response_revision:24260; number_of_response:1; }","duration":"121.815119ms","start":"2026-05-06T05:49:38.425573Z","end":"2026-05-06T05:49:38.547389Z","steps":["trace[1740967557] 'process raft request' (duration: 121.583324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:38.547601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.972679ms","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-05-06T05:49:38.547641Z","caller":"traceutil/trace.go:171","msg":"trace[1337329056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24260; }","duration":"121.05203ms","start":"2026-05-06T05:49:38.426579Z","end":"2026-05-06T05:49:38.547631Z","steps":["trace[1337329056] 'agreement among raft nodes before linearized reading' (duration: 120.898317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:38.547919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.734009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8c8e1359-8383-43e9-99a2-5e5daef08b91\" ","response":"range_response_count:1 size:3934"} {"level":"info","ts":"2026-05-06T05:49:38.548036Z","caller":"traceutil/trace.go:171","msg":"trace[1757087820] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8c8e1359-8383-43e9-99a2-5e5daef08b91; range_end:; response_count:1; response_revision:24260; }","duration":"119.888713ms","start":"2026-05-06T05:49:38.428115Z","end":"2026-05-06T05:49:38.548004Z","steps":["trace[1757087820] 'agreement among raft nodes before linearized reading' (duration: 119.658408ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:03.679147Z","caller":"traceutil/trace.go:171","msg":"trace[2012144423] transaction","detail":"{read_only:false; response_revision:24506; number_of_response:1; }","duration":"121.502091ms","start":"2026-05-06T05:50:03.557609Z","end":"2026-05-06T05:50:03.679111Z","steps":["trace[2012144423] 'process raft request' (duration: 121.353757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:03.920533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.668939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:03.920607Z","caller":"traceutil/trace.go:171","msg":"trace[1973525520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24506; }","duration":"238.793032ms","start":"2026-05-06T05:50:03.681796Z","end":"2026-05-06T05:50:03.92059Z","steps":["trace[1973525520] 'range keys from in-memory index tree' (duration: 238.550106ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:03.921464Z","caller":"traceutil/trace.go:171","msg":"trace[935421794] transaction","detail":"{read_only:false; response_revision:24507; number_of_response:1; }","duration":"238.073735ms","start":"2026-05-06T05:50:03.683371Z","end":"2026-05-06T05:50:03.921444Z","steps":["trace[935421794] 'process raft request' (duration: 148.675596ms)","trace[935421794] 'compare' (duration: 89.155543ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:04.064244Z","caller":"traceutil/trace.go:171","msg":"trace[1599224253] linearizableReadLoop","detail":"{readStateIndex:25309; appliedIndex:25308; }","duration":"224.604131ms","start":"2026-05-06T05:50:03.839614Z","end":"2026-05-06T05:50:04.064219Z","steps":["trace[1599224253] 'read index received' (duration: 204.000566ms)","trace[1599224253] 'applied index is now lower than readState.Index' (duration: 20.602365ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:04.064308Z","caller":"traceutil/trace.go:171","msg":"trace[1234285687] transaction","detail":"{read_only:false; response_revision:24508; number_of_response:1; }","duration":"380.437227ms","start":"2026-05-06T05:50:03.683829Z","end":"2026-05-06T05:50:04.064266Z","steps":["trace[1234285687] 'process raft request' (duration: 359.876133ms)","trace[1234285687] 'compare' (duration: 20.39424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:04.064454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.768355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:50:04.064486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:03.683809Z","time spent":"380.574531ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:04.064501Z","caller":"traceutil/trace.go:171","msg":"trace[1210536835] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24508; }","duration":"224.879838ms","start":"2026-05-06T05:50:03.839609Z","end":"2026-05-06T05:50:04.064488Z","steps":["trace[1210536835] 'agreement among raft nodes before linearized reading' (duration: 224.743494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:04.06473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.093457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-06T05:50:04.065069Z","caller":"traceutil/trace.go:171","msg":"trace[434560124] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24508; }","duration":"199.485597ms","start":"2026-05-06T05:50:03.865567Z","end":"2026-05-06T05:50:04.065053Z","steps":["trace[434560124] 'agreement among raft nodes before linearized reading' (duration: 199.004285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:04.431566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.134448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:50:04.431651Z","caller":"traceutil/trace.go:171","msg":"trace[20824858] linearizableReadLoop","detail":"{readStateIndex:25310; appliedIndex:25309; }","duration":"309.048682ms","start":"2026-05-06T05:50:04.12259Z","end":"2026-05-06T05:50:04.431639Z","steps":["trace[20824858] 'read index received' (duration: 92.690349ms)","trace[20824858] 'applied index is now lower than readState.Index' (duration: 216.356603ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:04.431663Z","caller":"traceutil/trace.go:171","msg":"trace[214390603] transaction","detail":"{read_only:false; response_revision:24509; number_of_response:1; }","duration":"341.468871ms","start":"2026-05-06T05:50:04.090176Z","end":"2026-05-06T05:50:04.431645Z","steps":["trace[214390603] 'process raft request' (duration: 125.176159ms)","trace[214390603] 'compare' (duration: 215.83159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:04.431719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:04.090156Z","time spent":"341.535352ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:04.431796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.177536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:04.431868Z","caller":"traceutil/trace.go:171","msg":"trace[1692101848] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24509; }","duration":"309.311569ms","start":"2026-05-06T05:50:04.122539Z","end":"2026-05-06T05:50:04.43185Z","steps":["trace[1692101848] 'agreement among raft nodes before linearized reading' (duration: 309.158755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:04.4319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:04.122519Z","time spent":"309.3736ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:50:05.012761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.662434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-05-06T05:50:05.012907Z","caller":"traceutil/trace.go:171","msg":"trace[1238511172] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24510; }","duration":"138.817188ms","start":"2026-05-06T05:50:04.874072Z","end":"2026-05-06T05:50:05.012889Z","steps":["trace[1238511172] 'range keys from in-memory index tree' (duration: 138.51199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:05.012913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.018512ms","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-05-06T05:50:05.013001Z","caller":"traceutil/trace.go:171","msg":"trace[136845837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24510; }","duration":"193.146965ms","start":"2026-05-06T05:50:04.819839Z","end":"2026-05-06T05:50:05.012986Z","steps":["trace[136845837] 'range keys from in-memory index tree' (duration: 192.800896ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:05.178161Z","caller":"traceutil/trace.go:171","msg":"trace[333156310] transaction","detail":"{read_only:false; response_revision:24511; number_of_response:1; }","duration":"157.414835ms","start":"2026-05-06T05:50:05.020715Z","end":"2026-05-06T05:50:05.17813Z","steps":["trace[333156310] 'process raft request' (duration: 157.233191ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:05.401983Z","caller":"traceutil/trace.go:171","msg":"trace[602138129] transaction","detail":"{read_only:false; response_revision:24512; number_of_response:1; }","duration":"370.784825ms","start":"2026-05-06T05:50:05.031172Z","end":"2026-05-06T05:50:05.401957Z","steps":["trace[602138129] 'process raft request' (duration: 369.831183ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:05.402007Z","caller":"traceutil/trace.go:171","msg":"trace[1230550516] transaction","detail":"{read_only:false; response_revision:24514; number_of_response:1; }","duration":"295.29451ms","start":"2026-05-06T05:50:05.106689Z","end":"2026-05-06T05:50:05.401983Z","steps":["trace[1230550516] 'process raft request' (duration: 295.222918ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:05.401989Z","caller":"traceutil/trace.go:171","msg":"trace[753986930] transaction","detail":"{read_only:false; response_revision:24513; number_of_response:1; }","duration":"314.177644ms","start":"2026-05-06T05:50:05.087784Z","end":"2026-05-06T05:50:05.401962Z","steps":["trace[753986930] 'process raft request' (duration: 314.040511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:05.402154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:05.031131Z","time spent":"370.945649ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:05.402227Z","caller":"traceutil/trace.go:171","msg":"trace[2025285326] linearizableReadLoop","detail":"{readStateIndex:25318; appliedIndex:25311; }","duration":"263.117507ms","start":"2026-05-06T05:50:05.139086Z","end":"2026-05-06T05:50:05.402204Z","steps":["trace[2025285326] 'read index received' (duration: 38.842865ms)","trace[2025285326] 'applied index is now lower than readState.Index' (duration: 224.273302ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:05.402369Z","caller":"traceutil/trace.go:171","msg":"trace[1547486942] transaction","detail":"{read_only:false; response_revision:24515; number_of_response:1; }","duration":"293.057518ms","start":"2026-05-06T05:50:05.109293Z","end":"2026-05-06T05:50:05.40235Z","steps":["trace[1547486942] 'process raft request' (duration: 292.653088ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:05.402381Z","caller":"traceutil/trace.go:171","msg":"trace[719374984] transaction","detail":"{read_only:false; response_revision:24516; number_of_response:1; }","duration":"291.070059ms","start":"2026-05-06T05:50:05.111299Z","end":"2026-05-06T05:50:05.402369Z","steps":["trace[719374984] 'process raft request' (duration: 290.795243ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:05.402391Z","caller":"traceutil/trace.go:171","msg":"trace[1742054833] transaction","detail":"{read_only:false; response_revision:24517; number_of_response:1; }","duration":"290.117735ms","start":"2026-05-06T05:50:05.112259Z","end":"2026-05-06T05:50:05.402377Z","steps":["trace[1742054833] 'process raft request' (duration: 289.90517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:05.402354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:05.087765Z","time spent":"314.395669ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:05.402564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.433735ms","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-05-06T05:50:05.402611Z","caller":"traceutil/trace.go:171","msg":"trace[689978209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24517; }","duration":"263.555778ms","start":"2026-05-06T05:50:05.139044Z","end":"2026-05-06T05:50:05.4026Z","steps":["trace[689978209] 'agreement among raft nodes before linearized reading' (duration: 263.302642ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:05.701149Z","caller":"traceutil/trace.go:171","msg":"trace[361627463] transaction","detail":"{read_only:false; response_revision:24518; number_of_response:1; }","duration":"282.804201ms","start":"2026-05-06T05:50:05.418322Z","end":"2026-05-06T05:50:05.701127Z","steps":["trace[361627463] 'process raft request' (duration: 204.768104ms)","trace[361627463] 'compare' (duration: 77.680538ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:06.203869Z","caller":"traceutil/trace.go:171","msg":"trace[89756783] linearizableReadLoop","detail":"{readStateIndex:25320; appliedIndex:25319; }","duration":"276.413476ms","start":"2026-05-06T05:50:05.927425Z","end":"2026-05-06T05:50:06.203839Z","steps":["trace[89756783] 'read index received' (duration: 276.17105ms)","trace[89756783] 'applied index is now lower than readState.Index' (duration: 239.936µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:06.204076Z","caller":"traceutil/trace.go:171","msg":"trace[143495544] transaction","detail":"{read_only:false; response_revision:24519; number_of_response:1; }","duration":"500.137236ms","start":"2026-05-06T05:50:05.7039Z","end":"2026-05-06T05:50:06.204038Z","steps":["trace[143495544] 'process raft request' (duration: 499.731306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:06.204177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.721163ms","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-05-06T05:50:06.204219Z","caller":"traceutil/trace.go:171","msg":"trace[1758953573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24519; }","duration":"276.835046ms","start":"2026-05-06T05:50:05.927369Z","end":"2026-05-06T05:50:06.204205Z","steps":["trace[1758953573] 'agreement among raft nodes before linearized reading' (duration: 276.649401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:06.204264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:05.70388Z","time spent":"500.2848ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:06.204327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.497053ms","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-05-06T05:50:06.20448Z","caller":"traceutil/trace.go:171","msg":"trace[1702682039] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:24519; }","duration":"208.740679ms","start":"2026-05-06T05:50:05.995709Z","end":"2026-05-06T05:50:06.204449Z","steps":["trace[1702682039] 'agreement among raft nodes before linearized reading' (duration: 208.426472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:06.204537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.865844ms","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-05-06T05:50:06.204582Z","caller":"traceutil/trace.go:171","msg":"trace[1994854711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24519; }","duration":"132.937106ms","start":"2026-05-06T05:50:06.071633Z","end":"2026-05-06T05:50:06.20457Z","steps":["trace[1994854711] 'agreement among raft nodes before linearized reading' (duration: 132.771052ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:06.449343Z","caller":"traceutil/trace.go:171","msg":"trace[792679506] transaction","detail":"{read_only:false; response_revision:24520; number_of_response:1; }","duration":"240.456612ms","start":"2026-05-06T05:50:06.208868Z","end":"2026-05-06T05:50:06.449325Z","steps":["trace[792679506] 'process raft request' (duration: 227.948021ms)","trace[792679506] 'compare' (duration: 12.426619ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:06.470611Z","caller":"traceutil/trace.go:171","msg":"trace[1633981125] linearizableReadLoop","detail":"{readStateIndex:25322; appliedIndex:25320; }","duration":"229.651402ms","start":"2026-05-06T05:50:06.240939Z","end":"2026-05-06T05:50:06.47059Z","steps":["trace[1633981125] 'read index received' (duration: 196.25499ms)","trace[1633981125] 'applied index is now lower than readState.Index' (duration: 33.395142ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:06.470654Z","caller":"traceutil/trace.go:171","msg":"trace[1108787506] transaction","detail":"{read_only:false; response_revision:24521; number_of_response:1; }","duration":"260.76224ms","start":"2026-05-06T05:50:06.209869Z","end":"2026-05-06T05:50:06.470631Z","steps":["trace[1108787506] 'process raft request' (duration: 260.580886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:06.470765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.824396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:06.470807Z","caller":"traceutil/trace.go:171","msg":"trace[697044507] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24521; }","duration":"229.925449ms","start":"2026-05-06T05:50:06.240867Z","end":"2026-05-06T05:50:06.470793Z","steps":["trace[697044507] 'agreement among raft nodes before linearized reading' (duration: 229.814566ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:06.754205Z","caller":"traceutil/trace.go:171","msg":"trace[1472421694] linearizableReadLoop","detail":"{readStateIndex:25323; appliedIndex:25322; }","duration":"137.797165ms","start":"2026-05-06T05:50:06.616386Z","end":"2026-05-06T05:50:06.754184Z","steps":["trace[1472421694] 'read index received' (duration: 124.807452ms)","trace[1472421694] 'applied index is now lower than readState.Index' (duration: 12.988603ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:06.754253Z","caller":"traceutil/trace.go:171","msg":"trace[362356912] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"315.022606ms","start":"2026-05-06T05:50:06.439181Z","end":"2026-05-06T05:50:06.754203Z","steps":["trace[362356912] 'process raft request' (duration: 301.761707ms)","trace[362356912] 'compare' (duration: 12.786447ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:06.754313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.914747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:06.754391Z","caller":"traceutil/trace.go:171","msg":"trace[173923916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24522; }","duration":"138.04127ms","start":"2026-05-06T05:50:06.616336Z","end":"2026-05-06T05:50:06.754377Z","steps":["trace[173923916] 'agreement among raft nodes before linearized reading' (duration: 137.934098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:06.754459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:06.439157Z","time spent":"315.185339ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:06.756259Z","caller":"traceutil/trace.go:171","msg":"trace[569962921] transaction","detail":"{read_only:false; response_revision:24523; number_of_response:1; }","duration":"101.100711ms","start":"2026-05-06T05:50:06.655147Z","end":"2026-05-06T05:50:06.756247Z","steps":["trace[569962921] 'process raft request' (duration: 100.918057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:07.370096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.169745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-06T05:50:07.370181Z","caller":"traceutil/trace.go:171","msg":"trace[350180839] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24523; }","duration":"509.282247ms","start":"2026-05-06T05:50:06.860878Z","end":"2026-05-06T05:50:07.37016Z","steps":["trace[350180839] 'range keys from in-memory index tree' (duration: 508.986429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:07.370384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:06.860868Z","time spent":"509.502672ms","remote":"127.0.0.1:42742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3260,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-06T05:50:07.370913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.585665ms","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-05-06T05:50:07.370946Z","caller":"traceutil/trace.go:171","msg":"trace[623835879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24523; }","duration":"188.658287ms","start":"2026-05-06T05:50:07.182277Z","end":"2026-05-06T05:50:07.370936Z","steps":["trace[623835879] 'range keys from in-memory index tree' (duration: 188.450452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:07.945865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980512545721234620,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T05:50:08.025371Z","caller":"traceutil/trace.go:171","msg":"trace[1324300075] transaction","detail":"{read_only:false; response_revision:24525; number_of_response:1; }","duration":"617.601321ms","start":"2026-05-06T05:50:07.407745Z","end":"2026-05-06T05:50:08.025347Z","steps":["trace[1324300075] 'process raft request' (duration: 617.388986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:08.025527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:07.407718Z","time spent":"617.733204ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:08.02566Z","caller":"traceutil/trace.go:171","msg":"trace[1603895491] transaction","detail":"{read_only:false; response_revision:24526; number_of_response:1; }","duration":"616.836064ms","start":"2026-05-06T05:50:07.408814Z","end":"2026-05-06T05:50:08.02565Z","steps":["trace[1603895491] 'process raft request' (duration: 616.488855ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:08.025678Z","caller":"traceutil/trace.go:171","msg":"trace[1726125618] transaction","detail":"{read_only:false; response_revision:24527; number_of_response:1; }","duration":"616.001913ms","start":"2026-05-06T05:50:07.409658Z","end":"2026-05-06T05:50:08.02566Z","steps":["trace[1726125618] 'process raft request' (duration: 615.884719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:08.025718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:07.408803Z","time spent":"616.887415ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:08.025772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:07.409646Z","time spent":"616.082325ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:08.025912Z","caller":"traceutil/trace.go:171","msg":"trace[1125928170] transaction","detail":"{read_only:false; response_revision:24528; number_of_response:1; }","duration":"616.177287ms","start":"2026-05-06T05:50:07.409722Z","end":"2026-05-06T05:50:08.025899Z","steps":["trace[1125928170] 'process raft request' (duration: 615.89659ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:08.02592Z","caller":"traceutil/trace.go:171","msg":"trace[574498241] transaction","detail":"{read_only:false; response_revision:24529; number_of_response:1; }","duration":"613.41999ms","start":"2026-05-06T05:50:07.412489Z","end":"2026-05-06T05:50:08.025909Z","steps":["trace[574498241] 'process raft request' (duration: 613.251146ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:08.025934Z","caller":"traceutil/trace.go:171","msg":"trace[1738470317] linearizableReadLoop","detail":"{readStateIndex:25330; appliedIndex:25325; }","duration":"581.132395ms","start":"2026-05-06T05:50:07.444773Z","end":"2026-05-06T05:50:08.025905Z","steps":["trace[1738470317] 'read index received' (duration: 580.293814ms)","trace[1738470317] 'applied index is now lower than readState.Index' (duration: 837.061µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:08.025973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:07.412481Z","time spent":"613.465492ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:08.025974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:07.409708Z","time spent":"616.234968ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:08.026227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.698982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-06T05:50:08.026293Z","caller":"traceutil/trace.go:171","msg":"trace[523524832] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24529; }","duration":"619.811334ms","start":"2026-05-06T05:50:07.406464Z","end":"2026-05-06T05:50:08.026275Z","steps":["trace[523524832] 'agreement among raft nodes before linearized reading' (duration: 619.616799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:08.026346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:07.406445Z","time spent":"619.887967ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":583,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-06T05:50:08.418841Z","caller":"traceutil/trace.go:171","msg":"trace[244015191] linearizableReadLoop","detail":"{readStateIndex:25331; appliedIndex:25330; }","duration":"392.804265ms","start":"2026-05-06T05:50:08.026019Z","end":"2026-05-06T05:50:08.418823Z","steps":["trace[244015191] 'read index received' (duration: 392.731693ms)","trace[244015191] 'applied index is now lower than readState.Index' (duration: 71.252µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:08.418935Z","caller":"traceutil/trace.go:171","msg":"trace[228295425] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"435.352008ms","start":"2026-05-06T05:50:07.983555Z","end":"2026-05-06T05:50:08.418907Z","steps":["trace[228295425] 'process raft request' (duration: 435.165914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:08.419094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.261816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:50:08.419083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:07.983524Z","time spent":"435.484522ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:08.419121Z","caller":"traceutil/trace.go:171","msg":"trace[1723457878] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24530; }","duration":"897.353838ms","start":"2026-05-06T05:50:07.521761Z","end":"2026-05-06T05:50:08.419115Z","steps":["trace[1723457878] 'agreement among raft nodes before linearized reading' (duration: 897.140333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:08.419161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:07.521742Z","time spent":"897.4138ms","remote":"127.0.0.1:41250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-06T05:50:08.419311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.836155ms","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-05-06T05:50:08.419555Z","caller":"traceutil/trace.go:171","msg":"trace[296387057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24530; }","duration":"714.201064ms","start":"2026-05-06T05:50:07.70534Z","end":"2026-05-06T05:50:08.419541Z","steps":["trace[296387057] 'agreement among raft nodes before linearized reading' (duration: 713.572629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:08.419597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:07.705321Z","time spent":"714.263455ms","remote":"127.0.0.1:41054","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-05-06T05:50:08.423177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.806645ms","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-05-06T05:50:08.42323Z","caller":"traceutil/trace.go:171","msg":"trace[1730014257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24531; }","duration":"214.899257ms","start":"2026-05-06T05:50:08.208318Z","end":"2026-05-06T05:50:08.423217Z","steps":["trace[1730014257] 'agreement among raft nodes before linearized reading' (duration: 214.731853ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:08.426824Z","caller":"traceutil/trace.go:171","msg":"trace[661626960] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"384.430325ms","start":"2026-05-06T05:50:08.036371Z","end":"2026-05-06T05:50:08.420801Z","steps":["trace[661626960] 'process raft request' (duration: 384.284051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:08.426993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:08.03635Z","time spent":"390.556383ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:12.270926Z","caller":"traceutil/trace.go:171","msg":"trace[1335382962] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"227.88667ms","start":"2026-05-06T05:50:12.043018Z","end":"2026-05-06T05:50:12.270905Z","steps":["trace[1335382962] 'process raft request' (duration: 155.754335ms)","trace[1335382962] 'compare' (duration: 71.674754ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:12.271125Z","caller":"traceutil/trace.go:171","msg":"trace[1626564087] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"225.347788ms","start":"2026-05-06T05:50:12.045748Z","end":"2026-05-06T05:50:12.271095Z","steps":["trace[1626564087] 'process raft request' (duration: 225.078622ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:12.271204Z","caller":"traceutil/trace.go:171","msg":"trace[1000829142] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"221.969637ms","start":"2026-05-06T05:50:12.049211Z","end":"2026-05-06T05:50:12.27118Z","steps":["trace[1000829142] 'process raft request' (duration: 221.917966ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:12.271298Z","caller":"traceutil/trace.go:171","msg":"trace[1531130311] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"223.606317ms","start":"2026-05-06T05:50:12.047654Z","end":"2026-05-06T05:50:12.27126Z","steps":["trace[1531130311] 'process raft request' (duration: 223.374332ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:12.618922Z","caller":"traceutil/trace.go:171","msg":"trace[202906456] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"157.480787ms","start":"2026-05-06T05:50:12.461415Z","end":"2026-05-06T05:50:12.618896Z","steps":["trace[202906456] 'process raft request' (duration: 157.317783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:12.825847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.944393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:50:12.82643Z","caller":"traceutil/trace.go:171","msg":"trace[1397357307] linearizableReadLoop","detail":"{readStateIndex:25370; appliedIndex:25367; }","duration":"356.126294ms","start":"2026-05-06T05:50:12.470287Z","end":"2026-05-06T05:50:12.826413Z","steps":["trace[1397357307] 'read index received' (duration: 148.47604ms)","trace[1397357307] 'applied index is now lower than readState.Index' (duration: 207.649314ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:12.826519Z","caller":"traceutil/trace.go:171","msg":"trace[495463637] transaction","detail":"{read_only:false; response_revision:24567; number_of_response:1; }","duration":"364.221618ms","start":"2026-05-06T05:50:12.462284Z","end":"2026-05-06T05:50:12.826505Z","steps":["trace[495463637] 'process raft request' (duration: 246.564659ms)","trace[495463637] 'compare' (duration: 116.485531ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:12.826584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:12.462269Z","time spent":"364.27824ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:12.826605Z","caller":"traceutil/trace.go:171","msg":"trace[646895308] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"362.111167ms","start":"2026-05-06T05:50:12.464458Z","end":"2026-05-06T05:50:12.82657Z","steps":["trace[646895308] 'process raft request' (duration: 361.455832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:12.82673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.423391ms","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-05-06T05:50:12.826791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:12.464441Z","time spent":"362.25356ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:12.826816Z","caller":"traceutil/trace.go:171","msg":"trace[818623830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24568; }","duration":"356.532893ms","start":"2026-05-06T05:50:12.470266Z","end":"2026-05-06T05:50:12.826799Z","steps":["trace[818623830] 'agreement among raft nodes before linearized reading' (duration: 356.296147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:12.826882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:12.470252Z","time spent":"356.602895ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T05:50:13.214606Z","caller":"traceutil/trace.go:171","msg":"trace[232146258] linearizableReadLoop","detail":"{readStateIndex:25372; appliedIndex:25370; }","duration":"388.118904ms","start":"2026-05-06T05:50:12.826471Z","end":"2026-05-06T05:50:13.21459Z","steps":["trace[232146258] 'read index received' (duration: 20.116794ms)","trace[232146258] 'applied index is now lower than readState.Index' (duration: 368.00119ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:13.214818Z","caller":"traceutil/trace.go:171","msg":"trace[533077112] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"426.034305ms","start":"2026-05-06T05:50:12.788776Z","end":"2026-05-06T05:50:13.21481Z","steps":["trace[533077112] 'process raft request' (duration: 425.675096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:13.214891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:12.788744Z","time spent":"426.094496ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:13.214985Z","caller":"traceutil/trace.go:171","msg":"trace[1530760614] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"383.549374ms","start":"2026-05-06T05:50:12.831422Z","end":"2026-05-06T05:50:13.214972Z","steps":["trace[1530760614] 'process raft request' (duration: 383.139244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:13.21499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.118984ms","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-05-06T05:50:13.21503Z","caller":"traceutil/trace.go:171","msg":"trace[805824624] 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:24571; }","duration":"492.158655ms","start":"2026-05-06T05:50:12.72286Z","end":"2026-05-06T05:50:13.215019Z","steps":["trace[805824624] 'agreement among raft nodes before linearized reading' (duration: 492.104073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:13.215052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:12.831395Z","time spent":"383.626105ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:13.215066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:12.722798Z","time spent":"492.259278ms","remote":"127.0.0.1:56602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:50:13.215044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.75384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-06T05:50:13.215057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.706285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:50:13.215083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.413479ms","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-05-06T05:50:13.215132Z","caller":"traceutil/trace.go:171","msg":"trace[360464761] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:24571; }","duration":"292.887884ms","start":"2026-05-06T05:50:12.922224Z","end":"2026-05-06T05:50:13.215112Z","steps":["trace[360464761] 'agreement among raft nodes before linearized reading' (duration: 292.746141ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:13.215142Z","caller":"traceutil/trace.go:171","msg":"trace[1380356485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24571; }","duration":"600.844448ms","start":"2026-05-06T05:50:12.614278Z","end":"2026-05-06T05:50:13.215123Z","steps":["trace[1380356485] 'agreement among raft nodes before linearized reading' (duration: 600.694695ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:13.215144Z","caller":"traceutil/trace.go:171","msg":"trace[2070809151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24571; }","duration":"704.518891ms","start":"2026-05-06T05:50:12.510609Z","end":"2026-05-06T05:50:13.215128Z","steps":["trace[2070809151] 'agreement among raft nodes before linearized reading' (duration: 704.319647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:13.215186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:12.614255Z","time spent":"600.922099ms","remote":"127.0.0.1:40788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:50:13.215174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.597822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:50:13.215206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.887036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-05-06T05:50:13.215258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.294578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.139\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-06T05:50:13.215281Z","caller":"traceutil/trace.go:171","msg":"trace[1818942092] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24571; }","duration":"106.191133ms","start":"2026-05-06T05:50:13.109073Z","end":"2026-05-06T05:50:13.215264Z","steps":["trace[1818942092] 'agreement among raft nodes before linearized reading' (duration: 105.953848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:13.215291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.769219ms","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-05-06T05:50:13.215321Z","caller":"traceutil/trace.go:171","msg":"trace[1438160539] 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:24571; }","duration":"136.827841ms","start":"2026-05-06T05:50:13.078486Z","end":"2026-05-06T05:50:13.215314Z","steps":["trace[1438160539] 'agreement among raft nodes before linearized reading' (duration: 136.776649ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:13.215315Z","caller":"traceutil/trace.go:171","msg":"trace[1148392352] range","detail":"{range_begin:/registry/masterleases/199.19.213.139; range_end:; response_count:1; response_revision:24571; }","duration":"104.367679ms","start":"2026-05-06T05:50:13.110934Z","end":"2026-05-06T05:50:13.215302Z","steps":["trace[1148392352] 'agreement among raft nodes before linearized reading' (duration: 104.188215ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:13.21524Z","caller":"traceutil/trace.go:171","msg":"trace[2088186967] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:24571; }","duration":"113.709985ms","start":"2026-05-06T05:50:13.101514Z","end":"2026-05-06T05:50:13.215224Z","steps":["trace[2088186967] 'agreement among raft nodes before linearized reading' (duration: 113.544351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:13.215187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:12.510587Z","time spent":"704.591622ms","remote":"127.0.0.1:41054","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-05-06T05:50:15.356639Z","caller":"traceutil/trace.go:171","msg":"trace[83578621] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"135.240213ms","start":"2026-05-06T05:50:15.221373Z","end":"2026-05-06T05:50:15.356613Z","steps":["trace[83578621] 'process raft request' (duration: 135.054068ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:15.405Z","caller":"traceutil/trace.go:171","msg":"trace[453691889] linearizableReadLoop","detail":"{readStateIndex:25391; appliedIndex:25389; }","duration":"180.468351ms","start":"2026-05-06T05:50:15.224505Z","end":"2026-05-06T05:50:15.404974Z","steps":["trace[453691889] 'read index received' (duration: 131.889442ms)","trace[453691889] 'applied index is now lower than readState.Index' (duration: 48.577399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:15.405178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.060652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:15.405153Z","caller":"traceutil/trace.go:171","msg":"trace[47327218] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"181.745032ms","start":"2026-05-06T05:50:15.223313Z","end":"2026-05-06T05:50:15.405058Z","steps":["trace[47327218] 'process raft request' (duration: 181.545027ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:15.405223Z","caller":"traceutil/trace.go:171","msg":"trace[1703493946] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24588; }","duration":"158.150414ms","start":"2026-05-06T05:50:15.247059Z","end":"2026-05-06T05:50:15.405209Z","steps":["trace[1703493946] 'agreement among raft nodes before linearized reading' (duration: 158.054382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:15.405237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.712276ms","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-05-06T05:50:15.405544Z","caller":"traceutil/trace.go:171","msg":"trace[1142375203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24588; }","duration":"181.044745ms","start":"2026-05-06T05:50:15.224479Z","end":"2026-05-06T05:50:15.405523Z","steps":["trace[1142375203] 'agreement among raft nodes before linearized reading' (duration: 180.621765ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:15.729023Z","caller":"traceutil/trace.go:171","msg":"trace[599571491] linearizableReadLoop","detail":"{readStateIndex:25392; appliedIndex:25391; }","duration":"307.581087ms","start":"2026-05-06T05:50:15.421416Z","end":"2026-05-06T05:50:15.728997Z","steps":["trace[599571491] 'read index received' (duration: 221.659602ms)","trace[599571491] 'applied index is now lower than readState.Index' (duration: 85.920075ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:15.72904Z","caller":"traceutil/trace.go:171","msg":"trace[1678363149] transaction","detail":"{read_only:false; response_revision:24589; number_of_response:1; }","duration":"318.829838ms","start":"2026-05-06T05:50:15.410179Z","end":"2026-05-06T05:50:15.729008Z","steps":["trace[1678363149] 'process raft request' (duration: 232.903822ms)","trace[1678363149] 'compare' (duration: 85.629829ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:15.729185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.753411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-06T05:50:15.729187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:15.410156Z","time spent":"318.945691ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:15.72922Z","caller":"traceutil/trace.go:171","msg":"trace[330701445] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:24589; }","duration":"307.819783ms","start":"2026-05-06T05:50:15.421388Z","end":"2026-05-06T05:50:15.729208Z","steps":["trace[330701445] 'agreement among raft nodes before linearized reading' (duration: 307.739941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:15.72925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:15.421372Z","time spent":"307.870184ms","remote":"127.0.0.1:40934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":15,"response size":32,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:50:15.729287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.373868ms","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-05-06T05:50:15.729339Z","caller":"traceutil/trace.go:171","msg":"trace[1243102822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24589; }","duration":"249.46614ms","start":"2026-05-06T05:50:15.479858Z","end":"2026-05-06T05:50:15.729324Z","steps":["trace[1243102822] 'agreement among raft nodes before linearized reading' (duration: 249.292686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:15.72952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.663324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:15.729567Z","caller":"traceutil/trace.go:171","msg":"trace[46392996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24589; }","duration":"113.740547ms","start":"2026-05-06T05:50:15.615812Z","end":"2026-05-06T05:50:15.729553Z","steps":["trace[46392996] 'agreement among raft nodes before linearized reading' (duration: 113.664325ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:15.851032Z","caller":"traceutil/trace.go:171","msg":"trace[531031966] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"114.788551ms","start":"2026-05-06T05:50:15.736217Z","end":"2026-05-06T05:50:15.851005Z","steps":["trace[531031966] 'process raft request' (duration: 112.845384ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:19.527856Z","caller":"traceutil/trace.go:171","msg":"trace[354329196] linearizableReadLoop","detail":"{readStateIndex:25430; appliedIndex:25429; }","duration":"108.328475ms","start":"2026-05-06T05:50:19.419507Z","end":"2026-05-06T05:50:19.527835Z","steps":["trace[354329196] 'read index received' (duration: 108.172422ms)","trace[354329196] 'applied index is now lower than readState.Index' (duration: 154.393µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:19.5282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.675354ms","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-05-06T05:50:19.527967Z","caller":"traceutil/trace.go:171","msg":"trace[1257459619] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"160.174823ms","start":"2026-05-06T05:50:19.367761Z","end":"2026-05-06T05:50:19.527936Z","steps":["trace[1257459619] 'process raft request' (duration: 159.934797ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:19.528359Z","caller":"traceutil/trace.go:171","msg":"trace[1883233556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24626; }","duration":"108.817378ms","start":"2026-05-06T05:50:19.419456Z","end":"2026-05-06T05:50:19.528273Z","steps":["trace[1883233556] 'agreement among raft nodes before linearized reading' (duration: 108.597593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:20.077256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.708023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:50:20.077352Z","caller":"traceutil/trace.go:171","msg":"trace[964459363] linearizableReadLoop","detail":"{readStateIndex:25431; appliedIndex:25430; }","duration":"533.274226ms","start":"2026-05-06T05:50:19.544062Z","end":"2026-05-06T05:50:20.077336Z","steps":["trace[964459363] 'read index received' (duration: 407.416059ms)","trace[964459363] 'applied index is now lower than readState.Index' (duration: 125.856397ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:20.077419Z","caller":"traceutil/trace.go:171","msg":"trace[1568348366] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"536.664798ms","start":"2026-05-06T05:50:19.540743Z","end":"2026-05-06T05:50:20.077408Z","steps":["trace[1568348366] 'process raft request' (duration: 410.724169ms)","trace[1568348366] 'compare' (duration: 125.128289ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:20.077478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:19.540719Z","time spent":"536.73021ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:20.077523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.44238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:20.077579Z","caller":"traceutil/trace.go:171","msg":"trace[1143311707] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24627; }","duration":"533.549153ms","start":"2026-05-06T05:50:19.544017Z","end":"2026-05-06T05:50:20.077567Z","steps":["trace[1143311707] 'agreement among raft nodes before linearized reading' (duration: 533.451921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:20.077606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:19.543999Z","time spent":"533.602065ms","remote":"127.0.0.1:56698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:50:20.327584Z","caller":"traceutil/trace.go:171","msg":"trace[818206768] linearizableReadLoop","detail":"{readStateIndex:25432; appliedIndex:25431; }","duration":"250.192788ms","start":"2026-05-06T05:50:20.077379Z","end":"2026-05-06T05:50:20.327571Z","steps":["trace[818206768] 'read index received' (duration: 169.874225ms)","trace[818206768] 'applied index is now lower than readState.Index' (duration: 80.317033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:20.327672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.00319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:20.327689Z","caller":"traceutil/trace.go:171","msg":"trace[353642219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24628; }","duration":"713.06254ms","start":"2026-05-06T05:50:19.614621Z","end":"2026-05-06T05:50:20.327683Z","steps":["trace[353642219] 'agreement among raft nodes before linearized reading' (duration: 713.003859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:20.327706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:19.614598Z","time spent":"713.103882ms","remote":"127.0.0.1:40786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:50:20.327856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.365669ms","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-05-06T05:50:20.327872Z","caller":"traceutil/trace.go:171","msg":"trace[1123044446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24628; }","duration":"464.40938ms","start":"2026-05-06T05:50:19.863459Z","end":"2026-05-06T05:50:20.327868Z","steps":["trace[1123044446] 'agreement among raft nodes before linearized reading' (duration: 464.341278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:20.327885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:19.863444Z","time spent":"464.437581ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T05:50:20.32797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.271953ms","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-05-06T05:50:20.327987Z","caller":"traceutil/trace.go:171","msg":"trace[1153170110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24628; }","duration":"584.317465ms","start":"2026-05-06T05:50:19.743665Z","end":"2026-05-06T05:50:20.327983Z","steps":["trace[1153170110] 'agreement among raft nodes before linearized reading' (duration: 584.275754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:20.327999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:19.743647Z","time spent":"584.349115ms","remote":"127.0.0.1:41054","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-05-06T05:50:20.327686Z","caller":"traceutil/trace.go:171","msg":"trace[1096832534] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"734.024145ms","start":"2026-05-06T05:50:19.593633Z","end":"2026-05-06T05:50:20.327657Z","steps":["trace[1096832534] 'process raft request' (duration: 653.59427ms)","trace[1096832534] 'compare' (duration: 80.057256ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:20.328251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:19.593611Z","time spent":"734.555067ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:20.393728Z","caller":"traceutil/trace.go:171","msg":"trace[1029411006] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"270.970938ms","start":"2026-05-06T05:50:20.122729Z","end":"2026-05-06T05:50:20.3937Z","steps":["trace[1029411006] 'process raft request' (duration: 270.844214ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:20.412747Z","caller":"traceutil/trace.go:171","msg":"trace[355943666] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"119.36067ms","start":"2026-05-06T05:50:20.293364Z","end":"2026-05-06T05:50:20.412724Z","steps":["trace[355943666] 'process raft request' (duration: 119.128645ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:20.413068Z","caller":"traceutil/trace.go:171","msg":"trace[885929877] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"115.784315ms","start":"2026-05-06T05:50:20.297267Z","end":"2026-05-06T05:50:20.413051Z","steps":["trace[885929877] 'process raft request' (duration: 115.649122ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:20.413167Z","caller":"traceutil/trace.go:171","msg":"trace[1498388764] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"115.869726ms","start":"2026-05-06T05:50:20.297276Z","end":"2026-05-06T05:50:20.413146Z","steps":["trace[1498388764] 'process raft request' (duration: 115.713243ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:20.413063Z","caller":"traceutil/trace.go:171","msg":"trace[533854013] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"116.721337ms","start":"2026-05-06T05:50:20.296308Z","end":"2026-05-06T05:50:20.413029Z","steps":["trace[533854013] 'process raft request' (duration: 116.372048ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:22.538243Z","caller":"traceutil/trace.go:171","msg":"trace[1248924046] linearizableReadLoop","detail":"{readStateIndex:25447; appliedIndex:25446; }","duration":"120.425016ms","start":"2026-05-06T05:50:22.417796Z","end":"2026-05-06T05:50:22.538221Z","steps":["trace[1248924046] 'read index received' (duration: 120.372065ms)","trace[1248924046] 'applied index is now lower than readState.Index' (duration: 52.031µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:22.538649Z","caller":"traceutil/trace.go:171","msg":"trace[1774218794] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"139.741591ms","start":"2026-05-06T05:50:22.398897Z","end":"2026-05-06T05:50:22.538639Z","steps":["trace[1774218794] 'process raft request' (duration: 139.184148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:22.538975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.159224ms","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-05-06T05:50:22.539007Z","caller":"traceutil/trace.go:171","msg":"trace[686619438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24643; }","duration":"121.241165ms","start":"2026-05-06T05:50:22.417758Z","end":"2026-05-06T05:50:22.538999Z","steps":["trace[686619438] 'agreement among raft nodes before linearized reading' (duration: 121.112912ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:22.746823Z","caller":"traceutil/trace.go:171","msg":"trace[674140098] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"328.907061ms","start":"2026-05-06T05:50:22.417892Z","end":"2026-05-06T05:50:22.746799Z","steps":["trace[674140098] 'process raft request' (duration: 328.683315ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:22.747001Z","caller":"traceutil/trace.go:171","msg":"trace[663255430] transaction","detail":"{read_only:false; response_revision:24645; number_of_response:1; }","duration":"328.994053ms","start":"2026-05-06T05:50:22.417969Z","end":"2026-05-06T05:50:22.746963Z","steps":["trace[663255430] 'process raft request' (duration: 328.755966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:22.747032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:22.417881Z","time spent":"329.065135ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:22.747104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:22.417959Z","time spent":"329.090516ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:22.747614Z","caller":"traceutil/trace.go:171","msg":"trace[946869371] transaction","detail":"{read_only:false; response_revision:24646; number_of_response:1; }","duration":"329.603978ms","start":"2026-05-06T05:50:22.417996Z","end":"2026-05-06T05:50:22.7476Z","steps":["trace[946869371] 'process raft request' (duration: 328.766637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:22.747695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:22.417983Z","time spent":"329.67375ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:22.747868Z","caller":"traceutil/trace.go:171","msg":"trace[1507726594] linearizableReadLoop","detail":"{readStateIndex:25451; appliedIndex:25447; }","duration":"209.54182ms","start":"2026-05-06T05:50:22.538299Z","end":"2026-05-06T05:50:22.74784Z","steps":["trace[1507726594] 'read index received' (duration: 208.098335ms)","trace[1507726594] 'applied index is now lower than readState.Index' (duration: 1.441555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:22.748005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.522612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:22.748019Z","caller":"traceutil/trace.go:171","msg":"trace[188723825] transaction","detail":"{read_only:false; response_revision:24647; number_of_response:1; }","duration":"328.520752ms","start":"2026-05-06T05:50:22.419475Z","end":"2026-05-06T05:50:22.747996Z","steps":["trace[188723825] 'process raft request' (duration: 328.076611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:22.74805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.956919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:50:22.748114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.105513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"warn","ts":"2026-05-06T05:50:22.748137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.194952ms","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-05-06T05:50:22.748151Z","caller":"traceutil/trace.go:171","msg":"trace[959729765] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24647; }","duration":"284.086563ms","start":"2026-05-06T05:50:22.464039Z","end":"2026-05-06T05:50:22.748125Z","steps":["trace[959729765] 'agreement among raft nodes before linearized reading' (duration: 283.902668ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:22.748108Z","caller":"traceutil/trace.go:171","msg":"trace[170570960] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24647; }","duration":"259.649445ms","start":"2026-05-06T05:50:22.488424Z","end":"2026-05-06T05:50:22.748074Z","steps":["trace[170570960] 'agreement among raft nodes before linearized reading' (duration: 259.473841ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:22.748186Z","caller":"traceutil/trace.go:171","msg":"trace[431157594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24647; }","duration":"330.281874ms","start":"2026-05-06T05:50:22.417893Z","end":"2026-05-06T05:50:22.748175Z","steps":["trace[431157594] 'agreement among raft nodes before linearized reading' (duration: 330.147821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:22.748197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:22.419456Z","time spent":"328.639844ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:22.748235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:22.417875Z","time spent":"330.351316ms","remote":"127.0.0.1:41054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T05:50:22.748177Z","caller":"traceutil/trace.go:171","msg":"trace[856535324] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24647; }","duration":"284.232646ms","start":"2026-05-06T05:50:22.463933Z","end":"2026-05-06T05:50:22.748166Z","steps":["trace[856535324] 'agreement among raft nodes before linearized reading' (duration: 284.032831ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:22.830021Z","caller":"traceutil/trace.go:171","msg":"trace[2067774915] transaction","detail":"{read_only:false; response_revision:24648; number_of_response:1; }","duration":"285.687831ms","start":"2026-05-06T05:50:22.544297Z","end":"2026-05-06T05:50:22.829985Z","steps":["trace[2067774915] 'process raft request' (duration: 285.492197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:22.830173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.308122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-06T05:50:22.830535Z","caller":"traceutil/trace.go:171","msg":"trace[829772188] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24648; }","duration":"169.728842ms","start":"2026-05-06T05:50:22.660789Z","end":"2026-05-06T05:50:22.830518Z","steps":["trace[829772188] 'agreement among raft nodes before linearized reading' (duration: 169.184969ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:22.968018Z","caller":"traceutil/trace.go:171","msg":"trace[1523312361] linearizableReadLoop","detail":"{readStateIndex:25453; appliedIndex:25452; }","duration":"138.090532ms","start":"2026-05-06T05:50:22.829906Z","end":"2026-05-06T05:50:22.967996Z","steps":["trace[1523312361] 'read index received' (duration: 68.387946ms)","trace[1523312361] 'applied index is now lower than readState.Index' (duration: 69.701855ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:22.968076Z","caller":"traceutil/trace.go:171","msg":"trace[561195930] transaction","detail":"{read_only:false; response_revision:24649; number_of_response:1; }","duration":"214.53996ms","start":"2026-05-06T05:50:22.753501Z","end":"2026-05-06T05:50:22.968041Z","steps":["trace[561195930] 'process raft request' (duration: 144.773263ms)","trace[561195930] 'compare' (duration: 69.617243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:22.968207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.288143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-06T05:50:22.968228Z","caller":"traceutil/trace.go:171","msg":"trace[1844929073] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24649; }","duration":"211.353554ms","start":"2026-05-06T05:50:22.756869Z","end":"2026-05-06T05:50:22.968222Z","steps":["trace[1844929073] 'agreement among raft nodes before linearized reading' (duration: 211.22355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:22.969178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.650693ms","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-05-06T05:50:22.969244Z","caller":"traceutil/trace.go:171","msg":"trace[839922802] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"133.920192ms","start":"2026-05-06T05:50:22.835308Z","end":"2026-05-06T05:50:22.969228Z","steps":["trace[839922802] 'process raft request' (duration: 133.522532ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:22.969252Z","caller":"traceutil/trace.go:171","msg":"trace[190812390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24651; }","duration":"103.771916ms","start":"2026-05-06T05:50:22.865463Z","end":"2026-05-06T05:50:22.969235Z","steps":["trace[190812390] 'agreement among raft nodes before linearized reading' (duration: 103.53579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:22.969316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.640363ms","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-05-06T05:50:22.969398Z","caller":"traceutil/trace.go:171","msg":"trace[291805235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24651; }","duration":"103.808297ms","start":"2026-05-06T05:50:22.865573Z","end":"2026-05-06T05:50:22.969381Z","steps":["trace[291805235] 'agreement among raft nodes before linearized reading' (duration: 103.605372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:23.198578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.191901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:50:23.198729Z","caller":"traceutil/trace.go:171","msg":"trace[1832088326] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"223.758632ms","start":"2026-05-06T05:50:22.974931Z","end":"2026-05-06T05:50:23.19869Z","steps":["trace[1832088326] 'process raft request' (duration: 103.376756ms)","trace[1832088326] 'compare' (duration: 120.066858ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:52:46.760479Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006917.wal"} {"level":"info","ts":"2026-05-06T05:53:29.980082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23530} {"level":"info","ts":"2026-05-06T05:53:30.113458Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23530,"took":"128.006088ms","hash":3767097513,"current-db-size-bytes":61067264,"current-db-size":"61 MB","current-db-size-in-use-bytes":37445632,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-06T05:53:30.11353Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3767097513,"revision":23530,"compact-revision":20335} {"level":"info","ts":"2026-05-06T05:58:29.987271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26538} {"level":"info","ts":"2026-05-06T05:58:30.144809Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26538,"took":"154.830545ms","hash":651172649,"current-db-size-bytes":61067264,"current-db-size":"61 MB","current-db-size-in-use-bytes":31408128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-06T05:58:30.144865Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":651172649,"revision":26538,"compact-revision":23530} {"level":"info","ts":"2026-05-06T05:58:55.422384Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"cb257ce137899862","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T05:58:55.427434Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-06T05:58:55.427576Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-06T06:01:13.300341Z","caller":"traceutil/trace.go:171","msg":"trace[889915230] transaction","detail":"{read_only:false; response_revision:30174; number_of_response:1; }","duration":"202.674065ms","start":"2026-05-06T06:01:13.097642Z","end":"2026-05-06T06:01:13.300316Z","steps":["trace[889915230] 'process raft request' (duration: 202.491581ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T06:01:13.303046Z","caller":"traceutil/trace.go:171","msg":"trace[364512130] linearizableReadLoop","detail":"{readStateIndex:31131; appliedIndex:31127; }","duration":"155.661855ms","start":"2026-05-06T06:01:13.147367Z","end":"2026-05-06T06:01:13.303029Z","steps":["trace[364512130] 'read index received' (duration: 152.742655ms)","trace[364512130] 'applied index is now lower than readState.Index' (duration: 2.91736ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T06:01:13.303154Z","caller":"traceutil/trace.go:171","msg":"trace[330042695] transaction","detail":"{read_only:false; response_revision:30177; number_of_response:1; }","duration":"158.253317ms","start":"2026-05-06T06:01:13.144876Z","end":"2026-05-06T06:01:13.30313Z","steps":["trace[330042695] 'process raft request' (duration: 158.113564ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T06:01:13.303148Z","caller":"traceutil/trace.go:171","msg":"trace[1289426858] transaction","detail":"{read_only:false; response_revision:30176; number_of_response:1; }","duration":"203.67514ms","start":"2026-05-06T06:01:13.099447Z","end":"2026-05-06T06:01:13.303123Z","steps":["trace[1289426858] 'process raft request' (duration: 203.503816ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T06:01:13.303146Z","caller":"traceutil/trace.go:171","msg":"trace[1790577516] transaction","detail":"{read_only:false; response_revision:30175; number_of_response:1; }","duration":"204.011038ms","start":"2026-05-06T06:01:13.0991Z","end":"2026-05-06T06:01:13.303111Z","steps":["trace[1790577516] 'process raft request' (duration: 203.757502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T06:01:13.30335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.825672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T06:01:13.303385Z","caller":"traceutil/trace.go:171","msg":"trace[1706652656] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:30177; }","duration":"109.899273ms","start":"2026-05-06T06:01:13.193476Z","end":"2026-05-06T06:01:13.303375Z","steps":["trace[1706652656] 'agreement among raft nodes before linearized reading' (duration: 109.820521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T06:01:13.303353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.495302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-06T06:01:13.303492Z","caller":"traceutil/trace.go:171","msg":"trace[355782016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:30177; }","duration":"133.678976ms","start":"2026-05-06T06:01:13.169793Z","end":"2026-05-06T06:01:13.303472Z","steps":["trace[355782016] 'agreement among raft nodes before linearized reading' (duration: 133.370279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T06:01:13.303772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.406002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.139\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-06T06:01:13.303869Z","caller":"traceutil/trace.go:171","msg":"trace[616881432] range","detail":"{range_begin:/registry/masterleases/199.19.213.139; range_end:; response_count:1; response_revision:30177; }","duration":"156.529175ms","start":"2026-05-06T06:01:13.147333Z","end":"2026-05-06T06:01:13.303862Z","steps":["trace[616881432] 'agreement among raft nodes before linearized reading' (duration: 156.166727ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T06:03:29.994422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28888} {"level":"info","ts":"2026-05-06T06:03:30.121742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28888,"took":"121.697698ms","hash":4233392588,"current-db-size-bytes":61067264,"current-db-size":"61 MB","current-db-size-in-use-bytes":31019008,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-06T06:03:30.121795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4233392588,"revision":28888,"compact-revision":26538} {"level":"info","ts":"2026-05-06T06:07:30.282765Z","caller":"traceutil/trace.go:171","msg":"trace[935826901] transaction","detail":"{read_only:false; response_revision:33101; number_of_response:1; }","duration":"166.824602ms","start":"2026-05-06T06:07:30.115911Z","end":"2026-05-06T06:07:30.282736Z","steps":["trace[935826901] 'process raft request' (duration: 166.660939ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T06:07:31.357971Z","caller":"traceutil/trace.go:171","msg":"trace[764344636] transaction","detail":"{read_only:false; response_revision:33108; number_of_response:1; }","duration":"112.943556ms","start":"2026-05-06T06:07:31.245Z","end":"2026-05-06T06:07:31.357943Z","steps":["trace[764344636] 'process raft request' (duration: 112.772362ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T06:08:30.000261Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31181} {"level":"info","ts":"2026-05-06T06:08:30.123874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31181,"took":"118.805179ms","hash":4129656683,"current-db-size-bytes":61067264,"current-db-size":"61 MB","current-db-size-in-use-bytes":31535104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-06T06:08:30.123923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4129656683,"revision":31181,"compact-revision":28888} {"level":"info","ts":"2026-05-06T06:13:30.009196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33537} {"level":"info","ts":"2026-05-06T06:13:30.155976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33537,"took":"142.000386ms","hash":3841023160,"current-db-size-bytes":62914560,"current-db-size":"63 MB","current-db-size-in-use-bytes":32559104,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-06T06:13:30.156053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3841023160,"revision":33537,"compact-revision":31181} {"level":"info","ts":"2026-05-06T06:18:30.01549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35775} {"level":"info","ts":"2026-05-06T06:18:30.164633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35775,"took":"144.781412ms","hash":1384079513,"current-db-size-bytes":63377408,"current-db-size":"63 MB","current-db-size-in-use-bytes":30142464,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-06T06:18:30.164717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1384079513,"revision":35775,"compact-revision":33537}