{"level":"warn","ts":"2026-04-08T06:16:54.088622Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T06:16:54.088729Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-08T06:16:54.088807Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T06:16:54.088824Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-04-08T06:16:54.088852Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T06:16:54.089732Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-04-08T06:16:54.08983Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-08T06:16:54.095922Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.833968ms"} {"level":"info","ts":"2026-04-08T06:16:54.103765Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-04-08T06:16:54.103831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T06:16:54.103851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-04-08T06:16:54.103865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T06:16:54.10388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-04-08T06:16:54.10392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-04-08T06:16:54.112318Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T06:16:54.114884Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T06:16:54.116132Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-08T06:16:54.118809Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T06:16:54.119685Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T06:16:54.120512Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T06:16:54.120619Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T06:16:54.120643Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T06:16:54.121542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-04-08T06:16:54.121684Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-04-08T06:16:54.122539Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T06:16:54.12273Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T06:16:54.122758Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T06:16:54.122857Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-04-08T06:16:54.122877Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-04-08T06:16:55.104798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T06:16:55.104905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T06:16:55.104945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-04-08T06:16:55.104965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-04-08T06:16:55.104976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-04-08T06:16:55.104992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-04-08T06:16:55.105007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-04-08T06:16:55.113771Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:16:55.114251Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T06:16:55.114274Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T06:16:55.114272Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T06:16:55.114703Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T06:16:55.114732Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T06:16:55.118173Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-04-08T06:16:55.119631Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T06:16:55.129617Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:16:55.129851Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:16:55.129958Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T06:16:58.093498Z","caller":"traceutil/trace.go:171","msg":"trace[1750987339] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"157.555802ms","start":"2026-04-08T06:16:57.935882Z","end":"2026-04-08T06:16:58.093438Z","steps":["trace[1750987339] 'process raft request' (duration: 68.26503ms)","trace[1750987339] 'compare' (duration: 88.998756ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:16:58.09345Z","caller":"traceutil/trace.go:171","msg":"trace[1921867724] linearizableReadLoop","detail":"{readStateIndex:166; appliedIndex:165; }","duration":"106.039281ms","start":"2026-04-08T06:16:57.98737Z","end":"2026-04-08T06:16:58.093409Z","steps":["trace[1921867724] 'read index received' (duration: 16.783069ms)","trace[1921867724] 'applied index is now lower than readState.Index' (duration: 89.254711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:16:58.093794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.365117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:16:58.09393Z","caller":"traceutil/trace.go:171","msg":"trace[696880396] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:0; response_revision:161; }","duration":"106.566301ms","start":"2026-04-08T06:16:57.987339Z","end":"2026-04-08T06:16:58.093906Z","steps":["trace[696880396] 'agreement among raft nodes before linearized reading' (duration: 106.259055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:19.188842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.933474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T06:17:19.188923Z","caller":"traceutil/trace.go:171","msg":"trace[1696245849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:463; }","duration":"145.068068ms","start":"2026-04-08T06:17:19.043838Z","end":"2026-04-08T06:17:19.188906Z","steps":["trace[1696245849] 'range keys from in-memory index tree' (duration: 144.79023ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:19.311419Z","caller":"traceutil/trace.go:171","msg":"trace[771597141] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"118.959464ms","start":"2026-04-08T06:17:19.192423Z","end":"2026-04-08T06:17:19.311383Z","steps":["trace[771597141] 'process raft request' (duration: 118.716728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:23.316899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.830728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:17:23.317091Z","caller":"traceutil/trace.go:171","msg":"trace[1455327251] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"230.72859ms","start":"2026-04-08T06:17:23.086346Z","end":"2026-04-08T06:17:23.317074Z","steps":["trace[1455327251] 'read index received' (duration: 81.996731ms)","trace[1455327251] 'applied index is now lower than readState.Index' (duration: 148.730609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:17:23.317231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.896824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-08T06:17:23.317297Z","caller":"traceutil/trace.go:171","msg":"trace[1570463810] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:504; }","duration":"231.007056ms","start":"2026-04-08T06:17:23.086278Z","end":"2026-04-08T06:17:23.317285Z","steps":["trace[1570463810] 'agreement among raft nodes before linearized reading' (duration: 230.906834ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:23.317234Z","caller":"traceutil/trace.go:171","msg":"trace[1823672575] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"300.36275ms","start":"2026-04-08T06:17:23.016785Z","end":"2026-04-08T06:17:23.317147Z","steps":["trace[1823672575] 'process raft request' (duration: 151.768565ms)","trace[1823672575] 'compare' (duration: 147.463841ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:17:23.317547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:17:23.016742Z","time spent":"300.667427ms","remote":"127.0.0.1:39946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:17:23.317797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.129369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-08T06:17:23.317883Z","caller":"traceutil/trace.go:171","msg":"trace[222155233] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:504; }","duration":"231.388085ms","start":"2026-04-08T06:17:23.086473Z","end":"2026-04-08T06:17:23.317861Z","steps":["trace[222155233] 'agreement among raft nodes before linearized reading' (duration: 231.016427ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.013969Z","caller":"traceutil/trace.go:171","msg":"trace[2065725017] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"263.137593ms","start":"2026-04-08T06:17:24.7508Z","end":"2026-04-08T06:17:25.013937Z","steps":["trace[2065725017] 'process raft request' (duration: 262.981599ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.058852Z","caller":"traceutil/trace.go:171","msg":"trace[743749655] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"221.295913ms","start":"2026-04-08T06:17:24.837522Z","end":"2026-04-08T06:17:25.058818Z","steps":["trace[743749655] 'process raft request' (duration: 221.078178ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.058899Z","caller":"traceutil/trace.go:171","msg":"trace[1821699024] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"213.015602ms","start":"2026-04-08T06:17:24.845839Z","end":"2026-04-08T06:17:25.058855Z","steps":["trace[1821699024] 'process raft request' (duration: 212.880969ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.058947Z","caller":"traceutil/trace.go:171","msg":"trace[1911444098] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"205.859654ms","start":"2026-04-08T06:17:24.853076Z","end":"2026-04-08T06:17:25.058936Z","steps":["trace[1911444098] 'process raft request' (duration: 205.712151ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.058944Z","caller":"traceutil/trace.go:171","msg":"trace[380770957] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"213.010571ms","start":"2026-04-08T06:17:24.845901Z","end":"2026-04-08T06:17:25.058912Z","steps":["trace[380770957] 'process raft request' (duration: 212.857418ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.059018Z","caller":"traceutil/trace.go:171","msg":"trace[754688976] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"205.333873ms","start":"2026-04-08T06:17:24.853674Z","end":"2026-04-08T06:17:25.059008Z","steps":["trace[754688976] 'process raft request' (duration: 205.158339ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.059031Z","caller":"traceutil/trace.go:171","msg":"trace[1675045824] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"204.108235ms","start":"2026-04-08T06:17:24.854914Z","end":"2026-04-08T06:17:25.059023Z","steps":["trace[1675045824] 'process raft request' (duration: 203.995493ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.059076Z","caller":"traceutil/trace.go:171","msg":"trace[598514169] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"202.591293ms","start":"2026-04-08T06:17:24.856476Z","end":"2026-04-08T06:17:25.059067Z","steps":["trace[598514169] 'process raft request' (duration: 202.469971ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.059106Z","caller":"traceutil/trace.go:171","msg":"trace[1055323722] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"188.961193ms","start":"2026-04-08T06:17:24.870131Z","end":"2026-04-08T06:17:25.059092Z","steps":["trace[1055323722] 'process raft request' (duration: 188.862261ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.059096Z","caller":"traceutil/trace.go:171","msg":"trace[97606167] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"204.861762ms","start":"2026-04-08T06:17:24.854161Z","end":"2026-04-08T06:17:25.059022Z","steps":["trace[97606167] 'process raft request' (duration: 204.710219ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.059215Z","caller":"traceutil/trace.go:171","msg":"trace[1136639204] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"176.09522ms","start":"2026-04-08T06:17:24.883076Z","end":"2026-04-08T06:17:25.059171Z","steps":["trace[1136639204] 'process raft request' (duration: 175.970788ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:25.059299Z","caller":"traceutil/trace.go:171","msg":"trace[1731128828] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"164.031855ms","start":"2026-04-08T06:17:24.895257Z","end":"2026-04-08T06:17:25.059289Z","steps":["trace[1731128828] 'process raft request' (duration: 163.844401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:25.943702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.796404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:17:25.944173Z","caller":"traceutil/trace.go:171","msg":"trace[783903601] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"214.243325ms","start":"2026-04-08T06:17:25.729898Z","end":"2026-04-08T06:17:25.944141Z","steps":["trace[783903601] 'process raft request' (duration: 72.908938ms)","trace[783903601] 'compare' (duration: 140.61368ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:17:25.945025Z","caller":"traceutil/trace.go:171","msg":"trace[1143016171] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"208.028614ms","start":"2026-04-08T06:17:25.736985Z","end":"2026-04-08T06:17:25.945014Z","steps":["trace[1143016171] 'process raft request' (duration: 207.569814ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:26.172286Z","caller":"traceutil/trace.go:171","msg":"trace[689302757] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"115.835853ms","start":"2026-04-08T06:17:26.056424Z","end":"2026-04-08T06:17:26.17226Z","steps":["trace[689302757] 'process raft request' (duration: 15.971629ms)","trace[689302757] 'compare' (duration: 99.750491ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:17:26.174748Z","caller":"traceutil/trace.go:171","msg":"trace[1104520291] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"114.770189ms","start":"2026-04-08T06:17:26.059963Z","end":"2026-04-08T06:17:26.174733Z","steps":["trace[1104520291] 'process raft request' (duration: 114.694857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:26.405563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.2876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:17:26.405782Z","caller":"traceutil/trace.go:171","msg":"trace[855574374] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"173.58239ms","start":"2026-04-08T06:17:26.232163Z","end":"2026-04-08T06:17:26.405745Z","steps":["trace[855574374] 'process raft request' (duration: 65.815779ms)","trace[855574374] 'compare' (duration: 107.184488ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:17:29.573221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.853973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:17:29.573282Z","caller":"traceutil/trace.go:171","msg":"trace[1705041647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"127.968336ms","start":"2026-04-08T06:17:29.4453Z","end":"2026-04-08T06:17:29.573268Z","steps":["trace[1705041647] 'range keys from in-memory index tree' (duration: 127.696649ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:31.349386Z","caller":"traceutil/trace.go:171","msg":"trace[733103979] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"308.014311ms","start":"2026-04-08T06:17:31.04134Z","end":"2026-04-08T06:17:31.349354Z","steps":["trace[733103979] 'process raft request' (duration: 307.829837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:31.349589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:17:31.041325Z","time spent":"308.189695ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:17:31.716293Z","caller":"traceutil/trace.go:171","msg":"trace[1065510842] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"485.423397ms","start":"2026-04-08T06:17:31.230841Z","end":"2026-04-08T06:17:31.716264Z","steps":["trace[1065510842] 'read index received' (duration: 118.285968ms)","trace[1065510842] 'applied index is now lower than readState.Index' (duration: 367.136489ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:17:31.716334Z","caller":"traceutil/trace.go:171","msg":"trace[997757830] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"507.468512ms","start":"2026-04-08T06:17:31.208844Z","end":"2026-04-08T06:17:31.716313Z","steps":["trace[997757830] 'process raft request' (duration: 506.48573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:31.716523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:17:31.208815Z","time spent":"507.605865ms","remote":"127.0.0.1:39946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:17:31.71656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.693312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:17:31.716602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.763008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2tjhv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-08T06:17:31.716648Z","caller":"traceutil/trace.go:171","msg":"trace[1385400077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"485.837716ms","start":"2026-04-08T06:17:31.230789Z","end":"2026-04-08T06:17:31.716626Z","steps":["trace[1385400077] 'agreement among raft nodes before linearized reading' (duration: 485.670522ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:31.716664Z","caller":"traceutil/trace.go:171","msg":"trace[1939434108] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2tjhv; range_end:; response_count:1; response_revision:618; }","duration":"272.85409ms","start":"2026-04-08T06:17:31.443794Z","end":"2026-04-08T06:17:31.716648Z","steps":["trace[1939434108] 'agreement among raft nodes before linearized reading' (duration: 272.748488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:31.716696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:17:31.23077Z","time spent":"485.918207ms","remote":"127.0.0.1:39838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:17:31.71676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.608892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:17:31.71684Z","caller":"traceutil/trace.go:171","msg":"trace[151970623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"125.749415ms","start":"2026-04-08T06:17:31.591071Z","end":"2026-04-08T06:17:31.716821Z","steps":["trace[151970623] 'agreement among raft nodes before linearized reading' (duration: 125.50881ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:31.986763Z","caller":"traceutil/trace.go:171","msg":"trace[1702482760] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"251.394299ms","start":"2026-04-08T06:17:31.735339Z","end":"2026-04-08T06:17:31.986733Z","steps":["trace[1702482760] 'process raft request' (duration: 245.512887ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:32.008229Z","caller":"traceutil/trace.go:171","msg":"trace[873373863] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"234.751524ms","start":"2026-04-08T06:17:31.773443Z","end":"2026-04-08T06:17:32.008195Z","steps":["trace[873373863] 'process raft request' (duration: 234.54487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:33.047583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208185485474684185,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T06:17:33.121141Z","caller":"traceutil/trace.go:171","msg":"trace[46440312] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"574.446388ms","start":"2026-04-08T06:17:32.546671Z","end":"2026-04-08T06:17:33.121117Z","steps":["trace[46440312] 'read index received' (duration: 573.555147ms)","trace[46440312] 'applied index is now lower than readState.Index' (duration: 890.411µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:17:33.121216Z","caller":"traceutil/trace.go:171","msg":"trace[660322273] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"654.457976ms","start":"2026-04-08T06:17:32.466728Z","end":"2026-04-08T06:17:33.121186Z","steps":["trace[660322273] 'process raft request' (duration: 653.77214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:33.121342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.655752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T06:17:33.12143Z","caller":"traceutil/trace.go:171","msg":"trace[395056617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"574.797446ms","start":"2026-04-08T06:17:32.546617Z","end":"2026-04-08T06:17:33.121414Z","steps":["trace[395056617] 'agreement among raft nodes before linearized reading' (duration: 574.587151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:33.121354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:17:32.466695Z","time spent":"654.581989ms","remote":"127.0.0.1:39946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:17:33.121535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:17:32.54659Z","time spent":"574.935369ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T06:17:34.326564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.511717ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-08T06:17:36.042694Z","caller":"traceutil/trace.go:171","msg":"trace[1518387889] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"195.709658ms","start":"2026-04-08T06:17:35.846961Z","end":"2026-04-08T06:17:36.042671Z","steps":["trace[1518387889] 'read index received' (duration: 195.472762ms)","trace[1518387889] 'applied index is now lower than readState.Index' (duration: 235.506µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:17:36.042927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.916762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:17:36.04295Z","caller":"traceutil/trace.go:171","msg":"trace[466240110] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"259.571663ms","start":"2026-04-08T06:17:35.783293Z","end":"2026-04-08T06:17:36.042865Z","steps":["trace[466240110] 'process raft request' (duration: 259.203324ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:36.042991Z","caller":"traceutil/trace.go:171","msg":"trace[831473258] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:644; }","duration":"196.045425ms","start":"2026-04-08T06:17:35.846919Z","end":"2026-04-08T06:17:36.042965Z","steps":["trace[831473258] 'agreement among raft nodes before linearized reading' (duration: 195.897672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:36.04306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.245695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:17:36.043117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.200896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:17:36.043183Z","caller":"traceutil/trace.go:171","msg":"trace[1248879857] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:644; }","duration":"118.33245ms","start":"2026-04-08T06:17:35.924828Z","end":"2026-04-08T06:17:36.04316Z","steps":["trace[1248879857] 'agreement among raft nodes before linearized reading' (duration: 118.261038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:36.043281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.565296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:17:36.043322Z","caller":"traceutil/trace.go:171","msg":"trace[1784096755] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:644; }","duration":"135.620678ms","start":"2026-04-08T06:17:35.907693Z","end":"2026-04-08T06:17:36.043313Z","steps":["trace[1784096755] 'agreement among raft nodes before linearized reading' (duration: 135.566767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:36.043391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.932871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:17:36.043124Z","caller":"traceutil/trace.go:171","msg":"trace[1873772440] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:644; }","duration":"117.339986ms","start":"2026-04-08T06:17:35.925767Z","end":"2026-04-08T06:17:36.043107Z","steps":["trace[1873772440] 'agreement among raft nodes before linearized reading' (duration: 117.213644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:36.043027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.82798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:17:36.043488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.555625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:17:36.044379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.839631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:17:36.044438Z","caller":"traceutil/trace.go:171","msg":"trace[81966253] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:644; }","duration":"164.525976ms","start":"2026-04-08T06:17:35.879891Z","end":"2026-04-08T06:17:36.044416Z","steps":["trace[81966253] 'agreement among raft nodes before linearized reading' (duration: 163.545085ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:36.044477Z","caller":"traceutil/trace.go:171","msg":"trace[1764154111] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:644; }","duration":"164.883735ms","start":"2026-04-08T06:17:35.879567Z","end":"2026-04-08T06:17:36.044451Z","steps":["trace[1764154111] 'agreement among raft nodes before linearized reading' (duration: 163.72703ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:36.04344Z","caller":"traceutil/trace.go:171","msg":"trace[2031665106] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:644; }","duration":"135.015163ms","start":"2026-04-08T06:17:35.908406Z","end":"2026-04-08T06:17:36.043421Z","steps":["trace[2031665106] 'agreement among raft nodes before linearized reading' (duration: 134.951782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:36.043438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.565537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:17:36.044936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.996754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:17:36.045494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.5642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:17:36.045573Z","caller":"traceutil/trace.go:171","msg":"trace[2116520617] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:644; }","duration":"120.680422ms","start":"2026-04-08T06:17:35.924846Z","end":"2026-04-08T06:17:36.045526Z","steps":["trace[2116520617] 'agreement among raft nodes before linearized reading' (duration: 118.608655ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:36.0456Z","caller":"traceutil/trace.go:171","msg":"trace[457703539] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:644; }","duration":"198.067721ms","start":"2026-04-08T06:17:35.847393Z","end":"2026-04-08T06:17:36.045461Z","steps":["trace[457703539] 'agreement among raft nodes before linearized reading' (duration: 195.920792ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:36.045405Z","caller":"traceutil/trace.go:171","msg":"trace[1713932106] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:644; }","duration":"137.521759ms","start":"2026-04-08T06:17:35.90784Z","end":"2026-04-08T06:17:36.045362Z","steps":["trace[1713932106] 'agreement among raft nodes before linearized reading' (duration: 135.574486ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:36.046127Z","caller":"traceutil/trace.go:171","msg":"trace[659579234] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:644; }","duration":"198.679105ms","start":"2026-04-08T06:17:35.847143Z","end":"2026-04-08T06:17:36.045822Z","steps":["trace[659579234] 'agreement among raft nodes before linearized reading' (duration: 195.79549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:49.916631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.388115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kjb5z\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-08T06:17:49.916731Z","caller":"traceutil/trace.go:171","msg":"trace[1402741926] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kjb5z; range_end:; response_count:1; response_revision:785; }","duration":"347.518318ms","start":"2026-04-08T06:17:49.569193Z","end":"2026-04-08T06:17:49.916712Z","steps":["trace[1402741926] 'range keys from in-memory index tree' (duration: 347.244812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:49.916771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:17:49.569178Z","time spent":"347.58476ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kjb5z\" "} {"level":"warn","ts":"2026-04-08T06:17:49.916851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.115552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T06:17:49.916652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.488248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-08T06:17:49.916974Z","caller":"traceutil/trace.go:171","msg":"trace[1500914505] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"675.852536ms","start":"2026-04-08T06:17:49.241089Z","end":"2026-04-08T06:17:49.916942Z","steps":["trace[1500914505] 'range keys from in-memory index tree' (duration: 675.300453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:49.917077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:17:49.241062Z","time spent":"675.980868ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T06:17:49.916906Z","caller":"traceutil/trace.go:171","msg":"trace[1835426387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"425.206494ms","start":"2026-04-08T06:17:49.491682Z","end":"2026-04-08T06:17:49.916888Z","steps":["trace[1835426387] 'range keys from in-memory index tree' (duration: 424.992709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:17:49.917166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:17:49.491656Z","time spent":"425.46436ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T06:17:58.827644Z","caller":"traceutil/trace.go:171","msg":"trace[2053331929] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"106.208717ms","start":"2026-04-08T06:17:58.72141Z","end":"2026-04-08T06:17:58.827619Z","steps":["trace[2053331929] 'process raft request' (duration: 37.721218ms)","trace[2053331929] 'compare' (duration: 68.387146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:17:58.827745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.143324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T06:17:58.827912Z","caller":"traceutil/trace.go:171","msg":"trace[275981232] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:868; }","duration":"134.359539ms","start":"2026-04-08T06:17:58.693511Z","end":"2026-04-08T06:17:58.827871Z","steps":["trace[275981232] 'range keys from in-memory index tree' (duration: 133.856128ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:58.997261Z","caller":"traceutil/trace.go:171","msg":"trace[187993202] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"241.004665ms","start":"2026-04-08T06:17:58.756233Z","end":"2026-04-08T06:17:58.997238Z","steps":["trace[187993202] 'process raft request' (duration: 240.841632ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:59.039189Z","caller":"traceutil/trace.go:171","msg":"trace[1393242560] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:902; }","duration":"177.150371ms","start":"2026-04-08T06:17:58.86201Z","end":"2026-04-08T06:17:59.03916Z","steps":["trace[1393242560] 'read index received' (duration: 135.033815ms)","trace[1393242560] 'applied index is now lower than readState.Index' (duration: 42.115016ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:17:59.039436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.382836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T06:17:59.039475Z","caller":"traceutil/trace.go:171","msg":"trace[709962353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:870; }","duration":"177.502369ms","start":"2026-04-08T06:17:58.861961Z","end":"2026-04-08T06:17:59.039464Z","steps":["trace[709962353] 'agreement among raft nodes before linearized reading' (duration: 177.297394ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:59.8136Z","caller":"traceutil/trace.go:171","msg":"trace[1747770122] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"104.411775ms","start":"2026-04-08T06:17:59.709156Z","end":"2026-04-08T06:17:59.813568Z","steps":["trace[1747770122] 'process raft request' (duration: 30.28788ms)","trace[1747770122] 'compare' (duration: 73.922731ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:17:59.821133Z","caller":"traceutil/trace.go:171","msg":"trace[1044798649] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"111.903544ms","start":"2026-04-08T06:17:59.709202Z","end":"2026-04-08T06:17:59.821106Z","steps":["trace[1044798649] 'process raft request' (duration: 111.625448ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:17:59.821264Z","caller":"traceutil/trace.go:171","msg":"trace[1824183635] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"107.339333ms","start":"2026-04-08T06:17:59.713899Z","end":"2026-04-08T06:17:59.821239Z","steps":["trace[1824183635] 'process raft request' (duration: 107.083167ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:18:11.038226Z","caller":"traceutil/trace.go:171","msg":"trace[283285649] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"120.255003ms","start":"2026-04-08T06:18:10.917932Z","end":"2026-04-08T06:18:11.038187Z","steps":["trace[283285649] 'process raft request' (duration: 61.255757ms)","trace[283285649] 'compare' (duration: 58.192998ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:18:11.040062Z","caller":"traceutil/trace.go:171","msg":"trace[368039314] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"121.991181ms","start":"2026-04-08T06:18:10.918049Z","end":"2026-04-08T06:18:11.04004Z","steps":["trace[368039314] 'process raft request' (duration: 121.893919ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:18:13.214445Z","caller":"traceutil/trace.go:171","msg":"trace[2088315983] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"431.125747ms","start":"2026-04-08T06:18:12.783295Z","end":"2026-04-08T06:18:13.214421Z","steps":["trace[2088315983] 'process raft request' (duration: 430.966873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:18:13.214602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:18:12.783267Z","time spent":"431.25029ms","remote":"127.0.0.1:40132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1602,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:18:13.396545Z","caller":"traceutil/trace.go:171","msg":"trace[62319826] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"612.936273ms","start":"2026-04-08T06:18:12.783581Z","end":"2026-04-08T06:18:13.396517Z","steps":["trace[62319826] 'process raft request' (duration: 612.713728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:18:13.396726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:18:12.783568Z","time spent":"613.059335ms","remote":"127.0.0.1:40030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:18:13.396878Z","caller":"traceutil/trace.go:171","msg":"trace[718508783] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"612.392601ms","start":"2026-04-08T06:18:12.784462Z","end":"2026-04-08T06:18:13.396854Z","steps":["trace[718508783] 'process raft request' (duration: 611.994772ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:18:13.396997Z","caller":"traceutil/trace.go:171","msg":"trace[800851836] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1187; }","duration":"223.37587ms","start":"2026-04-08T06:18:13.173608Z","end":"2026-04-08T06:18:13.396984Z","steps":["trace[800851836] 'read index received' (duration: 40.628274ms)","trace[800851836] 'applied index is now lower than readState.Index' (duration: 182.746426ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:18:13.397101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:18:12.784452Z","time spent":"612.537844ms","remote":"127.0.0.1:40340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4503,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:18:13.397092Z","caller":"traceutil/trace.go:171","msg":"trace[429075238] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"512.656719ms","start":"2026-04-08T06:18:12.884394Z","end":"2026-04-08T06:18:13.397051Z","steps":["trace[429075238] 'process raft request' (duration: 512.320062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:18:13.39718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.647316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T06:18:13.39724Z","caller":"traceutil/trace.go:171","msg":"trace[1701017889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1154; }","duration":"223.782718ms","start":"2026-04-08T06:18:13.173443Z","end":"2026-04-08T06:18:13.397226Z","steps":["trace[1701017889] 'agreement among raft nodes before linearized reading' (duration: 223.594394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:18:13.397269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:18:12.884367Z","time spent":"512.819522ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:18:20.314854Z","caller":"traceutil/trace.go:171","msg":"trace[1752040238] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"136.958047ms","start":"2026-04-08T06:18:20.177872Z","end":"2026-04-08T06:18:20.31483Z","steps":["trace[1752040238] 'read index received' (duration: 136.806274ms)","trace[1752040238] 'applied index is now lower than readState.Index' (duration: 150.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:18:20.314942Z","caller":"traceutil/trace.go:171","msg":"trace[1932524527] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"156.26441ms","start":"2026-04-08T06:18:20.158639Z","end":"2026-04-08T06:18:20.314904Z","steps":["trace[1932524527] 'process raft request' (duration: 156.012614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:18:20.315116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.207032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T06:18:20.315209Z","caller":"traceutil/trace.go:171","msg":"trace[1230707336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1187; }","duration":"137.343086ms","start":"2026-04-08T06:18:20.177844Z","end":"2026-04-08T06:18:20.315187Z","steps":["trace[1230707336] 'agreement among raft nodes before linearized reading' (duration: 137.07202ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:18:27.165129Z","caller":"traceutil/trace.go:171","msg":"trace[252170719] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"106.39509ms","start":"2026-04-08T06:18:27.058706Z","end":"2026-04-08T06:18:27.165101Z","steps":["trace[252170719] 'process raft request' (duration: 106.234217ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:18:29.71312Z","caller":"traceutil/trace.go:171","msg":"trace[628184852] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"165.269603ms","start":"2026-04-08T06:18:29.547831Z","end":"2026-04-08T06:18:29.713101Z","steps":["trace[628184852] 'process raft request' (duration: 165.152841ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:18:29.718648Z","caller":"traceutil/trace.go:171","msg":"trace[528381627] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"164.986277ms","start":"2026-04-08T06:18:29.553636Z","end":"2026-04-08T06:18:29.718622Z","steps":["trace[528381627] 'process raft request' (duration: 164.837784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:18:29.937389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.47149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T06:18:29.937479Z","caller":"traceutil/trace.go:171","msg":"trace[750459756] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1275; }","duration":"105.631473ms","start":"2026-04-08T06:18:29.831819Z","end":"2026-04-08T06:18:29.93745Z","steps":["trace[750459756] 'count revisions from in-memory index tree' (duration: 105.332286ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:18:33.980194Z","caller":"traceutil/trace.go:171","msg":"trace[801581325] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"213.174839ms","start":"2026-04-08T06:18:33.766999Z","end":"2026-04-08T06:18:33.980174Z","steps":["trace[801581325] 'read index received' (duration: 210.759076ms)","trace[801581325] 'applied index is now lower than readState.Index' (duration: 2.414903ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:18:33.98039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.386395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-hqq5x\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-04-08T06:18:33.980421Z","caller":"traceutil/trace.go:171","msg":"trace[498252850] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-hqq5x; range_end:; response_count:1; response_revision:1318; }","duration":"213.468265ms","start":"2026-04-08T06:18:33.766943Z","end":"2026-04-08T06:18:33.980412Z","steps":["trace[498252850] 'agreement among raft nodes before linearized reading' (duration: 213.350923ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:18:39.559127Z","caller":"traceutil/trace.go:171","msg":"trace[2053800787] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"179.373739ms","start":"2026-04-08T06:18:39.37971Z","end":"2026-04-08T06:18:39.559084Z","steps":["trace[2053800787] 'process raft request' (duration: 151.8235ms)","trace[2053800787] 'compare' (duration: 27.316464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:04.347248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.489028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:19:04.347444Z","caller":"traceutil/trace.go:171","msg":"trace[1533152923] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"642.167044ms","start":"2026-04-08T06:19:03.70524Z","end":"2026-04-08T06:19:04.347407Z","steps":["trace[1533152923] 'process raft request' (duration: 151.379421ms)","trace[1533152923] 'compare' (duration: 490.151909ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:04.347537Z","caller":"traceutil/trace.go:171","msg":"trace[2096141372] linearizableReadLoop","detail":"{readStateIndex:1915; appliedIndex:1914; }","duration":"612.065399ms","start":"2026-04-08T06:19:03.73545Z","end":"2026-04-08T06:19:04.347515Z","steps":["trace[2096141372] 'read index received' (duration: 121.179092ms)","trace[2096141372] 'applied index is now lower than readState.Index' (duration: 490.884837ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:04.347618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:03.70521Z","time spent":"642.320569ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:04.391867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.407433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T06:19:04.391953Z","caller":"traceutil/trace.go:171","msg":"trace[283260455] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1840; }","duration":"656.530846ms","start":"2026-04-08T06:19:03.735397Z","end":"2026-04-08T06:19:04.391928Z","steps":["trace[283260455] 'agreement among raft nodes before linearized reading' (duration: 612.265923ms)","trace[283260455] 'range keys from in-memory index tree' (duration: 44.051059ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:04.392026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:03.735375Z","time spent":"656.628009ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T06:19:04.392059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.546148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d1461e9-3aa6-416e-8b7b-012d5fdfe279\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T06:19:04.39211Z","caller":"traceutil/trace.go:171","msg":"trace[1791098490] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d1461e9-3aa6-416e-8b7b-012d5fdfe279; range_end:; response_count:1; response_revision:1840; }","duration":"287.63877ms","start":"2026-04-08T06:19:04.104457Z","end":"2026-04-08T06:19:04.392096Z","steps":["trace[1791098490] 'agreement among raft nodes before linearized reading' (duration: 287.422715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:04.39207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.872952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d1461e9-3aa6-416e-8b7b-012d5fdfe279\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-08T06:19:04.392171Z","caller":"traceutil/trace.go:171","msg":"trace[1123084127] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d1461e9-3aa6-416e-8b7b-012d5fdfe279; range_end:; response_count:1; response_revision:1840; }","duration":"453.020775ms","start":"2026-04-08T06:19:03.939131Z","end":"2026-04-08T06:19:04.392151Z","steps":["trace[1123084127] 'agreement among raft nodes before linearized reading' (duration: 452.722419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:04.392213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:03.939113Z","time spent":"453.088496ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d1461e9-3aa6-416e-8b7b-012d5fdfe279\" "} {"level":"info","ts":"2026-04-08T06:19:04.701487Z","caller":"traceutil/trace.go:171","msg":"trace[1096449651] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"301.901041ms","start":"2026-04-08T06:19:04.399541Z","end":"2026-04-08T06:19:04.701443Z","steps":["trace[1096449651] 'process raft request' (duration: 301.768568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:04.701627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:04.399523Z","time spent":"302.031545ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:04.907326Z","caller":"traceutil/trace.go:171","msg":"trace[321772445] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1917; }","duration":"411.316038ms","start":"2026-04-08T06:19:04.495983Z","end":"2026-04-08T06:19:04.907299Z","steps":["trace[321772445] 'read index received' (duration: 205.335681ms)","trace[321772445] 'applied index is now lower than readState.Index' (duration: 205.979397ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:04.907523Z","caller":"traceutil/trace.go:171","msg":"trace[687637468] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"493.675899ms","start":"2026-04-08T06:19:04.413813Z","end":"2026-04-08T06:19:04.907489Z","steps":["trace[687637468] 'process raft request' (duration: 438.05604ms)","trace[687637468] 'compare' (duration: 55.310852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:04.907653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:04.413797Z","time spent":"493.780931ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:04.907747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.104175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T06:19:04.907767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.499351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-08T06:19:04.907767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.021783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T06:19:04.907794Z","caller":"traceutil/trace.go:171","msg":"trace[1427982693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1843; }","duration":"273.178446ms","start":"2026-04-08T06:19:04.634601Z","end":"2026-04-08T06:19:04.90778Z","steps":["trace[1427982693] 'agreement among raft nodes before linearized reading' (duration: 273.004181ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:04.907826Z","caller":"traceutil/trace.go:171","msg":"trace[622562146] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1843; }","duration":"361.614144ms","start":"2026-04-08T06:19:04.546191Z","end":"2026-04-08T06:19:04.907806Z","steps":["trace[622562146] 'agreement among raft nodes before linearized reading' (duration: 361.279915ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:04.907827Z","caller":"traceutil/trace.go:171","msg":"trace[1667730459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"277.139635ms","start":"2026-04-08T06:19:04.630673Z","end":"2026-04-08T06:19:04.907813Z","steps":["trace[1667730459] 'agreement among raft nodes before linearized reading' (duration: 276.97195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:04.90787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:04.54615Z","time spent":"361.708776ms","remote":"127.0.0.1:38558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-08T06:19:04.907965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.385663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T06:19:04.907993Z","caller":"traceutil/trace.go:171","msg":"trace[1056062168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1843; }","duration":"209.445475ms","start":"2026-04-08T06:19:04.698539Z","end":"2026-04-08T06:19:04.907984Z","steps":["trace[1056062168] 'agreement among raft nodes before linearized reading' (duration: 209.351813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:04.908019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.540457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-08T06:19:04.908075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.493299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:19:04.908106Z","caller":"traceutil/trace.go:171","msg":"trace[1510718225] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1843; }","duration":"274.650979ms","start":"2026-04-08T06:19:04.633428Z","end":"2026-04-08T06:19:04.908079Z","steps":["trace[1510718225] 'agreement among raft nodes before linearized reading' (duration: 274.314112ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:04.908125Z","caller":"traceutil/trace.go:171","msg":"trace[1422613467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1843; }","duration":"210.585811ms","start":"2026-04-08T06:19:04.697526Z","end":"2026-04-08T06:19:04.908112Z","steps":["trace[1422613467] 'agreement among raft nodes before linearized reading' (duration: 210.431207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:04.907842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.830981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-08T06:19:04.908179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.978372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a44d4c094af543\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-08T06:19:04.90819Z","caller":"traceutil/trace.go:171","msg":"trace[1589841367] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1843; }","duration":"412.25159ms","start":"2026-04-08T06:19:04.495923Z","end":"2026-04-08T06:19:04.908175Z","steps":["trace[1589841367] 'agreement among raft nodes before linearized reading' (duration: 411.506273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:04.908235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:04.495892Z","time spent":"412.330492ms","remote":"127.0.0.1:38558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-08T06:19:04.90824Z","caller":"traceutil/trace.go:171","msg":"trace[521347420] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a44d4c094af543; range_end:; response_count:1; response_revision:1843; }","duration":"342.065854ms","start":"2026-04-08T06:19:04.566152Z","end":"2026-04-08T06:19:04.908218Z","steps":["trace[521347420] 'agreement among raft nodes before linearized reading' (duration: 341.917381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:04.908289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:04.566129Z","time spent":"342.148475ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1006,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a44d4c094af543\" "} {"level":"warn","ts":"2026-04-08T06:19:05.537795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.908675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:19:05.538265Z","caller":"traceutil/trace.go:171","msg":"trace[48544605] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1920; }","duration":"306.636008ms","start":"2026-04-08T06:19:05.231607Z","end":"2026-04-08T06:19:05.538243Z","steps":["trace[48544605] 'read index received' (duration: 35.51µs)","trace[48544605] 'applied index is now lower than readState.Index' (duration: 306.598928ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:05.538344Z","caller":"traceutil/trace.go:171","msg":"trace[1545142946] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"624.377695ms","start":"2026-04-08T06:19:04.913915Z","end":"2026-04-08T06:19:05.538292Z","steps":["trace[1545142946] 'process raft request' (duration: 624.228742ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:05.538379Z","caller":"traceutil/trace.go:171","msg":"trace[1897396485] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"623.871933ms","start":"2026-04-08T06:19:04.914493Z","end":"2026-04-08T06:19:05.538365Z","steps":["trace[1897396485] 'process raft request' (duration: 623.69523ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:05.538426Z","caller":"traceutil/trace.go:171","msg":"trace[1670728780] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"626.232386ms","start":"2026-04-08T06:19:04.912181Z","end":"2026-04-08T06:19:05.538413Z","steps":["trace[1670728780] 'process raft request' (duration: 163.587504ms)","trace[1670728780] 'compare' (duration: 461.67101ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:05.53847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.851253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:19:05.538489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:04.913902Z","time spent":"624.515618ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:05.538514Z","caller":"traceutil/trace.go:171","msg":"trace[339506846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1849; }","duration":"306.949185ms","start":"2026-04-08T06:19:05.231554Z","end":"2026-04-08T06:19:05.538503Z","steps":["trace[339506846] 'agreement among raft nodes before linearized reading' (duration: 306.830873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:05.538548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:05.231534Z","time spent":"307.006346ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:19:05.538559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:04.912158Z","time spent":"626.314319ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:05.538719Z","caller":"traceutil/trace.go:171","msg":"trace[814917761] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"625.391409ms","start":"2026-04-08T06:19:04.9133Z","end":"2026-04-08T06:19:05.538692Z","steps":["trace[814917761] 'process raft request' (duration: 624.789175ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:05.538336Z","caller":"traceutil/trace.go:171","msg":"trace[1193100460] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"625.01662ms","start":"2026-04-08T06:19:04.913259Z","end":"2026-04-08T06:19:05.538275Z","steps":["trace[1193100460] 'process raft request' (duration: 624.672492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:05.538821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:04.913286Z","time spent":"625.48633ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:05.538967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:04.913246Z","time spent":"625.650144ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:05.539142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:04.914484Z","time spent":"623.942015ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:05.540874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.598755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d1461e9-3aa6-416e-8b7b-012d5fdfe279\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-08T06:19:05.540948Z","caller":"traceutil/trace.go:171","msg":"trace[590907899] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d1461e9-3aa6-416e-8b7b-012d5fdfe279; range_end:; response_count:1; response_revision:1850; }","duration":"143.708758ms","start":"2026-04-08T06:19:05.397222Z","end":"2026-04-08T06:19:05.540931Z","steps":["trace[590907899] 'agreement among raft nodes before linearized reading' (duration: 143.351769ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:13.793613Z","caller":"traceutil/trace.go:171","msg":"trace[664164567] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"219.831787ms","start":"2026-04-08T06:19:13.57376Z","end":"2026-04-08T06:19:13.793592Z","steps":["trace[664164567] 'read index received' (duration: 219.778666ms)","trace[664164567] 'applied index is now lower than readState.Index' (duration: 52.181µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:13.793668Z","caller":"traceutil/trace.go:171","msg":"trace[8037773] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"223.432088ms","start":"2026-04-08T06:19:13.570199Z","end":"2026-04-08T06:19:13.793631Z","steps":["trace[8037773] 'process raft request' (duration: 223.235744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.793852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.068223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T06:19:13.793931Z","caller":"traceutil/trace.go:171","msg":"trace[235304735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1927; }","duration":"220.208026ms","start":"2026-04-08T06:19:13.573705Z","end":"2026-04-08T06:19:13.793913Z","steps":["trace[235304735] 'agreement among raft nodes before linearized reading' (duration: 219.983391ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:13.9077Z","caller":"traceutil/trace.go:171","msg":"trace[1879561173] linearizableReadLoop","detail":"{readStateIndex:2005; appliedIndex:2004; }","duration":"114.00117ms","start":"2026-04-08T06:19:13.79368Z","end":"2026-04-08T06:19:13.907681Z","steps":["trace[1879561173] 'read index received' (duration: 89.09242ms)","trace[1879561173] 'applied index is now lower than readState.Index' (duration: 24.90792ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:13.907969Z","caller":"traceutil/trace.go:171","msg":"trace[242302947] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"110.061112ms","start":"2026-04-08T06:19:13.79789Z","end":"2026-04-08T06:19:13.907951Z","steps":["trace[242302947] 'process raft request' (duration: 109.722185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.907992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.761436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-08T06:19:13.907993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.841714ms","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-04-08T06:19:13.907997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.070403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T06:19:13.907997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.549722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:19:13.908031Z","caller":"traceutil/trace.go:171","msg":"trace[1203093312] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1927; }","duration":"333.847698ms","start":"2026-04-08T06:19:13.57417Z","end":"2026-04-08T06:19:13.908018Z","steps":["trace[1203093312] 'agreement among raft nodes before linearized reading' (duration: 333.738206ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:13.908046Z","caller":"traceutil/trace.go:171","msg":"trace[931157173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1927; }","duration":"331.923285ms","start":"2026-04-08T06:19:13.576108Z","end":"2026-04-08T06:19:13.908032Z","steps":["trace[931157173] 'agreement among raft nodes before linearized reading' (duration: 331.769301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.908074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.574145Z","time spent":"333.920561ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T06:19:13.908083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.576097Z","time spent":"331.977017ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T06:19:13.908079Z","caller":"traceutil/trace.go:171","msg":"trace[397303292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1927; }","duration":"333.707535ms","start":"2026-04-08T06:19:13.574353Z","end":"2026-04-08T06:19:13.908061Z","steps":["trace[397303292] 'agreement among raft nodes before linearized reading' (duration: 333.496941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.908139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.574311Z","time spent":"333.814848ms","remote":"127.0.0.1:40128","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":"info","ts":"2026-04-08T06:19:13.908068Z","caller":"traceutil/trace.go:171","msg":"trace[1134345830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1927; }","duration":"334.220187ms","start":"2026-04-08T06:19:13.57383Z","end":"2026-04-08T06:19:13.90805Z","steps":["trace[1134345830] 'agreement among raft nodes before linearized reading' (duration: 333.925661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:13.908194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:13.573795Z","time spent":"334.3919ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T06:19:20.275654Z","caller":"traceutil/trace.go:171","msg":"trace[208008812] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"604.969509ms","start":"2026-04-08T06:19:19.670656Z","end":"2026-04-08T06:19:20.275626Z","steps":["trace[208008812] 'process raft request' (duration: 604.824056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.275926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:19.670646Z","time spent":"605.080521ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5592,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:20.305004Z","caller":"traceutil/trace.go:171","msg":"trace[29152087] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"491.647514ms","start":"2026-04-08T06:19:19.813337Z","end":"2026-04-08T06:19:20.304984Z","steps":["trace[29152087] 'process raft request' (duration: 491.497461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.305117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:19.813309Z","time spent":"491.752917ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:20.30521Z","caller":"traceutil/trace.go:171","msg":"trace[1680467283] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"173.958168ms","start":"2026-04-08T06:19:20.131233Z","end":"2026-04-08T06:19:20.305191Z","steps":["trace[1680467283] 'process raft request' (duration: 173.718062ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:20.305144Z","caller":"traceutil/trace.go:171","msg":"trace[1117979474] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2040; }","duration":"371.801682ms","start":"2026-04-08T06:19:19.933309Z","end":"2026-04-08T06:19:20.30511Z","steps":["trace[1117979474] 'read index received' (duration: 342.163446ms)","trace[1117979474] 'applied index is now lower than readState.Index' (duration: 29.636786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:20.30555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.212521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T06:19:20.305604Z","caller":"traceutil/trace.go:171","msg":"trace[1366824307] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1964; }","duration":"372.320134ms","start":"2026-04-08T06:19:19.933268Z","end":"2026-04-08T06:19:20.305588Z","steps":["trace[1366824307] 'agreement among raft nodes before linearized reading' (duration: 372.088908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.305647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:19.93325Z","time spent":"372.386385ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T06:19:20.323421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.241059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-08T06:19:20.323442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.474254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T06:19:20.323508Z","caller":"traceutil/trace.go:171","msg":"trace[1931838808] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1965; }","duration":"278.581677ms","start":"2026-04-08T06:19:20.044908Z","end":"2026-04-08T06:19:20.32349Z","steps":["trace[1931838808] 'agreement among raft nodes before linearized reading' (duration: 278.408463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.323539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.254696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T06:19:20.323504Z","caller":"traceutil/trace.go:171","msg":"trace[1897736038] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1965; }","duration":"351.368042ms","start":"2026-04-08T06:19:19.97211Z","end":"2026-04-08T06:19:20.323478Z","steps":["trace[1897736038] 'agreement among raft nodes before linearized reading' (duration: 351.144887ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:20.323571Z","caller":"traceutil/trace.go:171","msg":"trace[1494115052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1965; }","duration":"293.306298ms","start":"2026-04-08T06:19:20.030257Z","end":"2026-04-08T06:19:20.323563Z","steps":["trace[1494115052] 'agreement among raft nodes before linearized reading' (duration: 293.222436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.323649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.932779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-08T06:19:20.323724Z","caller":"traceutil/trace.go:171","msg":"trace[1546733564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1965; }","duration":"301.043033ms","start":"2026-04-08T06:19:20.022663Z","end":"2026-04-08T06:19:20.323706Z","steps":["trace[1546733564] 'agreement among raft nodes before linearized reading' (duration: 300.858408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:20.323724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:19.972093Z","time spent":"351.615429ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T06:19:20.32378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:20.022646Z","time spent":"301.122254ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T06:19:20.323587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.282672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-08T06:19:20.324139Z","caller":"traceutil/trace.go:171","msg":"trace[332573559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1965; }","duration":"299.849295ms","start":"2026-04-08T06:19:20.024273Z","end":"2026-04-08T06:19:20.324122Z","steps":["trace[332573559] 'agreement among raft nodes before linearized reading' (duration: 299.181209ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:44.530111Z","caller":"traceutil/trace.go:171","msg":"trace[752453140] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"106.507533ms","start":"2026-04-08T06:19:44.423579Z","end":"2026-04-08T06:19:44.530087Z","steps":["trace[752453140] 'process raft request' (duration: 106.251638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:44.822319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.462601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:19:44.822611Z","caller":"traceutil/trace.go:171","msg":"trace[887299454] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2200; }","duration":"182.753804ms","start":"2026-04-08T06:19:44.639829Z","end":"2026-04-08T06:19:44.822582Z","steps":["trace[887299454] 'read index received' (duration: 13.851413ms)","trace[887299454] 'applied index is now lower than readState.Index' (duration: 168.900881ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:19:44.822682Z","caller":"traceutil/trace.go:171","msg":"trace[1055154025] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"242.227086ms","start":"2026-04-08T06:19:44.580392Z","end":"2026-04-08T06:19:44.822619Z","steps":["trace[1055154025] 'process raft request' (duration: 73.382946ms)","trace[1055154025] 'compare' (duration: 168.343179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:44.82277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.934768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T06:19:44.822803Z","caller":"traceutil/trace.go:171","msg":"trace[2084553229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2117; }","duration":"183.01509ms","start":"2026-04-08T06:19:44.639779Z","end":"2026-04-08T06:19:44.822794Z","steps":["trace[2084553229] 'agreement among raft nodes before linearized reading' (duration: 182.863056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:49.774311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.782053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T06:19:49.774889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.810717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T06:19:49.774974Z","caller":"traceutil/trace.go:171","msg":"trace[1118732884] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2140; }","duration":"210.900999ms","start":"2026-04-08T06:19:49.564052Z","end":"2026-04-08T06:19:49.774953Z","steps":["trace[1118732884] 'range keys from in-memory index tree' (duration: 210.643794ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:49.77497Z","caller":"traceutil/trace.go:171","msg":"trace[471730626] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2140; }","duration":"295.937557ms","start":"2026-04-08T06:19:49.478453Z","end":"2026-04-08T06:19:49.77439Z","steps":["trace[471730626] 'count revisions from in-memory index tree' (duration: 295.678211ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:56.272392Z","caller":"traceutil/trace.go:171","msg":"trace[1540994260] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"361.64643ms","start":"2026-04-08T06:19:55.910718Z","end":"2026-04-08T06:19:56.272364Z","steps":["trace[1540994260] 'process raft request' (duration: 361.417375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:56.272577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:55.910693Z","time spent":"361.787114ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:56.68373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.367236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-08T06:19:56.683833Z","caller":"traceutil/trace.go:171","msg":"trace[338426755] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2172; }","duration":"280.505009ms","start":"2026-04-08T06:19:56.403298Z","end":"2026-04-08T06:19:56.683803Z","steps":["trace[338426755] 'range keys from in-memory index tree' (duration: 280.252134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:56.684289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.064366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129772"} {"level":"info","ts":"2026-04-08T06:19:56.684352Z","caller":"traceutil/trace.go:171","msg":"trace[1160267474] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2172; }","duration":"275.152358ms","start":"2026-04-08T06:19:56.409184Z","end":"2026-04-08T06:19:56.684336Z","steps":["trace[1160267474] 'range keys from in-memory index tree' (duration: 274.463043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:57.380893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.451901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:19:57.381165Z","caller":"traceutil/trace.go:171","msg":"trace[1497431037] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"692.433214ms","start":"2026-04-08T06:19:56.688707Z","end":"2026-04-08T06:19:57.38114Z","steps":["trace[1497431037] 'process raft request' (duration: 98.40127ms)","trace[1497431037] 'compare' (duration: 593.051671ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:57.381276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:56.688696Z","time spent":"692.525636ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:57.38141Z","caller":"traceutil/trace.go:171","msg":"trace[1329210980] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"692.539166ms","start":"2026-04-08T06:19:56.688834Z","end":"2026-04-08T06:19:57.381374Z","steps":["trace[1329210980] 'process raft request' (duration: 692.198909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:57.381561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:56.688817Z","time spent":"692.68034ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:57.38173Z","caller":"traceutil/trace.go:171","msg":"trace[1498127640] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"692.839713ms","start":"2026-04-08T06:19:56.688855Z","end":"2026-04-08T06:19:57.381695Z","steps":["trace[1498127640] 'process raft request' (duration: 692.456305ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:57.38176Z","caller":"traceutil/trace.go:171","msg":"trace[1604816945] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2260; }","duration":"643.744995ms","start":"2026-04-08T06:19:56.737994Z","end":"2026-04-08T06:19:57.381739Z","steps":["trace[1604816945] 'read index received' (duration: 49.011706ms)","trace[1604816945] 'applied index is now lower than readState.Index' (duration: 594.730649ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:19:57.381785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.725279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:57.381795Z","caller":"traceutil/trace.go:171","msg":"trace[45674318] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"522.813627ms","start":"2026-04-08T06:19:56.858961Z","end":"2026-04-08T06:19:57.381774Z","steps":["trace[45674318] 'process raft request' (duration: 522.716615ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:57.381805Z","caller":"traceutil/trace.go:171","msg":"trace[1593347197] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"692.24412ms","start":"2026-04-08T06:19:56.689518Z","end":"2026-04-08T06:19:57.381762Z","steps":["trace[1593347197] 'process raft request' (duration: 692.074026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:57.381872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:56.688847Z","time spent":"692.956176ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:57.381903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:56.858936Z","time spent":"522.91247ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:19:57.381912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.743778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-08T06:19:57.381915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.101014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:19:57.381818Z","caller":"traceutil/trace.go:171","msg":"trace[442517866] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2180; }","duration":"668.75762ms","start":"2026-04-08T06:19:56.71305Z","end":"2026-04-08T06:19:57.381808Z","steps":["trace[442517866] 'agreement among raft nodes before linearized reading' (duration: 668.693408ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:57.381952Z","caller":"traceutil/trace.go:171","msg":"trace[1091489944] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2180; }","duration":"640.82594ms","start":"2026-04-08T06:19:56.741114Z","end":"2026-04-08T06:19:57.38194Z","steps":["trace[1091489944] 'agreement among raft nodes before linearized reading' (duration: 640.747118ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:19:57.381973Z","caller":"traceutil/trace.go:171","msg":"trace[1824391363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2180; }","duration":"149.198636ms","start":"2026-04-08T06:19:57.232757Z","end":"2026-04-08T06:19:57.381956Z","steps":["trace[1824391363] 'agreement among raft nodes before linearized reading' (duration: 149.101394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:57.382002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:56.713026Z","time spent":"668.964205ms","remote":"127.0.0.1:56980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:19:57.382008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:56.741095Z","time spent":"640.903222ms","remote":"127.0.0.1:39914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-08T06:19:57.382007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.099942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25770"} {"level":"warn","ts":"2026-04-08T06:19:57.382005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.808219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T06:19:57.381775Z","caller":"traceutil/trace.go:171","msg":"trace[377186598] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"175.519651ms","start":"2026-04-08T06:19:57.206239Z","end":"2026-04-08T06:19:57.381759Z","steps":["trace[377186598] 'process raft request' (duration: 175.48388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:57.382032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.386585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T06:19:57.382054Z","caller":"traceutil/trace.go:171","msg":"trace[941287091] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2180; }","duration":"694.172624ms","start":"2026-04-08T06:19:56.687869Z","end":"2026-04-08T06:19:57.382042Z","steps":["trace[941287091] 'agreement among raft nodes before linearized reading' (duration: 693.952329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:57.382043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:56.689511Z","time spent":"692.430714ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:19:57.382069Z","caller":"traceutil/trace.go:171","msg":"trace[1476595136] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2180; }","duration":"554.8698ms","start":"2026-04-08T06:19:56.827181Z","end":"2026-04-08T06:19:57.38205Z","steps":["trace[1476595136] 'agreement among raft nodes before linearized reading' (duration: 554.696986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:57.382089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:56.687849Z","time spent":"694.231095ms","remote":"127.0.0.1:40066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":15,"response size":25794,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-04-08T06:19:57.38207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.320586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-08T06:19:57.382096Z","caller":"traceutil/trace.go:171","msg":"trace[1020174119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2180; }","duration":"463.479348ms","start":"2026-04-08T06:19:56.918596Z","end":"2026-04-08T06:19:57.382076Z","steps":["trace[1020174119] 'agreement among raft nodes before linearized reading' (duration: 463.277903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:19:57.382115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:56.827124Z","time spent":"554.983022ms","remote":"127.0.0.1:38558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-08T06:19:57.382139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:19:56.918582Z","time spent":"463.546369ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T06:19:57.382134Z","caller":"traceutil/trace.go:171","msg":"trace[743778443] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2180; }","duration":"140.420459ms","start":"2026-04-08T06:19:57.241696Z","end":"2026-04-08T06:19:57.382117Z","steps":["trace[743778443] 'agreement among raft nodes before linearized reading' (duration: 140.236125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:45.583626Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208185485474688311,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T06:20:45.771461Z","caller":"traceutil/trace.go:171","msg":"trace[1544626378] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"849.455765ms","start":"2026-04-08T06:20:44.921984Z","end":"2026-04-08T06:20:45.771439Z","steps":["trace[1544626378] 'process raft request' (duration: 849.316742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:45.771582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:20:44.921951Z","time spent":"849.571448ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:20:45.82114Z","caller":"traceutil/trace.go:171","msg":"trace[222803246] transaction","detail":"{read_only:false; response_revision:2609; number_of_response:1; }","duration":"727.327378ms","start":"2026-04-08T06:20:45.093788Z","end":"2026-04-08T06:20:45.821115Z","steps":["trace[222803246] 'process raft request' (duration: 727.104433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:45.821292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:20:45.093761Z","time spent":"727.45412ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:20:45.821505Z","caller":"traceutil/trace.go:171","msg":"trace[320350660] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"686.538317ms","start":"2026-04-08T06:20:45.134909Z","end":"2026-04-08T06:20:45.821447Z","steps":["trace[320350660] 'process raft request' (duration: 686.162079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:45.821567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:20:45.134895Z","time spent":"686.64069ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:20:45.821566Z","caller":"traceutil/trace.go:171","msg":"trace[1360374264] linearizableReadLoop","detail":"{readStateIndex:2713; appliedIndex:2710; }","duration":"738.006839ms","start":"2026-04-08T06:20:45.083517Z","end":"2026-04-08T06:20:45.821524Z","steps":["trace[1360374264] 'read index received' (duration: 687.860868ms)","trace[1360374264] 'applied index is now lower than readState.Index' (duration: 50.144281ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:20:45.821647Z","caller":"traceutil/trace.go:171","msg":"trace[836238302] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"210.772339ms","start":"2026-04-08T06:20:45.61084Z","end":"2026-04-08T06:20:45.821612Z","steps":["trace[836238302] 'process raft request' (duration: 210.461461ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:20:45.821674Z","caller":"traceutil/trace.go:171","msg":"trace[614220990] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"161.149658ms","start":"2026-04-08T06:20:45.660507Z","end":"2026-04-08T06:20:45.821657Z","steps":["trace[614220990] 'process raft request' (duration: 160.900852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:45.821858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.571389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:20:45.821889Z","caller":"traceutil/trace.go:171","msg":"trace[869904472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2612; }","duration":"589.643301ms","start":"2026-04-08T06:20:45.232236Z","end":"2026-04-08T06:20:45.821879Z","steps":["trace[869904472] 'agreement among raft nodes before linearized reading' (duration: 589.58012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:45.821898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.369218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-08T06:20:45.821982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:20:45.232219Z","time spent":"589.754843ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:20:45.821994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.80312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T06:20:45.82201Z","caller":"traceutil/trace.go:171","msg":"trace[1050078704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2612; }","duration":"738.536881ms","start":"2026-04-08T06:20:45.083453Z","end":"2026-04-08T06:20:45.82199Z","steps":["trace[1050078704] 'agreement among raft nodes before linearized reading' (duration: 738.258015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:45.822036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.915028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-08T06:20:45.822064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:20:45.083431Z","time spent":"738.618964ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T06:20:45.822102Z","caller":"traceutil/trace.go:171","msg":"trace[565487459] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2612; }","duration":"311.01546ms","start":"2026-04-08T06:20:45.511066Z","end":"2026-04-08T06:20:45.822081Z","steps":["trace[565487459] 'agreement among raft nodes before linearized reading' (duration: 310.806256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:45.822147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.383106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:20:45.822189Z","caller":"traceutil/trace.go:171","msg":"trace[1706837456] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:2612; }","duration":"570.449178ms","start":"2026-04-08T06:20:45.25173Z","end":"2026-04-08T06:20:45.822179Z","steps":["trace[1706837456] 'agreement among raft nodes before linearized reading' (duration: 570.385046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:20:45.822218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:20:45.25172Z","time spent":"570.490438ms","remote":"127.0.0.1:40176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:20:45.822149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:20:45.511045Z","time spent":"311.091773ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-08T06:20:45.822048Z","caller":"traceutil/trace.go:171","msg":"trace[2042376367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2612; }","duration":"121.886972ms","start":"2026-04-08T06:20:45.700145Z","end":"2026-04-08T06:20:45.822032Z","steps":["trace[2042376367] 'agreement among raft nodes before linearized reading' (duration: 121.688218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:21:18.943162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.548823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:21:18.943197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.106591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-08T06:21:18.943263Z","caller":"traceutil/trace.go:171","msg":"trace[184961506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2813; }","duration":"710.698197ms","start":"2026-04-08T06:21:18.232537Z","end":"2026-04-08T06:21:18.943236Z","steps":["trace[184961506] 'range keys from in-memory index tree' (duration: 710.38143ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:21:18.943308Z","caller":"traceutil/trace.go:171","msg":"trace[135850447] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:2813; }","duration":"236.266034ms","start":"2026-04-08T06:21:18.707016Z","end":"2026-04-08T06:21:18.943282Z","steps":["trace[135850447] 'range keys from in-memory index tree' (duration: 235.853365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:21:18.943355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:21:18.232507Z","time spent":"710.837099ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T06:21:18.943517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.554813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:21:18.943738Z","caller":"traceutil/trace.go:171","msg":"trace[1954943275] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2813; }","duration":"272.849259ms","start":"2026-04-08T06:21:18.67085Z","end":"2026-04-08T06:21:18.943699Z","steps":["trace[1954943275] 'count revisions from in-memory index tree' (duration: 272.394409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:21:18.943625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.028141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-08T06:21:18.94394Z","caller":"traceutil/trace.go:171","msg":"trace[1018436197] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2813; }","duration":"415.388869ms","start":"2026-04-08T06:21:18.528531Z","end":"2026-04-08T06:21:18.94392Z","steps":["trace[1018436197] 'range keys from in-memory index tree' (duration: 414.477748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:21:18.943992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:21:18.528507Z","time spent":"415.47094ms","remote":"127.0.0.1:40066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-08T06:21:19.363661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.267945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:21:19.363758Z","caller":"traceutil/trace.go:171","msg":"trace[1534227627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2814; }","duration":"131.405338ms","start":"2026-04-08T06:21:19.23233Z","end":"2026-04-08T06:21:19.363735Z","steps":["trace[1534227627] 'range keys from in-memory index tree' (duration: 131.196983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:21:19.363755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.424074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:21:19.363869Z","caller":"traceutil/trace.go:171","msg":"trace[2031491687] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2814; }","duration":"215.602238ms","start":"2026-04-08T06:21:19.148238Z","end":"2026-04-08T06:21:19.36384Z","steps":["trace[2031491687] 'count revisions from in-memory index tree' (duration: 215.344652ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:21:19.856686Z","caller":"traceutil/trace.go:171","msg":"trace[241688188] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"422.462646ms","start":"2026-04-08T06:21:19.434192Z","end":"2026-04-08T06:21:19.856655Z","steps":["trace[241688188] 'process raft request' (duration: 422.277692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:21:19.856973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:21:19.434171Z","time spent":"422.703332ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:23:51.267041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.637189ms","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":"warn","ts":"2026-04-08T06:23:51.267101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:23:50.755718Z","time spent":"511.378295ms","remote":"127.0.0.1:41936","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-08T06:23:51.267162Z","caller":"traceutil/trace.go:171","msg":"trace[1454276166] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3813; }","duration":"533.778192ms","start":"2026-04-08T06:23:50.733358Z","end":"2026-04-08T06:23:51.267136Z","steps":["trace[1454276166] 'range keys from in-memory index tree' (duration: 533.447105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:23:51.267137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.881928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T06:23:51.267217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:23:50.733348Z","time spent":"533.855093ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-08T06:23:51.267245Z","caller":"traceutil/trace.go:171","msg":"trace[1489169129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3813; }","duration":"528.014691ms","start":"2026-04-08T06:23:50.739207Z","end":"2026-04-08T06:23:51.267222Z","steps":["trace[1489169129] 'range keys from in-memory index tree' (duration: 527.654633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:23:51.267293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:23:50.73915Z","time spent":"528.133264ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T06:23:51.267295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.624502ms","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-04-08T06:23:51.267367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.973264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-08T06:23:51.267403Z","caller":"traceutil/trace.go:171","msg":"trace[1794939466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3813; }","duration":"537.029385ms","start":"2026-04-08T06:23:50.73036Z","end":"2026-04-08T06:23:51.26739Z","steps":["trace[1794939466] 'range keys from in-memory index tree' (duration: 536.614485ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:23:51.267412Z","caller":"traceutil/trace.go:171","msg":"trace[1723592158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3813; }","duration":"527.734465ms","start":"2026-04-08T06:23:50.739643Z","end":"2026-04-08T06:23:51.267377Z","steps":["trace[1723592158] 'range keys from in-memory index tree' (duration: 527.437799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:23:51.267441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:23:50.730351Z","time spent":"537.082557ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T06:23:51.267464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:23:50.739635Z","time spent":"527.813587ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T06:23:51.267428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.473114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-08T06:23:51.267485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.814203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T06:23:51.267585Z","caller":"traceutil/trace.go:171","msg":"trace[470358307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3813; }","duration":"538.15909ms","start":"2026-04-08T06:23:50.729407Z","end":"2026-04-08T06:23:51.267566Z","steps":["trace[470358307] 'range keys from in-memory index tree' (duration: 537.542867ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:23:51.267623Z","caller":"traceutil/trace.go:171","msg":"trace[1295554018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3813; }","duration":"544.709669ms","start":"2026-04-08T06:23:50.722836Z","end":"2026-04-08T06:23:51.267546Z","steps":["trace[1295554018] 'range keys from in-memory index tree' (duration: 544.129396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:23:51.268418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:23:50.722813Z","time spent":"545.595248ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T06:23:51.268351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:23:50.729387Z","time spent":"538.946398ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T06:23:51.432501Z","caller":"traceutil/trace.go:171","msg":"trace[1264829227] transaction","detail":"{read_only:false; response_revision:3814; number_of_response:1; }","duration":"161.625602ms","start":"2026-04-08T06:23:51.27085Z","end":"2026-04-08T06:23:51.432476Z","steps":["trace[1264829227] 'process raft request' (duration: 161.499839ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:23:51.439112Z","caller":"traceutil/trace.go:171","msg":"trace[544074483] transaction","detail":"{read_only:false; response_revision:3815; number_of_response:1; }","duration":"167.059065ms","start":"2026-04-08T06:23:51.272022Z","end":"2026-04-08T06:23:51.439081Z","steps":["trace[544074483] 'process raft request' (duration: 166.886841ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:23:51.439358Z","caller":"traceutil/trace.go:171","msg":"trace[883504100] transaction","detail":"{read_only:false; response_revision:3816; number_of_response:1; }","duration":"167.278809ms","start":"2026-04-08T06:23:51.272065Z","end":"2026-04-08T06:23:51.439344Z","steps":["trace[883504100] 'process raft request' (duration: 166.969483ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:23:51.439731Z","caller":"traceutil/trace.go:171","msg":"trace[136465114] transaction","detail":"{read_only:false; response_revision:3817; number_of_response:1; }","duration":"167.009352ms","start":"2026-04-08T06:23:51.272695Z","end":"2026-04-08T06:23:51.439704Z","steps":["trace[136465114] 'process raft request' (duration: 166.608624ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:23:51.439812Z","caller":"traceutil/trace.go:171","msg":"trace[826943638] transaction","detail":"{read_only:false; response_revision:3818; number_of_response:1; }","duration":"166.949292ms","start":"2026-04-08T06:23:51.272819Z","end":"2026-04-08T06:23:51.439769Z","steps":["trace[826943638] 'process raft request' (duration: 166.769828ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:23:51.439949Z","caller":"traceutil/trace.go:171","msg":"trace[345035977] transaction","detail":"{read_only:false; response_revision:3819; number_of_response:1; }","duration":"166.975793ms","start":"2026-04-08T06:23:51.272913Z","end":"2026-04-08T06:23:51.439889Z","steps":["trace[345035977] 'process raft request' (duration: 166.778678ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:03.099678Z","caller":"traceutil/trace.go:171","msg":"trace[1812373474] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"111.765256ms","start":"2026-04-08T06:25:02.987886Z","end":"2026-04-08T06:25:03.099651Z","steps":["trace[1812373474] 'process raft request' (duration: 20.156045ms)","trace[1812373474] 'compare' (duration: 91.223872ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:25:10.119711Z","caller":"traceutil/trace.go:171","msg":"trace[572317032] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"135.559394ms","start":"2026-04-08T06:25:09.984124Z","end":"2026-04-08T06:25:10.119683Z","steps":["trace[572317032] 'process raft request' (duration: 135.128435ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:10.138422Z","caller":"traceutil/trace.go:171","msg":"trace[134569931] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"124.881512ms","start":"2026-04-08T06:25:10.013504Z","end":"2026-04-08T06:25:10.138386Z","steps":["trace[134569931] 'process raft request' (duration: 124.512134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:13.097174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.798731ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:25:13.097339Z","caller":"traceutil/trace.go:171","msg":"trace[1301780632] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"220.625777ms","start":"2026-04-08T06:25:12.876699Z","end":"2026-04-08T06:25:13.097325Z","steps":["trace[1301780632] 'process raft request' (duration: 220.571375ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:13.097419Z","caller":"traceutil/trace.go:171","msg":"trace[1359436640] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"222.07035ms","start":"2026-04-08T06:25:12.875314Z","end":"2026-04-08T06:25:13.097384Z","steps":["trace[1359436640] 'process raft request' (duration: 92.985192ms)","trace[1359436640] 'compare' (duration: 128.666209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:25:14.280166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208185485474693990,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T06:25:14.449218Z","caller":"traceutil/trace.go:171","msg":"trace[1932233394] linearizableReadLoop","detail":"{readStateIndex:5048; appliedIndex:5046; }","duration":"669.874702ms","start":"2026-04-08T06:25:13.779295Z","end":"2026-04-08T06:25:14.44917Z","steps":["trace[1932233394] 'read index received' (duration: 653.243447ms)","trace[1932233394] 'applied index is now lower than readState.Index' (duration: 16.629305ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:25:14.449341Z","caller":"traceutil/trace.go:171","msg":"trace[100927063] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"669.193427ms","start":"2026-04-08T06:25:13.780133Z","end":"2026-04-08T06:25:14.449326Z","steps":["trace[100927063] 'process raft request' (duration: 668.859389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:14.449444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:13.780119Z","time spent":"669.251319ms","remote":"127.0.0.1:39926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T06:25:14.456678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.553355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T06:25:14.456787Z","caller":"traceutil/trace.go:171","msg":"trace[1080117717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4862; }","duration":"561.708878ms","start":"2026-04-08T06:25:13.895059Z","end":"2026-04-08T06:25:14.456768Z","steps":["trace[1080117717] 'agreement among raft nodes before linearized reading' (duration: 554.462903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:14.45684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:13.895043Z","time spent":"561.78713ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T06:25:14.456939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.562872ms","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-04-08T06:25:14.457014Z","caller":"traceutil/trace.go:171","msg":"trace[1500505095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4862; }","duration":"568.703936ms","start":"2026-04-08T06:25:13.888291Z","end":"2026-04-08T06:25:14.456995Z","steps":["trace[1500505095] 'agreement among raft nodes before linearized reading' (duration: 561.117444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:14.457104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:13.888272Z","time spent":"568.819219ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T06:25:14.457234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.587351ms","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-04-08T06:25:14.45729Z","caller":"traceutil/trace.go:171","msg":"trace[1345078077] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4862; }","duration":"548.664993ms","start":"2026-04-08T06:25:13.908611Z","end":"2026-04-08T06:25:14.457276Z","steps":["trace[1345078077] 'agreement among raft nodes before linearized reading' (duration: 540.874006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:14.457333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:13.908599Z","time spent":"548.725614ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T06:25:14.457493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.60938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T06:25:14.457527Z","caller":"traceutil/trace.go:171","msg":"trace[969494816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4862; }","duration":"551.65048ms","start":"2026-04-08T06:25:13.905868Z","end":"2026-04-08T06:25:14.457518Z","steps":["trace[969494816] 'agreement among raft nodes before linearized reading' (duration: 543.628049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:14.456681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.473247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-08T06:25:14.457621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.322545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zmjmb\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-08T06:25:14.457625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.190656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T06:25:14.457664Z","caller":"traceutil/trace.go:171","msg":"trace[503274019] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zmjmb; range_end:; response_count:1; response_revision:4862; }","duration":"548.447818ms","start":"2026-04-08T06:25:13.909209Z","end":"2026-04-08T06:25:14.457657Z","steps":["trace[503274019] 'agreement among raft nodes before linearized reading' (duration: 540.238802ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:14.457675Z","caller":"traceutil/trace.go:171","msg":"trace[734237571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4862; }","duration":"553.258668ms","start":"2026-04-08T06:25:13.904407Z","end":"2026-04-08T06:25:14.457666Z","steps":["trace[734237571] 'agreement among raft nodes before linearized reading' (duration: 545.105933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:14.45769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:13.909188Z","time spent":"548.496739ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-zmjmb\" "} {"level":"warn","ts":"2026-04-08T06:25:14.457679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.372365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-5bf85\" ","response":"range_response_count:1 size:9716"} {"level":"warn","ts":"2026-04-08T06:25:14.457712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:13.904401Z","time spent":"553.296438ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T06:25:14.457736Z","caller":"traceutil/trace.go:171","msg":"trace[2006357625] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-5bf85; range_end:; response_count:1; response_revision:4862; }","duration":"678.461747ms","start":"2026-04-08T06:25:13.779258Z","end":"2026-04-08T06:25:14.45772Z","steps":["trace[2006357625] 'agreement among raft nodes before linearized reading' (duration: 670.179089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:14.457772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:13.77923Z","time spent":"678.532728ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9740,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-5bf85\" "} {"level":"info","ts":"2026-04-08T06:25:14.457616Z","caller":"traceutil/trace.go:171","msg":"trace[216556860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4862; }","duration":"548.441548ms","start":"2026-04-08T06:25:13.909139Z","end":"2026-04-08T06:25:14.45758Z","steps":["trace[216556860] 'agreement among raft nodes before linearized reading' (duration: 540.317324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:14.45786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:13.909128Z","time spent":"548.696453ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T06:25:14.457554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:13.905863Z","time spent":"551.684531ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T06:25:14.927959Z","caller":"traceutil/trace.go:171","msg":"trace[1277840918] linearizableReadLoop","detail":"{readStateIndex:5058; appliedIndex:5056; }","duration":"136.799612ms","start":"2026-04-08T06:25:14.791137Z","end":"2026-04-08T06:25:14.927937Z","steps":["trace[1277840918] 'read index received' (duration: 12.554223ms)","trace[1277840918] 'applied index is now lower than readState.Index' (duration: 124.244709ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:25:14.928027Z","caller":"traceutil/trace.go:171","msg":"trace[1992467395] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"151.007604ms","start":"2026-04-08T06:25:14.77696Z","end":"2026-04-08T06:25:14.927967Z","steps":["trace[1992467395] 'process raft request' (duration: 150.068193ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:14.928035Z","caller":"traceutil/trace.go:171","msg":"trace[385229265] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"135.418111ms","start":"2026-04-08T06:25:14.792572Z","end":"2026-04-08T06:25:14.92799Z","steps":["trace[385229265] 'process raft request' (duration: 135.305298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:14.928314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.130281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-5bf85\" ","response":"range_response_count:1 size:9728"} {"level":"info","ts":"2026-04-08T06:25:14.928552Z","caller":"traceutil/trace.go:171","msg":"trace[263917659] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-5bf85; range_end:; response_count:1; response_revision:4872; }","duration":"137.441707ms","start":"2026-04-08T06:25:14.791094Z","end":"2026-04-08T06:25:14.928536Z","steps":["trace[263917659] 'agreement among raft nodes before linearized reading' (duration: 136.983206ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:16.779097Z","caller":"traceutil/trace.go:171","msg":"trace[689137308] linearizableReadLoop","detail":"{readStateIndex:5164; appliedIndex:5163; }","duration":"298.729134ms","start":"2026-04-08T06:25:16.480345Z","end":"2026-04-08T06:25:16.779074Z","steps":["trace[689137308] 'read index received' (duration: 298.55251ms)","trace[689137308] 'applied index is now lower than readState.Index' (duration: 175.514µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:25:16.779163Z","caller":"traceutil/trace.go:171","msg":"trace[213793818] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"304.539995ms","start":"2026-04-08T06:25:16.474582Z","end":"2026-04-08T06:25:16.779122Z","steps":["trace[213793818] 'process raft request' (duration: 304.33542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:16.779349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:16.47457Z","time spent":"304.683128ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:25:16.779367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.718184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-08T06:25:16.779364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.00864ms","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-04-08T06:25:16.779405Z","caller":"traceutil/trace.go:171","msg":"trace[985636020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4977; }","duration":"298.791665ms","start":"2026-04-08T06:25:16.480601Z","end":"2026-04-08T06:25:16.779393Z","steps":["trace[985636020] 'agreement among raft nodes before linearized reading' (duration: 298.633271ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:16.779446Z","caller":"traceutil/trace.go:171","msg":"trace[1818973216] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4977; }","duration":"299.115231ms","start":"2026-04-08T06:25:16.48031Z","end":"2026-04-08T06:25:16.779426Z","steps":["trace[1818973216] 'agreement among raft nodes before linearized reading' (duration: 298.894817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:16.779363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.161389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:25:16.779556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.269433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T06:25:16.779561Z","caller":"traceutil/trace.go:171","msg":"trace[1975911205] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4977; }","duration":"194.403085ms","start":"2026-04-08T06:25:16.585142Z","end":"2026-04-08T06:25:16.779545Z","steps":["trace[1975911205] 'agreement among raft nodes before linearized reading' (duration: 194.134139ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:16.77959Z","caller":"traceutil/trace.go:171","msg":"trace[349719286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4977; }","duration":"298.341585ms","start":"2026-04-08T06:25:16.48124Z","end":"2026-04-08T06:25:16.779582Z","steps":["trace[349719286] 'agreement among raft nodes before linearized reading' (duration: 298.261533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:16.779823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.549929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T06:25:16.77989Z","caller":"traceutil/trace.go:171","msg":"trace[889357902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4977; }","duration":"298.672052ms","start":"2026-04-08T06:25:16.481202Z","end":"2026-04-08T06:25:16.779874Z","steps":["trace[889357902] 'agreement among raft nodes before linearized reading' (duration: 298.419527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:16.779809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.131063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-08T06:25:16.779955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.163377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719300"} {"level":"info","ts":"2026-04-08T06:25:16.780008Z","caller":"traceutil/trace.go:171","msg":"trace[619522651] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4977; }","duration":"189.235918ms","start":"2026-04-08T06:25:16.590756Z","end":"2026-04-08T06:25:16.779992Z","steps":["trace[619522651] 'agreement among raft nodes before linearized reading' (duration: 188.841609ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:16.780007Z","caller":"traceutil/trace.go:171","msg":"trace[177678238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4977; }","duration":"299.344027ms","start":"2026-04-08T06:25:16.480644Z","end":"2026-04-08T06:25:16.779988Z","steps":["trace[177678238] 'agreement among raft nodes before linearized reading' (duration: 299.000159ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:20.902198Z","caller":"traceutil/trace.go:171","msg":"trace[1469984467] linearizableReadLoop","detail":"{readStateIndex:5256; appliedIndex:5255; }","duration":"108.550803ms","start":"2026-04-08T06:25:20.793623Z","end":"2026-04-08T06:25:20.902174Z","steps":["trace[1469984467] 'read index received' (duration: 108.440091ms)","trace[1469984467] 'applied index is now lower than readState.Index' (duration: 109.522µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:25:20.902308Z","caller":"traceutil/trace.go:171","msg":"trace[426746610] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"162.522374ms","start":"2026-04-08T06:25:20.739776Z","end":"2026-04-08T06:25:20.902298Z","steps":["trace[426746610] 'process raft request' (duration: 162.256808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:20.902501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.863571ms","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-04-08T06:25:20.902576Z","caller":"traceutil/trace.go:171","msg":"trace[1344747730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5065; }","duration":"108.990014ms","start":"2026-04-08T06:25:20.793569Z","end":"2026-04-08T06:25:20.902559Z","steps":["trace[1344747730] 'agreement among raft nodes before linearized reading' (duration: 108.753138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:20.926898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.442049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T06:25:20.92695Z","caller":"traceutil/trace.go:171","msg":"trace[493935906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5066; }","duration":"103.51689ms","start":"2026-04-08T06:25:20.823421Z","end":"2026-04-08T06:25:20.926938Z","steps":["trace[493935906] 'agreement among raft nodes before linearized reading' (duration: 103.385957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:20.926922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.110183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T06:25:20.927003Z","caller":"traceutil/trace.go:171","msg":"trace[306455381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5066; }","duration":"104.268797ms","start":"2026-04-08T06:25:20.822714Z","end":"2026-04-08T06:25:20.926983Z","steps":["trace[306455381] 'agreement among raft nodes before linearized reading' (duration: 104.082563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:20.927001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.967363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-08T06:25:20.927024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.102466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T06:25:20.927077Z","caller":"traceutil/trace.go:171","msg":"trace[1226266048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5066; }","duration":"105.199288ms","start":"2026-04-08T06:25:20.821864Z","end":"2026-04-08T06:25:20.927063Z","steps":["trace[1226266048] 'agreement among raft nodes before linearized reading' (duration: 105.046974ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:20.927073Z","caller":"traceutil/trace.go:171","msg":"trace[974443187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5066; }","duration":"105.079205ms","start":"2026-04-08T06:25:20.821976Z","end":"2026-04-08T06:25:20.927055Z","steps":["trace[974443187] 'agreement among raft nodes before linearized reading' (duration: 104.8589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:20.927123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.518062ms","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-04-08T06:25:20.927144Z","caller":"traceutil/trace.go:171","msg":"trace[924591481] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5066; }","duration":"104.548523ms","start":"2026-04-08T06:25:20.822587Z","end":"2026-04-08T06:25:20.927136Z","steps":["trace[924591481] 'agreement among raft nodes before linearized reading' (duration: 104.496102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:21.175906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.96151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:25:21.17615Z","caller":"traceutil/trace.go:171","msg":"trace[202941711] transaction","detail":"{read_only:false; response_revision:5068; number_of_response:1; }","duration":"247.389282ms","start":"2026-04-08T06:25:20.928723Z","end":"2026-04-08T06:25:21.176112Z","steps":["trace[202941711] 'process raft request' (duration: 69.992902ms)","trace[202941711] 'compare' (duration: 176.822837ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:25:21.176397Z","caller":"traceutil/trace.go:171","msg":"trace[1247376481] transaction","detail":"{read_only:false; response_revision:5069; number_of_response:1; }","duration":"244.760032ms","start":"2026-04-08T06:25:20.931599Z","end":"2026-04-08T06:25:21.176359Z","steps":["trace[1247376481] 'process raft request' (duration: 244.408804ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:21.176464Z","caller":"traceutil/trace.go:171","msg":"trace[2035951912] transaction","detail":"{read_only:false; response_revision:5070; number_of_response:1; }","duration":"244.790944ms","start":"2026-04-08T06:25:20.931634Z","end":"2026-04-08T06:25:21.176425Z","steps":["trace[2035951912] 'process raft request' (duration: 244.455096ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:21.176484Z","caller":"traceutil/trace.go:171","msg":"trace[115588257] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"244.776213ms","start":"2026-04-08T06:25:20.931685Z","end":"2026-04-08T06:25:21.176461Z","steps":["trace[115588257] 'process raft request' (duration: 244.455466ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:21.176688Z","caller":"traceutil/trace.go:171","msg":"trace[1754217968] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"244.349314ms","start":"2026-04-08T06:25:20.93231Z","end":"2026-04-08T06:25:21.176659Z","steps":["trace[1754217968] 'process raft request' (duration: 244.081648ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:21.176801Z","caller":"traceutil/trace.go:171","msg":"trace[926497768] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"244.401655ms","start":"2026-04-08T06:25:20.932383Z","end":"2026-04-08T06:25:21.176785Z","steps":["trace[926497768] 'process raft request' (duration: 244.1965ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:21.176847Z","caller":"traceutil/trace.go:171","msg":"trace[1839173640] linearizableReadLoop","detail":"{readStateIndex:5266; appliedIndex:5259; }","duration":"215.03773ms","start":"2026-04-08T06:25:20.961773Z","end":"2026-04-08T06:25:21.176811Z","steps":["trace[1839173640] 'read index received' (duration: 36.950605ms)","trace[1839173640] 'applied index is now lower than readState.Index' (duration: 178.085785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:25:21.177051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.837835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:25:21.176905Z","caller":"traceutil/trace.go:171","msg":"trace[2006358809] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"236.224691ms","start":"2026-04-08T06:25:20.940659Z","end":"2026-04-08T06:25:21.176884Z","steps":["trace[2006358809] 'process raft request' (duration: 236.055126ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:21.177103Z","caller":"traceutil/trace.go:171","msg":"trace[13190403] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5074; }","duration":"236.937876ms","start":"2026-04-08T06:25:20.940148Z","end":"2026-04-08T06:25:21.177086Z","steps":["trace[13190403] 'agreement among raft nodes before linearized reading' (duration: 236.827824ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:21.17871Z","caller":"traceutil/trace.go:171","msg":"trace[127938920] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"210.310664ms","start":"2026-04-08T06:25:20.96838Z","end":"2026-04-08T06:25:21.178691Z","steps":["trace[127938920] 'process raft request' (duration: 210.173101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:21.184273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.037218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-08T06:25:21.184311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.192442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-08T06:25:21.184376Z","caller":"traceutil/trace.go:171","msg":"trace[1710605526] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5077; }","duration":"222.270224ms","start":"2026-04-08T06:25:20.962085Z","end":"2026-04-08T06:25:21.184355Z","steps":["trace[1710605526] 'agreement among raft nodes before linearized reading' (duration: 222.058809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:21.184489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.516088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16452"} {"level":"info","ts":"2026-04-08T06:25:21.184353Z","caller":"traceutil/trace.go:171","msg":"trace[113732974] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5077; }","duration":"149.161031ms","start":"2026-04-08T06:25:21.035171Z","end":"2026-04-08T06:25:21.184332Z","steps":["trace[113732974] 'agreement among raft nodes before linearized reading' (duration: 148.942927ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:21.184633Z","caller":"traceutil/trace.go:171","msg":"trace[583572128] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5077; }","duration":"214.61434ms","start":"2026-04-08T06:25:20.96993Z","end":"2026-04-08T06:25:21.184544Z","steps":["trace[583572128] 'agreement among raft nodes before linearized reading' (duration: 214.451366ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:35.409001Z","caller":"traceutil/trace.go:171","msg":"trace[1041068970] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"246.985552ms","start":"2026-04-08T06:25:35.161981Z","end":"2026-04-08T06:25:35.408967Z","steps":["trace[1041068970] 'process raft request' (duration: 220.33051ms)","trace[1041068970] 'compare' (duration: 26.451228ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:25:35.409083Z","caller":"traceutil/trace.go:171","msg":"trace[1644283758] linearizableReadLoop","detail":"{readStateIndex:5499; appliedIndex:5498; }","duration":"246.511152ms","start":"2026-04-08T06:25:35.162543Z","end":"2026-04-08T06:25:35.409054Z","steps":["trace[1644283758] 'read index received' (duration: 219.782468ms)","trace[1644283758] 'applied index is now lower than readState.Index' (duration: 26.725794ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:25:35.409016Z","caller":"traceutil/trace.go:171","msg":"trace[306575206] transaction","detail":"{read_only:false; response_revision:5304; number_of_response:1; }","duration":"171.566678ms","start":"2026-04-08T06:25:35.237427Z","end":"2026-04-08T06:25:35.408993Z","steps":["trace[306575206] 'process raft request' (duration: 171.528377ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:35.409034Z","caller":"traceutil/trace.go:171","msg":"trace[1697405676] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"173.710727ms","start":"2026-04-08T06:25:35.235316Z","end":"2026-04-08T06:25:35.409026Z","steps":["trace[1697405676] 'process raft request' (duration: 173.574154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:35.409425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.611458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:25:35.409397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.833199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"warn","ts":"2026-04-08T06:25:35.409523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.267912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:25:35.409579Z","caller":"traceutil/trace.go:171","msg":"trace[1090023378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5304; }","duration":"167.337053ms","start":"2026-04-08T06:25:35.242221Z","end":"2026-04-08T06:25:35.409558Z","steps":["trace[1090023378] 'agreement among raft nodes before linearized reading' (duration: 167.173819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:35.409353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.921824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"warn","ts":"2026-04-08T06:25:35.409559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.403647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T06:25:35.409656Z","caller":"traceutil/trace.go:171","msg":"trace[1581099823] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5304; }","duration":"225.271753ms","start":"2026-04-08T06:25:35.184371Z","end":"2026-04-08T06:25:35.409643Z","steps":["trace[1581099823] 'agreement among raft nodes before linearized reading' (duration: 224.832943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:35.409643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.429084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T06:25:35.409685Z","caller":"traceutil/trace.go:171","msg":"trace[79244624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5304; }","duration":"164.56647ms","start":"2026-04-08T06:25:35.245109Z","end":"2026-04-08T06:25:35.409675Z","steps":["trace[79244624] 'agreement among raft nodes before linearized reading' (duration: 164.254023ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:35.409497Z","caller":"traceutil/trace.go:171","msg":"trace[1627966822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5304; }","duration":"178.690659ms","start":"2026-04-08T06:25:35.230764Z","end":"2026-04-08T06:25:35.409455Z","steps":["trace[1627966822] 'agreement among raft nodes before linearized reading' (duration: 178.640758ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:35.409721Z","caller":"traceutil/trace.go:171","msg":"trace[962364908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5304; }","duration":"163.543807ms","start":"2026-04-08T06:25:35.246159Z","end":"2026-04-08T06:25:35.409703Z","steps":["trace[962364908] 'agreement among raft nodes before linearized reading' (duration: 163.270391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:35.409712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.206557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-08T06:25:35.40981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.592908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T06:25:35.409932Z","caller":"traceutil/trace.go:171","msg":"trace[866417215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5304; }","duration":"163.766301ms","start":"2026-04-08T06:25:35.24614Z","end":"2026-04-08T06:25:35.409907Z","steps":["trace[866417215] 'agreement among raft nodes before linearized reading' (duration: 163.376623ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:35.409784Z","caller":"traceutil/trace.go:171","msg":"trace[180539801] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5304; }","duration":"166.318739ms","start":"2026-04-08T06:25:35.243449Z","end":"2026-04-08T06:25:35.409768Z","steps":["trace[180539801] 'agreement among raft nodes before linearized reading' (duration: 165.932861ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:35.409557Z","caller":"traceutil/trace.go:171","msg":"trace[2128609254] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5304; }","duration":"247.018513ms","start":"2026-04-08T06:25:35.162523Z","end":"2026-04-08T06:25:35.409541Z","steps":["trace[2128609254] 'agreement among raft nodes before linearized reading' (duration: 246.651845ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:37.109135Z","caller":"traceutil/trace.go:171","msg":"trace[508863477] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"113.048887ms","start":"2026-04-08T06:25:36.996063Z","end":"2026-04-08T06:25:37.109111Z","steps":["trace[508863477] 'process raft request' (duration: 112.886703ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:39.377794Z","caller":"traceutil/trace.go:171","msg":"trace[2003092207] linearizableReadLoop","detail":"{readStateIndex:5573; appliedIndex:5572; }","duration":"227.33834ms","start":"2026-04-08T06:25:39.150433Z","end":"2026-04-08T06:25:39.377771Z","steps":["trace[2003092207] 'read index received' (duration: 227.170026ms)","trace[2003092207] 'applied index is now lower than readState.Index' (duration: 167.314µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:25:39.377851Z","caller":"traceutil/trace.go:171","msg":"trace[1692257876] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"262.974095ms","start":"2026-04-08T06:25:39.114839Z","end":"2026-04-08T06:25:39.377814Z","steps":["trace[1692257876] 'process raft request' (duration: 262.789991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:39.37804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.598385ms","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-04-08T06:25:39.378077Z","caller":"traceutil/trace.go:171","msg":"trace[477331248] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5375; }","duration":"227.687138ms","start":"2026-04-08T06:25:39.15038Z","end":"2026-04-08T06:25:39.378067Z","steps":["trace[477331248] 'agreement among raft nodes before linearized reading' (duration: 227.542854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:39.378165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.134915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-08T06:25:39.37825Z","caller":"traceutil/trace.go:171","msg":"trace[779561059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5375; }","duration":"162.251178ms","start":"2026-04-08T06:25:39.215977Z","end":"2026-04-08T06:25:39.378228Z","steps":["trace[779561059] 'agreement among raft nodes before linearized reading' (duration: 162.019763ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:40.287584Z","caller":"traceutil/trace.go:171","msg":"trace[286103424] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"215.650415ms","start":"2026-04-08T06:25:40.071892Z","end":"2026-04-08T06:25:40.287543Z","steps":["trace[286103424] 'process raft request' (duration: 215.383189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:52.165745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.532367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-08T06:25:52.165839Z","caller":"traceutil/trace.go:171","msg":"trace[1811728906] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5560; }","duration":"900.683891ms","start":"2026-04-08T06:25:51.265135Z","end":"2026-04-08T06:25:52.165819Z","steps":["trace[1811728906] 'range keys from in-memory index tree' (duration: 900.321052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:52.165885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.265111Z","time spent":"900.763963ms","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-08T06:25:52.166446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.645153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:25:52.166645Z","caller":"traceutil/trace.go:171","msg":"trace[2128913428] linearizableReadLoop","detail":"{readStateIndex:5763; appliedIndex:5762; }","duration":"714.956372ms","start":"2026-04-08T06:25:51.451667Z","end":"2026-04-08T06:25:52.166623Z","steps":["trace[2128913428] 'read index received' (duration: 177.011181ms)","trace[2128913428] 'applied index is now lower than readState.Index' (duration: 537.94369ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:25:52.166957Z","caller":"traceutil/trace.go:171","msg":"trace[21258092] transaction","detail":"{read_only:false; response_revision:5562; number_of_response:1; }","duration":"683.046521ms","start":"2026-04-08T06:25:51.483895Z","end":"2026-04-08T06:25:52.166941Z","steps":["trace[21258092] 'process raft request' (duration: 682.671202ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:52.166837Z","caller":"traceutil/trace.go:171","msg":"trace[1747700195] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"720.800343ms","start":"2026-04-08T06:25:51.445996Z","end":"2026-04-08T06:25:52.166796Z","steps":["trace[1747700195] 'process raft request' (duration: 182.671329ms)","trace[1747700195] 'compare' (duration: 537.440669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:25:52.167053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.483875Z","time spent":"683.111412ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:25:52.167174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.07017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T06:25:52.16719Z","caller":"traceutil/trace.go:171","msg":"trace[1630426208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5563; }","duration":"663.10754ms","start":"2026-04-08T06:25:51.504077Z","end":"2026-04-08T06:25:52.167185Z","steps":["trace[1630426208] 'agreement among raft nodes before linearized reading' (duration: 663.044069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:52.167205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.504052Z","time spent":"663.148871ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T06:25:52.167203Z","caller":"traceutil/trace.go:171","msg":"trace[807316251] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"682.833996ms","start":"2026-04-08T06:25:51.484356Z","end":"2026-04-08T06:25:52.16719Z","steps":["trace[807316251] 'process raft request' (duration: 682.529599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:52.167217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.445968Z","time spent":"721.108541ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:25:52.167316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.484331Z","time spent":"682.912077ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:25:52.167322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.30968ms","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":"warn","ts":"2026-04-08T06:25:52.167409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.912238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-08T06:25:52.167424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.337638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-08T06:25:52.167407Z","caller":"traceutil/trace.go:171","msg":"trace[1257075147] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5563; }","duration":"669.388942ms","start":"2026-04-08T06:25:51.497974Z","end":"2026-04-08T06:25:52.167363Z","steps":["trace[1257075147] 'agreement among raft nodes before linearized reading' (duration: 669.215388ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:25:52.167445Z","caller":"traceutil/trace.go:171","msg":"trace[221206342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5563; }","duration":"672.359899ms","start":"2026-04-08T06:25:51.49508Z","end":"2026-04-08T06:25:52.16744Z","steps":["trace[221206342] 'agreement among raft nodes before linearized reading' (duration: 672.303447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:52.167415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.636493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-08T06:25:52.16745Z","caller":"traceutil/trace.go:171","msg":"trace[1235914333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5563; }","duration":"663.97744ms","start":"2026-04-08T06:25:51.503461Z","end":"2026-04-08T06:25:52.167439Z","steps":["trace[1235914333] 'agreement among raft nodes before linearized reading' (duration: 663.869197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:52.167462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.49502Z","time spent":"672.438641ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-08T06:25:52.167471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.497964Z","time spent":"669.493274ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T06:25:52.167482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.50345Z","time spent":"664.02442ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T06:25:52.167486Z","caller":"traceutil/trace.go:171","msg":"trace[174572857] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5563; }","duration":"345.749265ms","start":"2026-04-08T06:25:51.821719Z","end":"2026-04-08T06:25:52.167468Z","steps":["trace[174572857] 'agreement among raft nodes before linearized reading' (duration: 345.540931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:52.167487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.82284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-08T06:25:52.167513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.772504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-08T06:25:52.167526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.821697Z","time spent":"345.821056ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-08T06:25:52.167549Z","caller":"traceutil/trace.go:171","msg":"trace[1849593400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5563; }","duration":"674.832125ms","start":"2026-04-08T06:25:51.492707Z","end":"2026-04-08T06:25:52.167539Z","steps":["trace[1849593400] 'agreement among raft nodes before linearized reading' (duration: 674.375665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:52.167579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.492696Z","time spent":"674.877566ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T06:25:52.167538Z","caller":"traceutil/trace.go:171","msg":"trace[180114205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5563; }","duration":"715.912173ms","start":"2026-04-08T06:25:51.451611Z","end":"2026-04-08T06:25:52.167523Z","steps":["trace[180114205] 'agreement among raft nodes before linearized reading' (duration: 715.32824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:25:52.167685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:25:51.451589Z","time spent":"716.080637ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T06:26:36.57Z","caller":"traceutil/trace.go:171","msg":"trace[1773517261] transaction","detail":"{read_only:false; response_revision:5938; number_of_response:1; }","duration":"118.79158ms","start":"2026-04-08T06:26:36.451184Z","end":"2026-04-08T06:26:36.569975Z","steps":["trace[1773517261] 'process raft request' (duration: 81.784885ms)","trace[1773517261] 'compare' (duration: 36.601246ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:26:36.570315Z","caller":"traceutil/trace.go:171","msg":"trace[1779798580] transaction","detail":"{read_only:false; response_revision:5939; number_of_response:1; }","duration":"116.738444ms","start":"2026-04-08T06:26:36.453558Z","end":"2026-04-08T06:26:36.570296Z","steps":["trace[1779798580] 'process raft request' (duration: 116.280793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:26:37.028413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.091106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zmjmb\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T06:26:37.02851Z","caller":"traceutil/trace.go:171","msg":"trace[1657700027] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zmjmb; range_end:; response_count:1; response_revision:5943; }","duration":"158.239549ms","start":"2026-04-08T06:26:36.870251Z","end":"2026-04-08T06:26:37.02849Z","steps":["trace[1657700027] 'range keys from in-memory index tree' (duration: 157.900872ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:26:48.641296Z","caller":"traceutil/trace.go:171","msg":"trace[1087588112] transaction","detail":"{read_only:false; response_revision:6027; number_of_response:1; }","duration":"106.568514ms","start":"2026-04-08T06:26:48.534703Z","end":"2026-04-08T06:26:48.641272Z","steps":["trace[1087588112] 'process raft request' (duration: 106.195555ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:26:55.144032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3011} {"level":"info","ts":"2026-04-08T06:26:55.231122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3011,"took":"85.259884ms","hash":491432663,"current-db-size-bytes":58261504,"current-db-size":"58 MB","current-db-size-in-use-bytes":31547392,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-08T06:26:55.231188Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":491432663,"revision":3011,"compact-revision":-1} {"level":"warn","ts":"2026-04-08T06:31:34.913563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.920732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:31:34.913805Z","caller":"traceutil/trace.go:171","msg":"trace[271075355] transaction","detail":"{read_only:false; response_revision:8176; number_of_response:1; }","duration":"659.971726ms","start":"2026-04-08T06:31:34.253812Z","end":"2026-04-08T06:31:34.913784Z","steps":["trace[271075355] 'process raft request' (duration: 659.875164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:31:34.913882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:34.253792Z","time spent":"660.053567ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:31:34.91392Z","caller":"traceutil/trace.go:171","msg":"trace[473780336] transaction","detail":"{read_only:false; response_revision:8175; number_of_response:1; }","duration":"662.756579ms","start":"2026-04-08T06:31:34.251127Z","end":"2026-04-08T06:31:34.913884Z","steps":["trace[473780336] 'process raft request' (duration: 561.406236ms)","trace[473780336] 'compare' (duration: 100.608355ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:31:34.914128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:34.251114Z","time spent":"662.919252ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:31:35.150186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.757864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:31:35.150379Z","caller":"traceutil/trace.go:171","msg":"trace[2016176506] transaction","detail":"{read_only:false; response_revision:8177; number_of_response:1; }","duration":"585.355948ms","start":"2026-04-08T06:31:34.56498Z","end":"2026-04-08T06:31:35.150336Z","steps":["trace[2016176506] 'process raft request' (duration: 482.328207ms)","trace[2016176506] 'compare' (duration: 102.606961ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:31:35.150337Z","caller":"traceutil/trace.go:171","msg":"trace[568336733] linearizableReadLoop","detail":"{readStateIndex:8460; appliedIndex:8459; }","duration":"256.298485ms","start":"2026-04-08T06:31:34.894012Z","end":"2026-04-08T06:31:35.150311Z","steps":["trace[568336733] 'read index received' (duration: 153.308366ms)","trace[568336733] 'applied index is now lower than readState.Index' (duration: 102.989009ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:31:35.15056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:34.564953Z","time spent":"585.50453ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:31:35.150616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.599542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zmjmb\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T06:31:35.150675Z","caller":"traceutil/trace.go:171","msg":"trace[571607308] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zmjmb; range_end:; response_count:1; response_revision:8177; }","duration":"256.700125ms","start":"2026-04-08T06:31:34.893958Z","end":"2026-04-08T06:31:35.150658Z","steps":["trace[571607308] 'agreement among raft nodes before linearized reading' (duration: 256.518761ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:45.320064Z","caller":"traceutil/trace.go:171","msg":"trace[1920535269] transaction","detail":"{read_only:false; response_revision:8232; number_of_response:1; }","duration":"103.402188ms","start":"2026-04-08T06:31:45.216635Z","end":"2026-04-08T06:31:45.320037Z","steps":["trace[1920535269] 'process raft request' (duration: 103.173783ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:46.657262Z","caller":"traceutil/trace.go:171","msg":"trace[1696290367] linearizableReadLoop","detail":"{readStateIndex:8523; appliedIndex:8522; }","duration":"479.440903ms","start":"2026-04-08T06:31:46.177792Z","end":"2026-04-08T06:31:46.657233Z","steps":["trace[1696290367] 'read index received' (duration: 479.402582ms)","trace[1696290367] 'applied index is now lower than readState.Index' (duration: 36.741µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:31:46.657354Z","caller":"traceutil/trace.go:171","msg":"trace[1549575369] transaction","detail":"{read_only:false; response_revision:8237; number_of_response:1; }","duration":"521.627847ms","start":"2026-04-08T06:31:46.135689Z","end":"2026-04-08T06:31:46.657317Z","steps":["trace[1549575369] 'process raft request' (duration: 521.385071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:31:46.657583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:46.135673Z","time spent":"521.761291ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:31:46.657596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.77046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:31:46.65769Z","caller":"traceutil/trace.go:171","msg":"trace[1363574488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8237; }","duration":"479.913594ms","start":"2026-04-08T06:31:46.177753Z","end":"2026-04-08T06:31:46.657667Z","steps":["trace[1363574488] 'agreement among raft nodes before linearized reading' (duration: 479.603727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:31:46.657764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:46.177738Z","time spent":"480.008395ms","remote":"127.0.0.1:40128","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":"info","ts":"2026-04-08T06:31:46.891835Z","caller":"traceutil/trace.go:171","msg":"trace[723517875] transaction","detail":"{read_only:false; response_revision:8238; number_of_response:1; }","duration":"428.279265ms","start":"2026-04-08T06:31:46.463532Z","end":"2026-04-08T06:31:46.891811Z","steps":["trace[723517875] 'process raft request' (duration: 352.170554ms)","trace[723517875] 'compare' (duration: 75.993498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:31:46.891949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.918581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:31:46.89197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:46.463505Z","time spent":"428.396938ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:31:46.891835Z","caller":"traceutil/trace.go:171","msg":"trace[436059616] linearizableReadLoop","detail":"{readStateIndex:8524; appliedIndex:8523; }","duration":"234.460532ms","start":"2026-04-08T06:31:46.657344Z","end":"2026-04-08T06:31:46.891804Z","steps":["trace[436059616] 'read index received' (duration: 158.341681ms)","trace[436059616] 'applied index is now lower than readState.Index' (duration: 76.117651ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:31:46.892005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.21429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T06:31:46.891996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.100005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-08T06:31:46.892072Z","caller":"traceutil/trace.go:171","msg":"trace[1015915699] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:8238; }","duration":"270.322532ms","start":"2026-04-08T06:31:46.621723Z","end":"2026-04-08T06:31:46.892046Z","steps":["trace[1015915699] 'agreement among raft nodes before linearized reading' (duration: 270.20393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:31:46.892098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.237152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T06:31:46.892118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.716248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T06:31:46.892076Z","caller":"traceutil/trace.go:171","msg":"trace[2088079688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8238; }","duration":"586.215107ms","start":"2026-04-08T06:31:46.305844Z","end":"2026-04-08T06:31:46.892059Z","steps":["trace[2088079688] 'agreement among raft nodes before linearized reading' (duration: 586.035133ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:46.89217Z","caller":"traceutil/trace.go:171","msg":"trace[2025212384] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8238; }","duration":"586.771049ms","start":"2026-04-08T06:31:46.305385Z","end":"2026-04-08T06:31:46.892156Z","steps":["trace[2025212384] 'agreement among raft nodes before linearized reading' (duration: 586.668306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:31:46.89224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:46.30532Z","time spent":"586.894412ms","remote":"127.0.0.1:45262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":13,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:31:46.892235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:46.30583Z","time spent":"586.37836ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-08T06:31:46.892004Z","caller":"traceutil/trace.go:171","msg":"trace[911482266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8238; }","duration":"659.011863ms","start":"2026-04-08T06:31:46.232977Z","end":"2026-04-08T06:31:46.891989Z","steps":["trace[911482266] 'agreement among raft nodes before linearized reading' (duration: 658.910461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:31:46.891997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.53716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-08T06:31:46.892393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:46.232955Z","time spent":"659.423993ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T06:31:46.892156Z","caller":"traceutil/trace.go:171","msg":"trace[1685289914] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:8238; }","duration":"496.331094ms","start":"2026-04-08T06:31:46.395808Z","end":"2026-04-08T06:31:46.892139Z","steps":["trace[1685289914] 'agreement among raft nodes before linearized reading' (duration: 496.234612ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:46.892426Z","caller":"traceutil/trace.go:171","msg":"trace[1807271438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8238; }","duration":"573.97938ms","start":"2026-04-08T06:31:46.318422Z","end":"2026-04-08T06:31:46.892401Z","steps":["trace[1807271438] 'agreement among raft nodes before linearized reading' (duration: 573.403006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:31:46.892462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:46.39579Z","time spent":"496.657032ms","remote":"127.0.0.1:41038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":31,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:31:46.892484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:31:46.318409Z","time spent":"574.057442ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-08T06:31:46.898249Z","caller":"traceutil/trace.go:171","msg":"trace[541752642] transaction","detail":"{read_only:false; response_revision:8239; number_of_response:1; }","duration":"235.874235ms","start":"2026-04-08T06:31:46.662349Z","end":"2026-04-08T06:31:46.898223Z","steps":["trace[541752642] 'process raft request' (duration: 235.702411ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:47.196755Z","caller":"traceutil/trace.go:171","msg":"trace[773995883] transaction","detail":"{read_only:false; response_revision:8243; number_of_response:1; }","duration":"147.342572ms","start":"2026-04-08T06:31:47.049385Z","end":"2026-04-08T06:31:47.196728Z","steps":["trace[773995883] 'process raft request' (duration: 107.89847ms)","trace[773995883] 'compare' (duration: 39.37223ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:31:47.746317Z","caller":"traceutil/trace.go:171","msg":"trace[292099523] transaction","detail":"{read_only:false; response_revision:8245; number_of_response:1; }","duration":"120.214149ms","start":"2026-04-08T06:31:47.626077Z","end":"2026-04-08T06:31:47.746291Z","steps":["trace[292099523] 'process raft request' (duration: 120.031175ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:48.782179Z","caller":"traceutil/trace.go:171","msg":"trace[1430672105] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"116.235668ms","start":"2026-04-08T06:31:48.665908Z","end":"2026-04-08T06:31:48.782143Z","steps":["trace[1430672105] 'process raft request' (duration: 116.000573ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:31:55.151425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6091} {"level":"info","ts":"2026-04-08T06:31:55.245502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6091,"took":"91.838416ms","hash":1368470612,"current-db-size-bytes":58261504,"current-db-size":"58 MB","current-db-size-in-use-bytes":23535616,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-08T06:31:55.245596Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1368470612,"revision":6091,"compact-revision":3011} {"level":"info","ts":"2026-04-08T06:32:13.036082Z","caller":"traceutil/trace.go:171","msg":"trace[1953836266] linearizableReadLoop","detail":"{readStateIndex:8722; appliedIndex:8721; }","duration":"104.992174ms","start":"2026-04-08T06:32:12.931055Z","end":"2026-04-08T06:32:13.036047Z","steps":["trace[1953836266] 'read index received' (duration: 104.638816ms)","trace[1953836266] 'applied index is now lower than readState.Index' (duration: 351.858µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:32:13.036193Z","caller":"traceutil/trace.go:171","msg":"trace[413409894] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"123.795799ms","start":"2026-04-08T06:32:12.91236Z","end":"2026-04-08T06:32:13.036156Z","steps":["trace[413409894] 'process raft request' (duration: 123.432341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:13.036358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-08T06:32:13.036451Z","caller":"traceutil/trace.go:171","msg":"trace[920512015] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8428; }","duration":"105.434193ms","start":"2026-04-08T06:32:12.930995Z","end":"2026-04-08T06:32:13.036429Z","steps":["trace[920512015] 'agreement among raft nodes before linearized reading' (duration: 105.180798ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:16.184772Z","caller":"traceutil/trace.go:171","msg":"trace[644935846] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"331.662669ms","start":"2026-04-08T06:32:15.853069Z","end":"2026-04-08T06:32:16.184732Z","steps":["trace[644935846] 'process raft request' (duration: 331.451355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:16.184999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:15.853036Z","time spent":"331.849074ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:32:16.495887Z","caller":"traceutil/trace.go:171","msg":"trace[1951828481] transaction","detail":"{read_only:false; response_revision:8449; number_of_response:1; }","duration":"642.767645ms","start":"2026-04-08T06:32:15.853072Z","end":"2026-04-08T06:32:16.49584Z","steps":["trace[1951828481] 'process raft request' (duration: 641.912926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:16.496004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.032388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:32:16.496077Z","caller":"traceutil/trace.go:171","msg":"trace[1166213287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8450; }","duration":"263.177522ms","start":"2026-04-08T06:32:16.232888Z","end":"2026-04-08T06:32:16.496065Z","steps":["trace[1166213287] 'agreement among raft nodes before linearized reading' (duration: 263.045379ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:16.495923Z","caller":"traceutil/trace.go:171","msg":"trace[489616787] transaction","detail":"{read_only:false; response_revision:8450; number_of_response:1; }","duration":"640.109655ms","start":"2026-04-08T06:32:15.855762Z","end":"2026-04-08T06:32:16.495872Z","steps":["trace[489616787] 'process raft request' (duration: 639.707646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:16.496111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:15.853043Z","time spent":"642.96112ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:32:16.495638Z","caller":"traceutil/trace.go:171","msg":"trace[980226381] linearizableReadLoop","detail":"{readStateIndex:8745; appliedIndex:8743; }","duration":"262.64858ms","start":"2026-04-08T06:32:16.23296Z","end":"2026-04-08T06:32:16.495608Z","steps":["trace[980226381] 'read index received' (duration: 261.924744ms)","trace[980226381] 'applied index is now lower than readState.Index' (duration: 722.526µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:32:16.496265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:15.855749Z","time spent":"640.384012ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:32:29.435695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.970068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T06:32:29.435991Z","caller":"traceutil/trace.go:171","msg":"trace[133124859] linearizableReadLoop","detail":"{readStateIndex:8854; appliedIndex:8852; }","duration":"713.781171ms","start":"2026-04-08T06:32:28.722189Z","end":"2026-04-08T06:32:29.43597Z","steps":["trace[133124859] 'read index received' (duration: 44.429645ms)","trace[133124859] 'applied index is now lower than readState.Index' (duration: 669.350256ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:32:29.436335Z","caller":"traceutil/trace.go:171","msg":"trace[2124588478] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8553; }","duration":"714.356615ms","start":"2026-04-08T06:32:28.721952Z","end":"2026-04-08T06:32:29.436309Z","steps":["trace[2124588478] 'process raft request' (duration: 713.897895ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:29.436348Z","caller":"traceutil/trace.go:171","msg":"trace[1230131041] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"716.046812ms","start":"2026-04-08T06:32:28.720258Z","end":"2026-04-08T06:32:29.436305Z","steps":["trace[1230131041] 'process raft request' (duration: 46.336907ms)","trace[1230131041] 'compare' (duration: 668.296663ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:32:29.43641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.342981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T06:32:29.436463Z","caller":"traceutil/trace.go:171","msg":"trace[1495250422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8553; }","duration":"204.455874ms","start":"2026-04-08T06:32:29.231994Z","end":"2026-04-08T06:32:29.43645Z","steps":["trace[1495250422] 'agreement among raft nodes before linearized reading' (duration: 204.340391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:29.436486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:28.720237Z","time spent":"716.178375ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4760,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:32:29.43649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:28.721942Z","time spent":"714.463417ms","remote":"127.0.0.1:40058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":4810,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:32:29.436499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.338471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-08T06:32:29.436497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.95247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-08T06:32:29.436514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.011072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T06:32:29.43655Z","caller":"traceutil/trace.go:171","msg":"trace[199158435] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:8553; }","duration":"694.402113ms","start":"2026-04-08T06:32:28.742135Z","end":"2026-04-08T06:32:29.436537Z","steps":["trace[199158435] 'agreement among raft nodes before linearized reading' (duration: 694.198488ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:29.436565Z","caller":"traceutil/trace.go:171","msg":"trace[670631152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8553; }","duration":"318.055842ms","start":"2026-04-08T06:32:29.118488Z","end":"2026-04-08T06:32:29.436544Z","steps":["trace[670631152] 'agreement among raft nodes before linearized reading' (duration: 317.839297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:29.436535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.211651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T06:32:29.436584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:28.74213Z","time spent":"694.446124ms","remote":"127.0.0.1:39868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.250\" "} {"level":"warn","ts":"2026-04-08T06:32:29.436557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.994618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-08T06:32:29.436601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:29.118464Z","time spent":"318.129634ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-08T06:32:29.436617Z","caller":"traceutil/trace.go:171","msg":"trace[2015264814] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8553; }","duration":"423.436065ms","start":"2026-04-08T06:32:29.013164Z","end":"2026-04-08T06:32:29.4366Z","steps":["trace[2015264814] 'agreement among raft nodes before linearized reading' (duration: 423.166219ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:29.436589Z","caller":"traceutil/trace.go:171","msg":"trace[1491325486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8553; }","duration":"288.131195ms","start":"2026-04-08T06:32:29.148437Z","end":"2026-04-08T06:32:29.436568Z","steps":["trace[1491325486] 'agreement among raft nodes before linearized reading' (duration: 287.90227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:29.436654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:29.013132Z","time spent":"423.514457ms","remote":"127.0.0.1:55926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"info","ts":"2026-04-08T06:32:29.436649Z","caller":"traceutil/trace.go:171","msg":"trace[1298962432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8553; }","duration":"275.185203ms","start":"2026-04-08T06:32:29.161443Z","end":"2026-04-08T06:32:29.436628Z","steps":["trace[1298962432] 'agreement among raft nodes before linearized reading' (duration: 274.889586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:29.436632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.888193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-08T06:32:29.436724Z","caller":"traceutil/trace.go:171","msg":"trace[1295904661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8553; }","duration":"247.024517ms","start":"2026-04-08T06:32:29.189676Z","end":"2026-04-08T06:32:29.436701Z","steps":["trace[1295904661] 'agreement among raft nodes before linearized reading' (duration: 246.729649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:29.437099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.907337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-08T06:32:29.437178Z","caller":"traceutil/trace.go:171","msg":"trace[800275374] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8553; }","duration":"715.073421ms","start":"2026-04-08T06:32:28.722093Z","end":"2026-04-08T06:32:29.437167Z","steps":["trace[800275374] 'agreement among raft nodes before linearized reading' (duration: 714.062488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:29.437442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:28.722074Z","time spent":"715.128263ms","remote":"127.0.0.1:39958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":555,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"info","ts":"2026-04-08T06:32:29.831131Z","caller":"traceutil/trace.go:171","msg":"trace[763442994] linearizableReadLoop","detail":"{readStateIndex:8868; appliedIndex:8866; }","duration":"184.460012ms","start":"2026-04-08T06:32:29.646638Z","end":"2026-04-08T06:32:29.831098Z","steps":["trace[763442994] 'read index received' (duration: 25.378335ms)","trace[763442994] 'applied index is now lower than readState.Index' (duration: 159.079897ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T06:32:29.831375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.454017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T06:32:29.83145Z","caller":"traceutil/trace.go:171","msg":"trace[1551577348] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8566; }","duration":"129.608451ms","start":"2026-04-08T06:32:29.701824Z","end":"2026-04-08T06:32:29.831433Z","steps":["trace[1551577348] 'agreement among raft nodes before linearized reading' (duration: 129.465198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:29.8315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.82832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zmjmb\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-08T06:32:29.831603Z","caller":"traceutil/trace.go:171","msg":"trace[771783920] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zmjmb; range_end:; response_count:1; response_revision:8566; }","duration":"184.995344ms","start":"2026-04-08T06:32:29.646583Z","end":"2026-04-08T06:32:29.831578Z","steps":["trace[771783920] 'agreement among raft nodes before linearized reading' (duration: 184.662086ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:29.831952Z","caller":"traceutil/trace.go:171","msg":"trace[454963849] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"215.151805ms","start":"2026-04-08T06:32:29.616039Z","end":"2026-04-08T06:32:29.83119Z","steps":["trace[454963849] 'process raft request' (duration: 214.888359ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:30.168116Z","caller":"traceutil/trace.go:171","msg":"trace[154487454] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"104.095615ms","start":"2026-04-08T06:32:30.063989Z","end":"2026-04-08T06:32:30.168084Z","steps":["trace[154487454] 'process raft request' (duration: 103.899151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:30.546863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.335891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-04-08T06:32:30.546948Z","caller":"traceutil/trace.go:171","msg":"trace[1199619912] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8569; }","duration":"250.512006ms","start":"2026-04-08T06:32:30.296415Z","end":"2026-04-08T06:32:30.546927Z","steps":["trace[1199619912] 'range keys from in-memory index tree' (duration: 250.131827ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:30.928393Z","caller":"traceutil/trace.go:171","msg":"trace[259357443] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"358.409275ms","start":"2026-04-08T06:32:30.569948Z","end":"2026-04-08T06:32:30.928358Z","steps":["trace[259357443] 'process raft request' (duration: 358.1979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:30.928605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:30.569922Z","time spent":"358.570189ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T06:32:31.009948Z","caller":"traceutil/trace.go:171","msg":"trace[1238361015] linearizableReadLoop","detail":"{readStateIndex:8873; appliedIndex:8871; }","duration":"426.76642ms","start":"2026-04-08T06:32:30.58316Z","end":"2026-04-08T06:32:31.009926Z","steps":["trace[1238361015] 'read index received' (duration: 344.963101ms)","trace[1238361015] 'applied index is now lower than readState.Index' (duration: 81.802379ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:32:31.01011Z","caller":"traceutil/trace.go:171","msg":"trace[275184237] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"439.53935ms","start":"2026-04-08T06:32:30.570512Z","end":"2026-04-08T06:32:31.010051Z","steps":["trace[275184237] 'process raft request' (duration: 439.250414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:31.010251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.707572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T06:32:31.010294Z","caller":"traceutil/trace.go:171","msg":"trace[1006085533] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:8571; }","duration":"236.777724ms","start":"2026-04-08T06:32:30.773505Z","end":"2026-04-08T06:32:31.010282Z","steps":["trace[1006085533] 'agreement among raft nodes before linearized reading' (duration: 236.679962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:31.010316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:30.570489Z","time spent":"439.710553ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T06:32:31.010149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.953904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T06:32:31.010491Z","caller":"traceutil/trace.go:171","msg":"trace[1177740202] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8571; }","duration":"427.329623ms","start":"2026-04-08T06:32:30.583125Z","end":"2026-04-08T06:32:31.010454Z","steps":["trace[1177740202] 'agreement among raft nodes before linearized reading' (duration: 426.944535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:31.010489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.614778ms","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":"warn","ts":"2026-04-08T06:32:31.010536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.66928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"warn","ts":"2026-04-08T06:32:31.010581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:30.583103Z","time spent":"427.453375ms","remote":"127.0.0.1:40296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-08T06:32:31.010582Z","caller":"traceutil/trace.go:171","msg":"trace[1097535517] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8571; }","duration":"392.766592ms","start":"2026-04-08T06:32:30.617794Z","end":"2026-04-08T06:32:31.010561Z","steps":["trace[1097535517] 'agreement among raft nodes before linearized reading' (duration: 392.609168ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:32:31.010688Z","caller":"traceutil/trace.go:171","msg":"trace[1206204486] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8571; }","duration":"404.819314ms","start":"2026-04-08T06:32:30.60581Z","end":"2026-04-08T06:32:31.01063Z","steps":["trace[1206204486] 'agreement among raft nodes before linearized reading' (duration: 404.355474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:32:31.010774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:30.617774Z","time spent":"392.917915ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-08T06:32:31.010832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T06:32:30.605789Z","time spent":"404.985397ms","remote":"127.0.0.1:39946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1845,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-04-08T06:33:17.934941Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002485.wal"} {"level":"info","ts":"2026-04-08T06:33:41.999425Z","caller":"traceutil/trace.go:171","msg":"trace[1481078380] transaction","detail":"{read_only:false; response_revision:9223; number_of_response:1; }","duration":"156.588982ms","start":"2026-04-08T06:33:41.842806Z","end":"2026-04-08T06:33:41.999395Z","steps":["trace[1481078380] 'process raft request' (duration: 151.829474ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:33:41.999582Z","caller":"traceutil/trace.go:171","msg":"trace[1989524192] linearizableReadLoop","detail":"{readStateIndex:9544; appliedIndex:9541; }","duration":"152.320265ms","start":"2026-04-08T06:33:41.847239Z","end":"2026-04-08T06:33:41.999559Z","steps":["trace[1989524192] 'read index received' (duration: 18.838667ms)","trace[1989524192] 'applied index is now lower than readState.Index' (duration: 133.480568ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:33:41.999737Z","caller":"traceutil/trace.go:171","msg":"trace[937561977] transaction","detail":"{read_only:false; response_revision:9224; number_of_response:1; }","duration":"153.903612ms","start":"2026-04-08T06:33:41.845781Z","end":"2026-04-08T06:33:41.999684Z","steps":["trace[937561977] 'process raft request' (duration: 153.558644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:33:41.999797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.53847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T06:33:41.999821Z","caller":"traceutil/trace.go:171","msg":"trace[140264706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9224; }","duration":"152.600412ms","start":"2026-04-08T06:33:41.847213Z","end":"2026-04-08T06:33:41.999814Z","steps":["trace[140264706] 'agreement among raft nodes before linearized reading' (duration: 152.472929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:33:42.000102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.513407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-08T06:33:42.000238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.957011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-08T06:33:42.000389Z","caller":"traceutil/trace.go:171","msg":"trace[796304671] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:9224; }","duration":"139.173356ms","start":"2026-04-08T06:33:41.861188Z","end":"2026-04-08T06:33:42.000361Z","steps":["trace[796304671] 'agreement among raft nodes before linearized reading' (duration: 138.694815ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:33:42.000194Z","caller":"traceutil/trace.go:171","msg":"trace[1413248934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9224; }","duration":"151.64856ms","start":"2026-04-08T06:33:41.848523Z","end":"2026-04-08T06:33:42.000172Z","steps":["trace[1413248934] 'agreement among raft nodes before linearized reading' (duration: 151.253441ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:33:42.186892Z","caller":"traceutil/trace.go:171","msg":"trace[63122745] transaction","detail":"{read_only:false; response_revision:9228; number_of_response:1; }","duration":"103.546539ms","start":"2026-04-08T06:33:42.083311Z","end":"2026-04-08T06:33:42.186858Z","steps":["trace[63122745] 'process raft request' (duration: 92.540319ms)","trace[63122745] 'compare' (duration: 10.850757ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:34:30.763892Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T06:34:30.770993Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T06:34:30.771167Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T06:36:00.903581Z","caller":"traceutil/trace.go:171","msg":"trace[1603136608] linearizableReadLoop","detail":"{readStateIndex:10734; appliedIndex:10732; }","duration":"103.183159ms","start":"2026-04-08T06:36:00.800359Z","end":"2026-04-08T06:36:00.903542Z","steps":["trace[1603136608] 'read index received' (duration: 35.70886ms)","trace[1603136608] 'applied index is now lower than readState.Index' (duration: 67.472849ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T06:36:00.903655Z","caller":"traceutil/trace.go:171","msg":"trace[1727287569] transaction","detail":"{read_only:false; response_revision:10377; number_of_response:1; }","duration":"113.482029ms","start":"2026-04-08T06:36:00.790127Z","end":"2026-04-08T06:36:00.903609Z","steps":["trace[1727287569] 'process raft request' (duration: 113.245574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T06:36:00.904282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.880666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T06:36:00.904454Z","caller":"traceutil/trace.go:171","msg":"trace[907897958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10377; }","duration":"104.06969ms","start":"2026-04-08T06:36:00.800305Z","end":"2026-04-08T06:36:00.904375Z","steps":["trace[907897958] 'agreement among raft nodes before linearized reading' (duration: 103.461885ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T06:36:55.159645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8288} {"level":"info","ts":"2026-04-08T06:36:55.249141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8288,"took":"84.671286ms","hash":1079488175,"current-db-size-bytes":58261504,"current-db-size":"58 MB","current-db-size-in-use-bytes":25223168,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-08T06:36:55.249236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1079488175,"revision":8288,"compact-revision":6091} {"level":"info","ts":"2026-04-08T06:41:55.165259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10709} {"level":"info","ts":"2026-04-08T06:41:55.246036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10709,"took":"77.852947ms","hash":2774853808,"current-db-size-bytes":58261504,"current-db-size":"58 MB","current-db-size-in-use-bytes":20434944,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-08T06:41:55.246111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2774853808,"revision":10709,"compact-revision":8288}