{"level":"warn","ts":"2026-04-20T02:06:40.3728Z","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-20T02:06:40.37295Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-20T02:06:40.373252Z","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-20T02:06:40.373266Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-20T02:06:40.373293Z","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-20T02:06:40.374259Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-20T02:06:40.374383Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-20T02:06:40.378467Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.786827ms"} {"level":"info","ts":"2026-04-20T02:06:40.3922Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-20T02:06:40.392329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T02:06:40.392359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-20T02:06:40.392373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T02:06:40.392386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-20T02:06:40.392438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-20T02:06:40.398759Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T02:06:40.400216Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T02:06:40.401651Z","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-20T02:06:40.40405Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T02:06:40.404249Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T02:06:40.404589Z","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-20T02:06:40.404716Z","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-20T02:06:40.404735Z","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-20T02:06:40.407934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-20T02:06:40.408127Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-20T02:06:40.409441Z","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-20T02:06:40.4096Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-20T02:06:40.40968Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-20T02:06:40.409783Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T02:06:40.409823Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T02:06:41.393237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T02:06:41.393331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T02:06:41.393377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-20T02:06:41.3934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-20T02:06:41.393414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-20T02:06:41.393438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-20T02:06:41.393465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-20T02:06:41.394608Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T02:06:41.394621Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T02:06:41.39464Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:06:41.394765Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T02:06:41.395101Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T02:06:41.395209Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T02:06:41.395626Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:06:41.395783Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:06:41.395847Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:06:41.397667Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-20T02:06:41.398622Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T02:07:04.883223Z","caller":"traceutil/trace.go:171","msg":"trace[1308709311] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"234.94376ms","start":"2026-04-20T02:07:04.648241Z","end":"2026-04-20T02:07:04.883185Z","steps":["trace[1308709311] 'process raft request' (duration: 179.770123ms)","trace[1308709311] 'compare' (duration: 54.98023ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:07:13.310364Z","caller":"traceutil/trace.go:171","msg":"trace[1259422842] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"126.642934ms","start":"2026-04-20T02:07:13.183693Z","end":"2026-04-20T02:07:13.310336Z","steps":["trace[1259422842] 'process raft request' (duration: 126.474678ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.317804Z","caller":"traceutil/trace.go:171","msg":"trace[1609316136] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"124.970478ms","start":"2026-04-20T02:07:13.192802Z","end":"2026-04-20T02:07:13.317772Z","steps":["trace[1609316136] 'process raft request' (duration: 124.785552ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.317843Z","caller":"traceutil/trace.go:171","msg":"trace[1634120895] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"124.832283ms","start":"2026-04-20T02:07:13.19297Z","end":"2026-04-20T02:07:13.317802Z","steps":["trace[1634120895] 'process raft request' (duration: 124.739ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.317958Z","caller":"traceutil/trace.go:171","msg":"trace[1099715225] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"116.818558ms","start":"2026-04-20T02:07:13.201077Z","end":"2026-04-20T02:07:13.317896Z","steps":["trace[1099715225] 'process raft request' (duration: 116.700294ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.318088Z","caller":"traceutil/trace.go:171","msg":"trace[543011784] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"115.711912ms","start":"2026-04-20T02:07:13.202367Z","end":"2026-04-20T02:07:13.318079Z","steps":["trace[543011784] 'process raft request' (duration: 115.505205ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.318062Z","caller":"traceutil/trace.go:171","msg":"trace[1998112965] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"116.402224ms","start":"2026-04-20T02:07:13.201593Z","end":"2026-04-20T02:07:13.317995Z","steps":["trace[1998112965] 'process raft request' (duration: 116.247999ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.318149Z","caller":"traceutil/trace.go:171","msg":"trace[2138566874] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"106.211547ms","start":"2026-04-20T02:07:13.211915Z","end":"2026-04-20T02:07:13.318127Z","steps":["trace[2138566874] 'process raft request' (duration: 105.99591ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.318306Z","caller":"traceutil/trace.go:171","msg":"trace[1299502944] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"102.506054ms","start":"2026-04-20T02:07:13.215788Z","end":"2026-04-20T02:07:13.318294Z","steps":["trace[1299502944] 'process raft request' (duration: 102.245666ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.318324Z","caller":"traceutil/trace.go:171","msg":"trace[396561841] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"116.913822ms","start":"2026-04-20T02:07:13.201218Z","end":"2026-04-20T02:07:13.318132Z","steps":["trace[396561841] 'process raft request' (duration: 116.590491ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.318351Z","caller":"traceutil/trace.go:171","msg":"trace[1481082867] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:530; }","duration":"116.87092ms","start":"2026-04-20T02:07:13.201425Z","end":"2026-04-20T02:07:13.318296Z","steps":["trace[1481082867] 'read index received' (duration: 108.813693ms)","trace[1481082867] 'applied index is now lower than readState.Index' (duration: 8.056367ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:07:13.318742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.237152ms","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-20T02:07:13.318865Z","caller":"traceutil/trace.go:171","msg":"trace[1304877386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"117.459239ms","start":"2026-04-20T02:07:13.201378Z","end":"2026-04-20T02:07:13.318837Z","steps":["trace[1304877386] 'agreement among raft nodes before linearized reading' (duration: 117.014355ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.319045Z","caller":"traceutil/trace.go:171","msg":"trace[1259954979] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"118.767643ms","start":"2026-04-20T02:07:13.200264Z","end":"2026-04-20T02:07:13.319032Z","steps":["trace[1259954979] 'process raft request' (duration: 117.48296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:13.576906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.817051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:07:13.577224Z","caller":"traceutil/trace.go:171","msg":"trace[251197475] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"155.322682ms","start":"2026-04-20T02:07:13.421858Z","end":"2026-04-20T02:07:13.57718Z","steps":["trace[251197475] 'process raft request' (duration: 52.033922ms)","trace[251197475] 'compare' (duration: 101.217282ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:07:13.577625Z","caller":"traceutil/trace.go:171","msg":"trace[1141574332] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"131.215125ms","start":"2026-04-20T02:07:13.446395Z","end":"2026-04-20T02:07:13.57761Z","steps":["trace[1141574332] 'process raft request' (duration: 130.942926ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.577645Z","caller":"traceutil/trace.go:171","msg":"trace[217289193] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"119.206857ms","start":"2026-04-20T02:07:13.458413Z","end":"2026-04-20T02:07:13.57762Z","steps":["trace[217289193] 'process raft request' (duration: 119.102454ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:13.577785Z","caller":"traceutil/trace.go:171","msg":"trace[1341389463] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"153.318707ms","start":"2026-04-20T02:07:13.424443Z","end":"2026-04-20T02:07:13.577762Z","steps":["trace[1341389463] 'process raft request' (duration: 152.711287ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:20.144721Z","caller":"traceutil/trace.go:171","msg":"trace[1120189880] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"194.638485ms","start":"2026-04-20T02:07:19.95005Z","end":"2026-04-20T02:07:20.144688Z","steps":["trace[1120189880] 'read index received' (duration: 176.67869ms)","trace[1120189880] 'applied index is now lower than readState.Index' (duration: 17.958695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:07:20.144914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.844491ms","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-20T02:07:20.145025Z","caller":"traceutil/trace.go:171","msg":"trace[485272043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:624; }","duration":"194.976386ms","start":"2026-04-20T02:07:19.950012Z","end":"2026-04-20T02:07:20.144989Z","steps":["trace[485272043] 'agreement among raft nodes before linearized reading' (duration: 194.771939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:20.280192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.701693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:20.280283Z","caller":"traceutil/trace.go:171","msg":"trace[1736803797] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:626; }","duration":"106.860678ms","start":"2026-04-20T02:07:20.173402Z","end":"2026-04-20T02:07:20.280263Z","steps":["trace[1736803797] 'agreement among raft nodes before linearized reading' (duration: 106.708753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:20.280612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.756929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:07:20.28061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.606663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:20.280692Z","caller":"traceutil/trace.go:171","msg":"trace[1607694014] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:626; }","duration":"130.871494ms","start":"2026-04-20T02:07:20.1498Z","end":"2026-04-20T02:07:20.280672Z","steps":["trace[1607694014] 'agreement among raft nodes before linearized reading' (duration: 130.730689ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:20.280722Z","caller":"traceutil/trace.go:171","msg":"trace[1433408042] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:626; }","duration":"107.781978ms","start":"2026-04-20T02:07:20.172912Z","end":"2026-04-20T02:07:20.280694Z","steps":["trace[1433408042] 'agreement among raft nodes before linearized reading' (duration: 107.566111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:20.28069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.323953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:20.280834Z","caller":"traceutil/trace.go:171","msg":"trace[869724373] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:626; }","duration":"107.534171ms","start":"2026-04-20T02:07:20.173262Z","end":"2026-04-20T02:07:20.280796Z","steps":["trace[869724373] 'agreement among raft nodes before linearized reading' (duration: 107.234121ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:20.459248Z","caller":"traceutil/trace.go:171","msg":"trace[1026968223] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"119.00881ms","start":"2026-04-20T02:07:20.340204Z","end":"2026-04-20T02:07:20.459212Z","steps":["trace[1026968223] 'process raft request' (duration: 118.71092ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:20.492966Z","caller":"traceutil/trace.go:171","msg":"trace[1914485416] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"120.21457ms","start":"2026-04-20T02:07:20.372699Z","end":"2026-04-20T02:07:20.492913Z","steps":["trace[1914485416] 'read index received' (duration: 86.257376ms)","trace[1914485416] 'applied index is now lower than readState.Index' (duration: 33.955714ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:07:20.493151Z","caller":"traceutil/trace.go:171","msg":"trace[338699076] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"127.235563ms","start":"2026-04-20T02:07:20.36588Z","end":"2026-04-20T02:07:20.493116Z","steps":["trace[338699076] 'process raft request' (duration: 126.749267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:20.493247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.512541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:20.493286Z","caller":"traceutil/trace.go:171","msg":"trace[1227245969] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"120.602723ms","start":"2026-04-20T02:07:20.372672Z","end":"2026-04-20T02:07:20.493275Z","steps":["trace[1227245969] 'agreement among raft nodes before linearized reading' (duration: 120.441568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:20.493337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.167969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:07:20.493429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.850908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:20.493457Z","caller":"traceutil/trace.go:171","msg":"trace[2011353245] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"120.365135ms","start":"2026-04-20T02:07:20.373068Z","end":"2026-04-20T02:07:20.493433Z","steps":["trace[2011353245] 'agreement among raft nodes before linearized reading' (duration: 120.133678ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:20.493479Z","caller":"traceutil/trace.go:171","msg":"trace[1044687298] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"119.936452ms","start":"2026-04-20T02:07:20.373528Z","end":"2026-04-20T02:07:20.493464Z","steps":["trace[1044687298] 'agreement among raft nodes before linearized reading' (duration: 119.828958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.049853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367128871308608,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T02:07:21.164019Z","caller":"traceutil/trace.go:171","msg":"trace[1633854305] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"614.402165ms","start":"2026-04-20T02:07:20.549569Z","end":"2026-04-20T02:07:21.163971Z","steps":["trace[1633854305] 'read index received' (duration: 544.628094ms)","trace[1633854305] 'applied index is now lower than readState.Index' (duration: 69.772681ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:07:21.164079Z","caller":"traceutil/trace.go:171","msg":"trace[453621710] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"655.385601ms","start":"2026-04-20T02:07:20.508655Z","end":"2026-04-20T02:07:21.164041Z","steps":["trace[453621710] 'process raft request' (duration: 585.684153ms)","trace[453621710] 'compare' (duration: 69.426979ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:07:21.164353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.589431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:07:21.164352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.181781ms","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-20T02:07:21.164302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.508641Z","time spent":"655.584528ms","remote":"127.0.0.1:43646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T02:07:21.164407Z","caller":"traceutil/trace.go:171","msg":"trace[241888489] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:629; }","duration":"614.700755ms","start":"2026-04-20T02:07:20.549694Z","end":"2026-04-20T02:07:21.164395Z","steps":["trace[241888489] 'agreement among raft nodes before linearized reading' (duration: 614.482467ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:21.16443Z","caller":"traceutil/trace.go:171","msg":"trace[1688408079] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:629; }","duration":"204.277124ms","start":"2026-04-20T02:07:20.960134Z","end":"2026-04-20T02:07:21.164411Z","steps":["trace[1688408079] 'agreement among raft nodes before linearized reading' (duration: 204.055947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.164455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.549675Z","time spent":"614.768296ms","remote":"127.0.0.1:56406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:07:21.164606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.010137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:07:21.164608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.061215ms","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-20T02:07:21.164716Z","caller":"traceutil/trace.go:171","msg":"trace[765659124] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:629; }","duration":"300.235591ms","start":"2026-04-20T02:07:20.864455Z","end":"2026-04-20T02:07:21.164691Z","steps":["trace[765659124] 'agreement among raft nodes before linearized reading' (duration: 300.006194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.164796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.423901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:07:21.164799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.864443Z","time spent":"300.342024ms","remote":"127.0.0.1:56438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-04-20T02:07:21.164839Z","caller":"traceutil/trace.go:171","msg":"trace[1766014822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"478.520295ms","start":"2026-04-20T02:07:20.686309Z","end":"2026-04-20T02:07:21.16483Z","steps":["trace[1766014822] 'agreement among raft nodes before linearized reading' (duration: 478.455203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.164871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.686287Z","time spent":"478.576397ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T02:07:21.16487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.251345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.164935Z","caller":"traceutil/trace.go:171","msg":"trace[704634204] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"317.327808ms","start":"2026-04-20T02:07:20.847583Z","end":"2026-04-20T02:07:21.16491Z","steps":["trace[704634204] 'agreement among raft nodes before linearized reading' (duration: 317.240925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.16487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.672646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:07:21.164994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.84758Z","time spent":"317.40068ms","remote":"127.0.0.1:56430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T02:07:21.165053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.68334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.165062Z","caller":"traceutil/trace.go:171","msg":"trace[1903627016] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"316.873833ms","start":"2026-04-20T02:07:20.848137Z","end":"2026-04-20T02:07:21.165011Z","steps":["trace[1903627016] 'agreement among raft nodes before linearized reading' (duration: 316.591394ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:21.165104Z","caller":"traceutil/trace.go:171","msg":"trace[441035475] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"478.768933ms","start":"2026-04-20T02:07:20.686316Z","end":"2026-04-20T02:07:21.165085Z","steps":["trace[441035475] 'agreement among raft nodes before linearized reading' (duration: 478.710161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.165025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.531332ms","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":"warn","ts":"2026-04-20T02:07:21.165141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.686304Z","time spent":"478.828335ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:07:21.16514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.848134Z","time spent":"316.981896ms","remote":"127.0.0.1:56418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-20T02:07:21.165219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.322179ms","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-20T02:07:21.16523Z","caller":"traceutil/trace.go:171","msg":"trace[48154149] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:629; }","duration":"300.735428ms","start":"2026-04-20T02:07:20.864423Z","end":"2026-04-20T02:07:21.165158Z","steps":["trace[48154149] 'agreement among raft nodes before linearized reading' (duration: 300.480219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.164568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.897651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.16532Z","caller":"traceutil/trace.go:171","msg":"trace[1148768339] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:629; }","duration":"205.405071ms","start":"2026-04-20T02:07:20.959862Z","end":"2026-04-20T02:07:21.165267Z","steps":["trace[1148768339] 'agreement among raft nodes before linearized reading' (duration: 205.308508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.165386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.864416Z","time spent":"300.942014ms","remote":"127.0.0.1:56438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:07:21.165496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.586344ms","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":"warn","ts":"2026-04-20T02:07:21.165521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.076302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.165558Z","caller":"traceutil/trace.go:171","msg":"trace[1330546407] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:629; }","duration":"204.677197ms","start":"2026-04-20T02:07:20.960863Z","end":"2026-04-20T02:07:21.16554Z","steps":["trace[1330546407] 'agreement among raft nodes before linearized reading' (duration: 204.589634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.165613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.293637ms","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-20T02:07:21.16565Z","caller":"traceutil/trace.go:171","msg":"trace[260216233] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:629; }","duration":"237.37172ms","start":"2026-04-20T02:07:20.92827Z","end":"2026-04-20T02:07:21.165642Z","steps":["trace[260216233] 'agreement among raft nodes before linearized reading' (duration: 237.314298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.165753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.453322ms","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-20T02:07:21.165784Z","caller":"traceutil/trace.go:171","msg":"trace[478048738] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:629; }","duration":"237.508704ms","start":"2026-04-20T02:07:20.928268Z","end":"2026-04-20T02:07:21.165777Z","steps":["trace[478048738] 'agreement among raft nodes before linearized reading' (duration: 237.458183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.165803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.360322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.165861Z","caller":"traceutil/trace.go:171","msg":"trace[582964137] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:629; }","duration":"273.471126ms","start":"2026-04-20T02:07:20.892378Z","end":"2026-04-20T02:07:21.165849Z","steps":["trace[582964137] 'agreement among raft nodes before linearized reading' (duration: 273.31127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.165853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.436361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:07:21.165868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.280335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.165909Z","caller":"traceutil/trace.go:171","msg":"trace[285364459] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:629; }","duration":"272.506423ms","start":"2026-04-20T02:07:20.893387Z","end":"2026-04-20T02:07:21.165893Z","steps":["trace[285364459] 'agreement among raft nodes before linearized reading' (duration: 272.42105ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:21.165927Z","caller":"traceutil/trace.go:171","msg":"trace[788647581] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:629; }","duration":"172.352587ms","start":"2026-04-20T02:07:20.993561Z","end":"2026-04-20T02:07:21.165913Z","steps":["trace[788647581] 'agreement among raft nodes before linearized reading' (duration: 172.251363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.166145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.378771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.166196Z","caller":"traceutil/trace.go:171","msg":"trace[2094516700] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:629; }","duration":"173.462404ms","start":"2026-04-20T02:07:20.992721Z","end":"2026-04-20T02:07:21.166183Z","steps":["trace[2094516700] 'agreement among raft nodes before linearized reading' (duration: 173.388581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.166372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.6661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.166443Z","caller":"traceutil/trace.go:171","msg":"trace[133234103] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:629; }","duration":"173.749634ms","start":"2026-04-20T02:07:20.992678Z","end":"2026-04-20T02:07:21.166428Z","steps":["trace[133234103] 'agreement among raft nodes before linearized reading' (duration: 173.655761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.166499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.974235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.165564Z","caller":"traceutil/trace.go:171","msg":"trace[320133161] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:629; }","duration":"273.141705ms","start":"2026-04-20T02:07:20.892406Z","end":"2026-04-20T02:07:21.165548Z","steps":["trace[320133161] 'agreement among raft nodes before linearized reading' (duration: 273.072512ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:21.166549Z","caller":"traceutil/trace.go:171","msg":"trace[1168934020] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:629; }","duration":"150.085499ms","start":"2026-04-20T02:07:21.016451Z","end":"2026-04-20T02:07:21.166537Z","steps":["trace[1168934020] 'agreement among raft nodes before linearized reading' (duration: 150.007237ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:21.165474Z","caller":"traceutil/trace.go:171","msg":"trace[898267036] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:629; }","duration":"615.808251ms","start":"2026-04-20T02:07:20.549546Z","end":"2026-04-20T02:07:21.165355Z","steps":["trace[898267036] 'agreement among raft nodes before linearized reading' (duration: 614.575491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.166745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.566196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.166777Z","caller":"traceutil/trace.go:171","msg":"trace[739175102] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:629; }","duration":"121.641418ms","start":"2026-04-20T02:07:21.045127Z","end":"2026-04-20T02:07:21.166769Z","steps":["trace[739175102] 'agreement among raft nodes before linearized reading' (duration: 121.589886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.166787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.549535Z","time spent":"617.215167ms","remote":"127.0.0.1:56406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T02:07:21.166945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.794812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:07:21.165283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.500351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.166983Z","caller":"traceutil/trace.go:171","msg":"trace[58338948] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:629; }","duration":"121.847965ms","start":"2026-04-20T02:07:21.045125Z","end":"2026-04-20T02:07:21.166973Z","steps":["trace[58338948] 'agreement among raft nodes before linearized reading' (duration: 121.795443ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:21.167088Z","caller":"traceutil/trace.go:171","msg":"trace[462227991] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:629; }","duration":"617.27962ms","start":"2026-04-20T02:07:20.549729Z","end":"2026-04-20T02:07:21.167009Z","steps":["trace[462227991] 'agreement among raft nodes before linearized reading' (duration: 615.528932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.167119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.086249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.167164Z","caller":"traceutil/trace.go:171","msg":"trace[993670125] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:629; }","duration":"150.193623ms","start":"2026-04-20T02:07:21.016945Z","end":"2026-04-20T02:07:21.167139Z","steps":["trace[993670125] 'agreement among raft nodes before linearized reading' (duration: 150.139631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.165255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.978653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:07:21.167689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.490076ms","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-20T02:07:21.1677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.5497Z","time spent":"617.434405ms","remote":"127.0.0.1:56406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"info","ts":"2026-04-20T02:07:21.164768Z","caller":"traceutil/trace.go:171","msg":"trace[919029395] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:629; }","duration":"317.177262ms","start":"2026-04-20T02:07:20.847571Z","end":"2026-04-20T02:07:21.164748Z","steps":["trace[919029395] 'agreement among raft nodes before linearized reading' (duration: 316.966105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.167796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.847517Z","time spent":"320.261324ms","remote":"127.0.0.1:56430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-20T02:07:21.167802Z","caller":"traceutil/trace.go:171","msg":"trace[289246102] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:629; }","duration":"302.59066ms","start":"2026-04-20T02:07:20.865164Z","end":"2026-04-20T02:07:21.167754Z","steps":["trace[289246102] 'agreement among raft nodes before linearized reading' (duration: 302.358672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.165581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.759195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:21.167997Z","caller":"traceutil/trace.go:171","msg":"trace[938199867] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:629; }","duration":"122.197336ms","start":"2026-04-20T02:07:21.045789Z","end":"2026-04-20T02:07:21.167986Z","steps":["trace[938199867] 'agreement among raft nodes before linearized reading' (duration: 119.749435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:21.167975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:07:20.865155Z","time spent":"302.804096ms","remote":"127.0.0.1:56438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-04-20T02:07:21.168145Z","caller":"traceutil/trace.go:171","msg":"trace[2042736138] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:629; }","duration":"151.33176ms","start":"2026-04-20T02:07:21.016207Z","end":"2026-04-20T02:07:21.167538Z","steps":["trace[2042736138] 'agreement among raft nodes before linearized reading' (duration: 149.000193ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:04.645952Z","caller":"traceutil/trace.go:171","msg":"trace[2096172593] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"174.276837ms","start":"2026-04-20T02:08:04.47165Z","end":"2026-04-20T02:08:04.645927Z","steps":["trace[2096172593] 'process raft request' (duration: 174.097022ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:04.661289Z","caller":"traceutil/trace.go:171","msg":"trace[1444318804] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1215; }","duration":"129.072948ms","start":"2026-04-20T02:08:04.532193Z","end":"2026-04-20T02:08:04.661265Z","steps":["trace[1444318804] 'read index received' (duration: 113.625406ms)","trace[1444318804] 'applied index is now lower than readState.Index' (duration: 15.446582ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:04.661335Z","caller":"traceutil/trace.go:171","msg":"trace[1454732463] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"181.139822ms","start":"2026-04-20T02:08:04.480179Z","end":"2026-04-20T02:08:04.661319Z","steps":["trace[1454732463] 'process raft request' (duration: 180.977937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:04.661523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.319195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T02:08:04.661583Z","caller":"traceutil/trace.go:171","msg":"trace[1666812079] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1187; }","duration":"129.429028ms","start":"2026-04-20T02:08:04.532139Z","end":"2026-04-20T02:08:04.661568Z","steps":["trace[1666812079] 'agreement among raft nodes before linearized reading' (duration: 129.217833ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:26.91856Z","caller":"traceutil/trace.go:171","msg":"trace[1608864988] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"138.275243ms","start":"2026-04-20T02:08:26.780247Z","end":"2026-04-20T02:08:26.918523Z","steps":["trace[1608864988] 'process raft request' (duration: 138.098738ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:26.967193Z","caller":"traceutil/trace.go:171","msg":"trace[532374712] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"131.055229ms","start":"2026-04-20T02:08:26.836108Z","end":"2026-04-20T02:08:26.967163Z","steps":["trace[532374712] 'process raft request' (duration: 130.898715ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:42.625195Z","caller":"traceutil/trace.go:171","msg":"trace[2067310865] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"119.390623ms","start":"2026-04-20T02:08:42.505766Z","end":"2026-04-20T02:08:42.625156Z","steps":["trace[2067310865] 'process raft request' (duration: 43.353571ms)","trace[2067310865] 'compare' (duration: 75.799516ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:42.625499Z","caller":"traceutil/trace.go:171","msg":"trace[1387272412] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"119.218339ms","start":"2026-04-20T02:08:42.506241Z","end":"2026-04-20T02:08:42.625459Z","steps":["trace[1387272412] 'process raft request' (duration: 118.964552ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:42.62566Z","caller":"traceutil/trace.go:171","msg":"trace[1406233942] linearizableReadLoop","detail":"{readStateIndex:1785; appliedIndex:1782; }","duration":"106.979828ms","start":"2026-04-20T02:08:42.518654Z","end":"2026-04-20T02:08:42.625634Z","steps":["trace[1406233942] 'read index received' (duration: 30.633289ms)","trace[1406233942] 'applied index is now lower than readState.Index' (duration: 76.344889ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:42.625778Z","caller":"traceutil/trace.go:171","msg":"trace[2104103603] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"118.980653ms","start":"2026-04-20T02:08:42.50675Z","end":"2026-04-20T02:08:42.625731Z","steps":["trace[2104103603] 'process raft request' (duration: 118.592093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.625903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.160583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:42.625947Z","caller":"traceutil/trace.go:171","msg":"trace[263595304] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:1723; }","duration":"107.244745ms","start":"2026-04-20T02:08:42.51869Z","end":"2026-04-20T02:08:42.625934Z","steps":["trace[263595304] 'agreement among raft nodes before linearized reading' (duration: 107.187963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.625845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.163733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:42.626105Z","caller":"traceutil/trace.go:171","msg":"trace[1376382048] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:1723; }","duration":"107.46905ms","start":"2026-04-20T02:08:42.518602Z","end":"2026-04-20T02:08:42.626071Z","steps":["trace[1376382048] 'agreement among raft nodes before linearized reading' (duration: 107.179203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.626138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.357002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:42.626218Z","caller":"traceutil/trace.go:171","msg":"trace[1849857852] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:1723; }","duration":"106.492155ms","start":"2026-04-20T02:08:42.519706Z","end":"2026-04-20T02:08:42.626198Z","steps":["trace[1849857852] 'agreement among raft nodes before linearized reading' (duration: 106.341052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:54.383412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.176938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:54.383582Z","caller":"traceutil/trace.go:171","msg":"trace[611606858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1874; }","duration":"208.425504ms","start":"2026-04-20T02:08:54.175128Z","end":"2026-04-20T02:08:54.383554Z","steps":["trace[611606858] 'range keys from in-memory index tree' (duration: 208.005473ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:54.416544Z","caller":"traceutil/trace.go:171","msg":"trace[267530485] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"226.856303ms","start":"2026-04-20T02:08:54.189665Z","end":"2026-04-20T02:08:54.416522Z","steps":["trace[267530485] 'process raft request' (duration: 226.727169ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:54.424716Z","caller":"traceutil/trace.go:171","msg":"trace[1037133705] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"220.967493ms","start":"2026-04-20T02:08:54.20371Z","end":"2026-04-20T02:08:54.424678Z","steps":["trace[1037133705] 'process raft request' (duration: 220.604393ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:07.364153Z","caller":"traceutil/trace.go:171","msg":"trace[1334906076] linearizableReadLoop","detail":"{readStateIndex:2059; appliedIndex:2058; }","duration":"225.302908ms","start":"2026-04-20T02:09:07.138803Z","end":"2026-04-20T02:09:07.364106Z","steps":["trace[1334906076] 'read index received' (duration: 207.673612ms)","trace[1334906076] 'applied index is now lower than readState.Index' (duration: 17.627626ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:07.364227Z","caller":"traceutil/trace.go:171","msg":"trace[92843131] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"251.50495ms","start":"2026-04-20T02:09:07.112679Z","end":"2026-04-20T02:09:07.364184Z","steps":["trace[92843131] 'process raft request' (duration: 233.908695ms)","trace[92843131] 'compare' (duration: 17.052652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:09:07.364396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.156305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:09:07.364439Z","caller":"traceutil/trace.go:171","msg":"trace[1985674834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1989; }","duration":"189.247118ms","start":"2026-04-20T02:09:07.17518Z","end":"2026-04-20T02:09:07.364427Z","steps":["trace[1985674834] 'agreement among raft nodes before linearized reading' (duration: 189.135365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:07.364475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.645966ms","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-20T02:09:07.36459Z","caller":"traceutil/trace.go:171","msg":"trace[203542679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1989; }","duration":"225.80432ms","start":"2026-04-20T02:09:07.138753Z","end":"2026-04-20T02:09:07.364557Z","steps":["trace[203542679] 'agreement among raft nodes before linearized reading' (duration: 225.494742ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:32.741459Z","caller":"traceutil/trace.go:171","msg":"trace[1659640255] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"110.169149ms","start":"2026-04-20T02:09:32.631245Z","end":"2026-04-20T02:09:32.741414Z","steps":["trace[1659640255] 'process raft request' (duration: 54.67486ms)","trace[1659640255] 'compare' (duration: 55.261354ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:38.34178Z","caller":"traceutil/trace.go:171","msg":"trace[201994838] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"121.853541ms","start":"2026-04-20T02:09:38.219904Z","end":"2026-04-20T02:09:38.341758Z","steps":["trace[201994838] 'process raft request' (duration: 121.721358ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:44.121745Z","caller":"traceutil/trace.go:171","msg":"trace[62545359] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"105.28927ms","start":"2026-04-20T02:09:44.016416Z","end":"2026-04-20T02:09:44.121705Z","steps":["trace[62545359] 'read index received' (duration: 104.87673ms)","trace[62545359] 'applied index is now lower than readState.Index' (duration: 410.62µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:44.121858Z","caller":"traceutil/trace.go:171","msg":"trace[2023764456] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"117.634558ms","start":"2026-04-20T02:09:44.004188Z","end":"2026-04-20T02:09:44.121823Z","steps":["trace[2023764456] 'process raft request' (duration: 117.247069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:44.122461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.013277ms","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-20T02:09:44.122579Z","caller":"traceutil/trace.go:171","msg":"trace[1834543366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2194; }","duration":"106.194422ms","start":"2026-04-20T02:09:44.016355Z","end":"2026-04-20T02:09:44.12255Z","steps":["trace[1834543366] 'agreement among raft nodes before linearized reading' (duration: 105.601747ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:44.321678Z","caller":"traceutil/trace.go:171","msg":"trace[872614331] linearizableReadLoop","detail":"{readStateIndex:2272; appliedIndex:2271; }","duration":"148.565046ms","start":"2026-04-20T02:09:44.173066Z","end":"2026-04-20T02:09:44.321631Z","steps":["trace[872614331] 'read index received' (duration: 147.302105ms)","trace[872614331] 'applied index is now lower than readState.Index' (duration: 1.261781ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:44.321738Z","caller":"traceutil/trace.go:171","msg":"trace[1913157256] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"194.722989ms","start":"2026-04-20T02:09:44.126938Z","end":"2026-04-20T02:09:44.321661Z","steps":["trace[1913157256] 'process raft request' (duration: 193.581422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:44.321917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.830292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:09:44.322121Z","caller":"traceutil/trace.go:171","msg":"trace[1912883088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2195; }","duration":"149.071728ms","start":"2026-04-20T02:09:44.172983Z","end":"2026-04-20T02:09:44.322055Z","steps":["trace[1912883088] 'agreement among raft nodes before linearized reading' (duration: 148.75944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:44.322288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.986767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T02:09:44.322383Z","caller":"traceutil/trace.go:171","msg":"trace[1941665630] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2195; }","duration":"137.107899ms","start":"2026-04-20T02:09:44.185256Z","end":"2026-04-20T02:09:44.322364Z","steps":["trace[1941665630] 'agreement among raft nodes before linearized reading' (duration: 136.551566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:45.038898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.153346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:09:45.039239Z","caller":"traceutil/trace.go:171","msg":"trace[654771323] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"399.669635ms","start":"2026-04-20T02:09:44.639552Z","end":"2026-04-20T02:09:45.039221Z","steps":["trace[654771323] 'process raft request' (duration: 399.45487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:45.039321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:44.639518Z","time spent":"399.777527ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:09:45.039287Z","caller":"traceutil/trace.go:171","msg":"trace[797993172] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"403.049627ms","start":"2026-04-20T02:09:44.636165Z","end":"2026-04-20T02:09:45.039215Z","steps":["trace[797993172] 'process raft request' (duration: 249.331897ms)","trace[797993172] 'compare' (duration: 152.987172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:09:45.039559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:44.636135Z","time spent":"403.292153ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:09:45.055745Z","caller":"traceutil/trace.go:171","msg":"trace[1064502332] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2274; }","duration":"260.147229ms","start":"2026-04-20T02:09:44.795573Z","end":"2026-04-20T02:09:45.055721Z","steps":["trace[1064502332] 'read index received' (duration: 90.083435ms)","trace[1064502332] 'applied index is now lower than readState.Index' (duration: 170.062064ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:45.055777Z","caller":"traceutil/trace.go:171","msg":"trace[219103946] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"397.670447ms","start":"2026-04-20T02:09:44.65808Z","end":"2026-04-20T02:09:45.05575Z","steps":["trace[219103946] 'process raft request' (duration: 397.557194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:45.055998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:44.658051Z","time spent":"397.885093ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:09:45.05632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.742683ms","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-20T02:09:45.056384Z","caller":"traceutil/trace.go:171","msg":"trace[979085751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2199; }","duration":"260.841826ms","start":"2026-04-20T02:09:44.795526Z","end":"2026-04-20T02:09:45.056368Z","steps":["trace[979085751] 'agreement among raft nodes before linearized reading' (duration: 260.264142ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:50.469134Z","caller":"traceutil/trace.go:171","msg":"trace[1289640304] linearizableReadLoop","detail":"{readStateIndex:2306; appliedIndex:2305; }","duration":"103.938008ms","start":"2026-04-20T02:09:50.365161Z","end":"2026-04-20T02:09:50.469099Z","steps":["trace[1289640304] 'read index received' (duration: 103.653261ms)","trace[1289640304] 'applied index is now lower than readState.Index' (duration: 283.157µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:50.469185Z","caller":"traceutil/trace.go:171","msg":"trace[714644024] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"112.460203ms","start":"2026-04-20T02:09:50.35669Z","end":"2026-04-20T02:09:50.46915Z","steps":["trace[714644024] 'process raft request' (duration: 112.198367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:50.469488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.296496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-20T02:09:50.469568Z","caller":"traceutil/trace.go:171","msg":"trace[277078421] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2227; }","duration":"104.462451ms","start":"2026-04-20T02:09:50.365085Z","end":"2026-04-20T02:09:50.469548Z","steps":["trace[277078421] 'agreement among raft nodes before linearized reading' (duration: 104.171164ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:50.680754Z","caller":"traceutil/trace.go:171","msg":"trace[1886041628] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"207.104819ms","start":"2026-04-20T02:09:50.473626Z","end":"2026-04-20T02:09:50.680731Z","steps":["trace[1886041628] 'process raft request' (duration: 162.892092ms)","trace[1886041628] 'compare' (duration: 43.809857ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:10:37.957152Z","caller":"traceutil/trace.go:171","msg":"trace[288020346] linearizableReadLoop","detail":"{readStateIndex:2752; appliedIndex:2751; }","duration":"125.249296ms","start":"2026-04-20T02:10:37.831875Z","end":"2026-04-20T02:10:37.957124Z","steps":["trace[288020346] 'read index received' (duration: 124.998569ms)","trace[288020346] 'applied index is now lower than readState.Index' (duration: 249.027µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:10:37.957175Z","caller":"traceutil/trace.go:171","msg":"trace[555829233] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"231.3027ms","start":"2026-04-20T02:10:37.725824Z","end":"2026-04-20T02:10:37.957127Z","steps":["trace[555829233] 'process raft request' (duration: 231.041502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:10:37.957389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.498223ms","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-20T02:10:37.957431Z","caller":"traceutil/trace.go:171","msg":"trace[977987655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2656; }","duration":"125.613536ms","start":"2026-04-20T02:10:37.831805Z","end":"2026-04-20T02:10:37.957418Z","steps":["trace[977987655] 'agreement among raft nodes before linearized reading' (duration: 125.433391ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:10:53.795133Z","caller":"traceutil/trace.go:171","msg":"trace[1680349476] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"174.258246ms","start":"2026-04-20T02:10:53.620847Z","end":"2026-04-20T02:10:53.795105Z","steps":["trace[1680349476] 'process raft request' (duration: 174.094971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:10:59.973409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367128871313113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:11:00.473835Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367128871313113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:11:00.760013Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.372616613s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T02:11:00.77536Z","caller":"traceutil/trace.go:171","msg":"trace[94965821] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"1.406673868s","start":"2026-04-20T02:10:59.368643Z","end":"2026-04-20T02:11:00.775317Z","steps":["trace[94965821] 'process raft request' (duration: 1.39194635s)","trace[94965821] 'compare' (duration: 13.326609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:11:00.775573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:10:59.368618Z","time spent":"1.406845593s","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:11:00.787385Z","caller":"traceutil/trace.go:171","msg":"trace[1093714860] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"720.016983ms","start":"2026-04-20T02:11:00.067335Z","end":"2026-04-20T02:11:00.787352Z","steps":["trace[1093714860] 'process raft request' (duration: 719.599581ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:11:00.787613Z","caller":"traceutil/trace.go:171","msg":"trace[1240194095] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"371.329726ms","start":"2026-04-20T02:11:00.416249Z","end":"2026-04-20T02:11:00.787578Z","steps":["trace[1240194095] 'process raft request' (duration: 371.002227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:00.787815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:00.416221Z","time spent":"371.489961ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:11:00.787805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:00.067299Z","time spent":"720.399573ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:11:00.787848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315211941s","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-20T02:11:00.787931Z","caller":"traceutil/trace.go:171","msg":"trace[2000934923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2796; }","duration":"1.315342744s","start":"2026-04-20T02:10:59.472562Z","end":"2026-04-20T02:11:00.787905Z","steps":["trace[2000934923] 'agreement among raft nodes before linearized reading' (duration: 1.315067466s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:00.787975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:10:59.472537Z","time spent":"1.315428617s","remote":"127.0.0.1:43730","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-20T02:11:00.787952Z","caller":"traceutil/trace.go:171","msg":"trace[1690429453] linearizableReadLoop","detail":"{readStateIndex:2898; appliedIndex:2894; }","duration":"1.314860151s","start":"2026-04-20T02:10:59.472619Z","end":"2026-04-20T02:11:00.787479Z","steps":["trace[1690429453] 'read index received' (duration: 1.28779703s)","trace[1690429453] 'applied index is now lower than readState.Index' (duration: 27.062011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:11:00.788283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.142647ms","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-20T02:11:00.788306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.855322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-04-20T02:11:00.788328Z","caller":"traceutil/trace.go:171","msg":"trace[501895586] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2796; }","duration":"969.236129ms","start":"2026-04-20T02:10:59.819081Z","end":"2026-04-20T02:11:00.788317Z","steps":["trace[501895586] 'agreement among raft nodes before linearized reading' (duration: 969.039914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:00.788354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:10:59.81906Z","time spent":"969.288541ms","remote":"127.0.0.1:43730","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-20T02:11:00.788352Z","caller":"traceutil/trace.go:171","msg":"trace[1584240753] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:2796; }","duration":"210.937725ms","start":"2026-04-20T02:11:00.577399Z","end":"2026-04-20T02:11:00.788336Z","steps":["trace[1584240753] 'agreement among raft nodes before linearized reading' (duration: 210.76461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:00.788515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.061793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/valkey-node-0\" ","response":"range_response_count:1 size:17078"} {"level":"info","ts":"2026-04-20T02:11:00.788544Z","caller":"traceutil/trace.go:171","msg":"trace[551896993] range","detail":"{range_begin:/registry/pods/openstack/valkey-node-0; range_end:; response_count:1; response_revision:2796; }","duration":"280.156976ms","start":"2026-04-20T02:11:00.50838Z","end":"2026-04-20T02:11:00.788537Z","steps":["trace[551896993] 'agreement among raft nodes before linearized reading' (duration: 280.069903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:00.788678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.686758ms","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-20T02:11:00.788717Z","caller":"traceutil/trace.go:171","msg":"trace[1651169832] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2796; }","duration":"619.774601ms","start":"2026-04-20T02:11:00.168932Z","end":"2026-04-20T02:11:00.788706Z","steps":["trace[1651169832] 'agreement among raft nodes before linearized reading' (duration: 619.657158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:00.7887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.993296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a7eeb7a730da4e\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-04-20T02:11:00.788801Z","caller":"traceutil/trace.go:171","msg":"trace[1598696864] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a7eeb7a730da4e; range_end:; response_count:1; response_revision:2796; }","duration":"211.14768ms","start":"2026-04-20T02:11:00.577632Z","end":"2026-04-20T02:11:00.78878Z","steps":["trace[1598696864] 'agreement among raft nodes before linearized reading' (duration: 210.861012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:00.78874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:00.168902Z","time spent":"619.833112ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-20T02:11:00.788737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.437149ms","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-20T02:11:00.788895Z","caller":"traceutil/trace.go:171","msg":"trace[91988354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2796; }","duration":"638.648614ms","start":"2026-04-20T02:11:00.15024Z","end":"2026-04-20T02:11:00.788888Z","steps":["trace[91988354] 'agreement among raft nodes before linearized reading' (duration: 638.407988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:00.788927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:00.150211Z","time spent":"638.708906ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T02:11:09.019518Z","caller":"traceutil/trace.go:171","msg":"trace[760770548] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"207.18095ms","start":"2026-04-20T02:11:08.812309Z","end":"2026-04-20T02:11:09.01949Z","steps":["trace[760770548] 'process raft request' (duration: 206.993015ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:11:09.24262Z","caller":"traceutil/trace.go:171","msg":"trace[2137568501] linearizableReadLoop","detail":"{readStateIndex:2945; appliedIndex:2942; }","duration":"396.70277ms","start":"2026-04-20T02:11:08.845887Z","end":"2026-04-20T02:11:09.24259Z","steps":["trace[2137568501] 'read index received' (duration: 173.417543ms)","trace[2137568501] 'applied index is now lower than readState.Index' (duration: 223.282157ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:11:09.242642Z","caller":"traceutil/trace.go:171","msg":"trace[347826000] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"426.355773ms","start":"2026-04-20T02:11:08.816243Z","end":"2026-04-20T02:11:09.242599Z","steps":["trace[347826000] 'process raft request' (duration: 396.075923ms)","trace[347826000] 'compare' (duration: 29.978632ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:11:09.242711Z","caller":"traceutil/trace.go:171","msg":"trace[1970083219] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"421.257541ms","start":"2026-04-20T02:11:08.8214Z","end":"2026-04-20T02:11:09.242657Z","steps":["trace[1970083219] 'process raft request' (duration: 421.127247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:09.24283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:08.816212Z","time spent":"426.517397ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:11:09.242898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.983578ms","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-20T02:11:09.242992Z","caller":"traceutil/trace.go:171","msg":"trace[1087563541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2842; }","duration":"397.132872ms","start":"2026-04-20T02:11:08.845836Z","end":"2026-04-20T02:11:09.242969Z","steps":["trace[1087563541] 'agreement among raft nodes before linearized reading' (duration: 396.870404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:09.242973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.825109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T02:11:09.243022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.667882ms","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-20T02:11:09.243045Z","caller":"traceutil/trace.go:171","msg":"trace[896496213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2842; }","duration":"140.960062ms","start":"2026-04-20T02:11:09.102068Z","end":"2026-04-20T02:11:09.243028Z","steps":["trace[896496213] 'agreement among raft nodes before linearized reading' (duration: 140.734956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:09.243037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:08.821379Z","time spent":"421.469017ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:11:09.243174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.186771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T02:11:09.243032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:08.845812Z","time spent":"397.213874ms","remote":"127.0.0.1:43730","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-20T02:11:09.243216Z","caller":"traceutil/trace.go:171","msg":"trace[1664692331] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2842; }","duration":"327.275773ms","start":"2026-04-20T02:11:08.915928Z","end":"2026-04-20T02:11:09.243204Z","steps":["trace[1664692331] 'agreement among raft nodes before linearized reading' (duration: 327.16618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:09.243386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:08.915906Z","time spent":"327.466138ms","remote":"127.0.0.1:43542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-04-20T02:11:09.24307Z","caller":"traceutil/trace.go:171","msg":"trace[873010613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2842; }","duration":"310.756214ms","start":"2026-04-20T02:11:08.932301Z","end":"2026-04-20T02:11:09.243057Z","steps":["trace[873010613] 'agreement among raft nodes before linearized reading' (duration: 310.657691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:09.24341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.871603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T02:11:09.243461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:08.932284Z","time spent":"311.164076ms","remote":"127.0.0.1:43730","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-20T02:11:09.243474Z","caller":"traceutil/trace.go:171","msg":"trace[1947090735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2842; }","duration":"392.978577ms","start":"2026-04-20T02:11:08.850479Z","end":"2026-04-20T02:11:09.243458Z","steps":["trace[1947090735] 'agreement among raft nodes before linearized reading' (duration: 392.673549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:09.243523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:08.850459Z","time spent":"393.053729ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T02:11:09.24293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.946856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T02:11:09.243605Z","caller":"traceutil/trace.go:171","msg":"trace[1957152427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2842; }","duration":"397.660516ms","start":"2026-04-20T02:11:08.845932Z","end":"2026-04-20T02:11:09.243593Z","steps":["trace[1957152427] 'agreement among raft nodes before linearized reading' (duration: 396.838023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:09.243649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:08.845926Z","time spent":"397.710738ms","remote":"127.0.0.1:43730","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-20T02:11:15.030723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.522084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:11:15.030879Z","caller":"traceutil/trace.go:171","msg":"trace[1515497935] linearizableReadLoop","detail":"{readStateIndex:2978; appliedIndex:2977; }","duration":"127.503729ms","start":"2026-04-20T02:11:14.903354Z","end":"2026-04-20T02:11:15.030858Z","steps":["trace[1515497935] 'read index received' (duration: 41.531µs)","trace[1515497935] 'applied index is now lower than readState.Index' (duration: 127.460218ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:11:15.030995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.641373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:11:15.030976Z","caller":"traceutil/trace.go:171","msg":"trace[1607997488] transaction","detail":"{read_only:false; response_revision:2873; number_of_response:1; }","duration":"318.961913ms","start":"2026-04-20T02:11:14.711983Z","end":"2026-04-20T02:11:15.030945Z","steps":["trace[1607997488] 'process raft request' (duration: 153.11551ms)","trace[1607997488] 'compare' (duration: 165.129903ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:11:15.031027Z","caller":"traceutil/trace.go:171","msg":"trace[1615561824] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2873; }","duration":"127.671733ms","start":"2026-04-20T02:11:14.903342Z","end":"2026-04-20T02:11:15.031013Z","steps":["trace[1615561824] 'agreement among raft nodes before linearized reading' (duration: 127.567841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:11:15.031124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:11:14.711962Z","time spent":"319.079145ms","remote":"127.0.0.1:43524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:13:56.423028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.024781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:13:56.42319Z","caller":"traceutil/trace.go:171","msg":"trace[1800367408] linearizableReadLoop","detail":"{readStateIndex:4068; appliedIndex:4067; }","duration":"332.211325ms","start":"2026-04-20T02:13:56.090956Z","end":"2026-04-20T02:13:56.423168Z","steps":["trace[1800367408] 'read index received' (duration: 123.844956ms)","trace[1800367408] 'applied index is now lower than readState.Index' (duration: 208.364329ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:13:56.423243Z","caller":"traceutil/trace.go:171","msg":"trace[494179084] transaction","detail":"{read_only:false; response_revision:3924; number_of_response:1; }","duration":"365.212189ms","start":"2026-04-20T02:13:56.057988Z","end":"2026-04-20T02:13:56.4232Z","steps":["trace[494179084] 'process raft request' (duration: 156.893171ms)","trace[494179084] 'compare' (duration: 207.781715ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:13:56.423458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.501873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-20T02:13:56.423505Z","caller":"traceutil/trace.go:171","msg":"trace[1438371434] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3924; }","duration":"332.543874ms","start":"2026-04-20T02:13:56.090943Z","end":"2026-04-20T02:13:56.423487Z","steps":["trace[1438371434] 'agreement among raft nodes before linearized reading' (duration: 332.357079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:13:56.42356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:13:56.090723Z","time spent":"332.82281ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-20T02:13:56.423554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.32416ms","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-20T02:13:56.42377Z","caller":"traceutil/trace.go:171","msg":"trace[1086263522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3924; }","duration":"226.604977ms","start":"2026-04-20T02:13:56.197136Z","end":"2026-04-20T02:13:56.423741Z","steps":["trace[1086263522] 'agreement among raft nodes before linearized reading' (duration: 226.198957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:13:56.423779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.94498ms","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-20T02:13:56.423811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.976273ms","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-20T02:13:56.42418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:13:56.057956Z","time spent":"365.417874ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:13:56.423686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.522785ms","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-20T02:13:56.424262Z","caller":"traceutil/trace.go:171","msg":"trace[593615141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3924; }","duration":"209.459405ms","start":"2026-04-20T02:13:56.214765Z","end":"2026-04-20T02:13:56.424224Z","steps":["trace[593615141] 'agreement among raft nodes before linearized reading' (duration: 208.925982ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:13:56.424312Z","caller":"traceutil/trace.go:171","msg":"trace[1100845275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3924; }","duration":"190.209792ms","start":"2026-04-20T02:13:56.234085Z","end":"2026-04-20T02:13:56.424295Z","steps":["trace[1100845275] 'agreement among raft nodes before linearized reading' (duration: 189.370932ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:13:56.423894Z","caller":"traceutil/trace.go:171","msg":"trace[457983287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3924; }","duration":"191.146334ms","start":"2026-04-20T02:13:56.232731Z","end":"2026-04-20T02:13:56.423878Z","steps":["trace[457983287] 'agreement among raft nodes before linearized reading' (duration: 190.779495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:13:56.424378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.007183ms","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-20T02:13:56.424636Z","caller":"traceutil/trace.go:171","msg":"trace[710176598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3924; }","duration":"189.253099ms","start":"2026-04-20T02:13:56.235318Z","end":"2026-04-20T02:13:56.424571Z","steps":["trace[710176598] 'agreement among raft nodes before linearized reading' (duration: 188.416829ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:15.849599Z","caller":"traceutil/trace.go:171","msg":"trace[1892339145] linearizableReadLoop","detail":"{readStateIndex:4982; appliedIndex:4981; }","duration":"204.805545ms","start":"2026-04-20T02:15:15.644762Z","end":"2026-04-20T02:15:15.849567Z","steps":["trace[1892339145] 'read index received' (duration: 204.496147ms)","trace[1892339145] 'applied index is now lower than readState.Index' (duration: 307.808µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:15:15.849648Z","caller":"traceutil/trace.go:171","msg":"trace[1882939911] transaction","detail":"{read_only:false; response_revision:4801; number_of_response:1; }","duration":"254.228297ms","start":"2026-04-20T02:15:15.595381Z","end":"2026-04-20T02:15:15.849609Z","steps":["trace[1882939911] 'process raft request' (duration: 253.96914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:15.84989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.109743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/grafana-tls.18a7eef4f9a22461\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-20T02:15:15.849935Z","caller":"traceutil/trace.go:171","msg":"trace[1543535329] range","detail":"{range_begin:/registry/events/monitoring/grafana-tls.18a7eef4f9a22461; range_end:; response_count:1; response_revision:4801; }","duration":"205.224095ms","start":"2026-04-20T02:15:15.6447Z","end":"2026-04-20T02:15:15.849924Z","steps":["trace[1543535329] 'agreement among raft nodes before linearized reading' (duration: 205.01828ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:15.929417Z","caller":"traceutil/trace.go:171","msg":"trace[1254980501] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"183.483066ms","start":"2026-04-20T02:15:15.745879Z","end":"2026-04-20T02:15:15.929362Z","steps":["trace[1254980501] 'process raft request' (duration: 165.426784ms)","trace[1254980501] 'compare' (duration: 17.905078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:15:15.929587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.018791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls-smsjj\" ","response":"range_response_count:1 size:2431"} {"level":"warn","ts":"2026-04-20T02:15:15.92959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.815729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/monitoring/grafana-tls-29lnt\" ","response":"range_response_count:1 size:6633"} {"level":"info","ts":"2026-04-20T02:15:15.929661Z","caller":"traceutil/trace.go:171","msg":"trace[364095739] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/monitoring/grafana-tls-29lnt; range_end:; response_count:1; response_revision:4802; }","duration":"233.895811ms","start":"2026-04-20T02:15:15.695749Z","end":"2026-04-20T02:15:15.929645Z","steps":["trace[364095739] 'agreement among raft nodes before linearized reading' (duration: 233.689736ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:15.92965Z","caller":"traceutil/trace.go:171","msg":"trace[2066437045] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls-smsjj; range_end:; response_count:1; response_revision:4802; }","duration":"136.144914ms","start":"2026-04-20T02:15:15.793492Z","end":"2026-04-20T02:15:15.929637Z","steps":["trace[2066437045] 'agreement among raft nodes before linearized reading' (duration: 135.897068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:15.929679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.311978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T02:15:15.929818Z","caller":"traceutil/trace.go:171","msg":"trace[665598426] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:4802; }","duration":"131.523864ms","start":"2026-04-20T02:15:15.798265Z","end":"2026-04-20T02:15:15.929789Z","steps":["trace[665598426] 'agreement among raft nodes before linearized reading' (duration: 131.262668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:15.929812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.342559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p2qnk\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T02:15:15.929979Z","caller":"traceutil/trace.go:171","msg":"trace[433963357] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p2qnk; range_end:; response_count:1; response_revision:4802; }","duration":"218.591515ms","start":"2026-04-20T02:15:15.711365Z","end":"2026-04-20T02:15:15.929957Z","steps":["trace[433963357] 'agreement among raft nodes before linearized reading' (duration: 218.019771ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:16.013837Z","caller":"traceutil/trace.go:171","msg":"trace[2092206326] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"161.211213ms","start":"2026-04-20T02:15:15.852594Z","end":"2026-04-20T02:15:16.013805Z","steps":["trace[2092206326] 'process raft request' (duration: 160.953807ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:16.013891Z","caller":"traceutil/trace.go:171","msg":"trace[745672723] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"158.424527ms","start":"2026-04-20T02:15:15.855425Z","end":"2026-04-20T02:15:16.013849Z","steps":["trace[745672723] 'process raft request' (duration: 158.285054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:16.014039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.846494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-patch-w54rs\" ","response":"range_response_count:1 size:4332"} {"level":"info","ts":"2026-04-20T02:15:16.014106Z","caller":"traceutil/trace.go:171","msg":"trace[1671953255] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-patch-w54rs; range_end:; response_count:1; response_revision:4804; }","duration":"124.973927ms","start":"2026-04-20T02:15:15.889114Z","end":"2026-04-20T02:15:16.014088Z","steps":["trace[1671953255] 'agreement among raft nodes before linearized reading' (duration: 124.715231ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:18.334348Z","caller":"traceutil/trace.go:171","msg":"trace[937669005] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"131.885462ms","start":"2026-04-20T02:15:18.202431Z","end":"2026-04-20T02:15:18.334316Z","steps":["trace[937669005] 'process raft request' (duration: 131.732129ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:18.477563Z","caller":"traceutil/trace.go:171","msg":"trace[1080117047] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"192.88613ms","start":"2026-04-20T02:15:18.284634Z","end":"2026-04-20T02:15:18.47752Z","steps":["trace[1080117047] 'process raft request' (duration: 145.584039ms)","trace[1080117047] 'compare' (duration: 46.872441ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:15:18.477855Z","caller":"traceutil/trace.go:171","msg":"trace[608873504] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"124.27216ms","start":"2026-04-20T02:15:18.353553Z","end":"2026-04-20T02:15:18.477826Z","steps":["trace[608873504] 'process raft request' (duration: 124.204069ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:18.477869Z","caller":"traceutil/trace.go:171","msg":"trace[380068169] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"138.736396ms","start":"2026-04-20T02:15:18.339107Z","end":"2026-04-20T02:15:18.477843Z","steps":["trace[380068169] 'process raft request' (duration: 138.276045ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:21.018503Z","caller":"traceutil/trace.go:171","msg":"trace[1205836986] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"102.700124ms","start":"2026-04-20T02:15:20.915777Z","end":"2026-04-20T02:15:21.018477Z","steps":["trace[1205836986] 'process raft request' (duration: 102.371167ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:21.674805Z","caller":"traceutil/trace.go:171","msg":"trace[673894869] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5138; }","duration":"158.084879ms","start":"2026-04-20T02:15:21.516693Z","end":"2026-04-20T02:15:21.674778Z","steps":["trace[673894869] 'read index received' (duration: 157.845133ms)","trace[673894869] 'applied index is now lower than readState.Index' (duration: 238.776µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:15:21.67485Z","caller":"traceutil/trace.go:171","msg":"trace[364042703] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"168.069898ms","start":"2026-04-20T02:15:21.506745Z","end":"2026-04-20T02:15:21.674815Z","steps":["trace[364042703] 'process raft request' (duration: 167.879563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:21.675083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.357175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7465"} {"level":"info","ts":"2026-04-20T02:15:21.675125Z","caller":"traceutil/trace.go:171","msg":"trace[752736577] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4956; }","duration":"158.474968ms","start":"2026-04-20T02:15:21.516639Z","end":"2026-04-20T02:15:21.675114Z","steps":["trace[752736577] 'agreement among raft nodes before linearized reading' (duration: 158.268003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:21.675321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.582819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97b85b26-4dd6-41a0-87ae-f6249f160703\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-04-20T02:15:21.675365Z","caller":"traceutil/trace.go:171","msg":"trace[203348924] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97b85b26-4dd6-41a0-87ae-f6249f160703; range_end:; response_count:1; response_revision:4956; }","duration":"114.680551ms","start":"2026-04-20T02:15:21.560675Z","end":"2026-04-20T02:15:21.675355Z","steps":["trace[203348924] 'agreement among raft nodes before linearized reading' (duration: 114.308363ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:24.118834Z","caller":"traceutil/trace.go:171","msg":"trace[396821281] linearizableReadLoop","detail":"{readStateIndex:5197; appliedIndex:5195; }","duration":"115.426818ms","start":"2026-04-20T02:15:24.003393Z","end":"2026-04-20T02:15:24.11882Z","steps":["trace[396821281] 'read index received' (duration: 50.150838ms)","trace[396821281] 'applied index is now lower than readState.Index' (duration: 65.27525ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:15:24.118912Z","caller":"traceutil/trace.go:171","msg":"trace[2092235813] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"116.609337ms","start":"2026-04-20T02:15:24.002297Z","end":"2026-04-20T02:15:24.118906Z","steps":["trace[2092235813] 'process raft request' (duration: 116.431503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:24.11911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.702516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-04-20T02:15:24.119144Z","caller":"traceutil/trace.go:171","msg":"trace[1539290300] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5011; }","duration":"115.787107ms","start":"2026-04-20T02:15:24.00335Z","end":"2026-04-20T02:15:24.119137Z","steps":["trace[1539290300] 'agreement among raft nodes before linearized reading' (duration: 115.567362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:28.623416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.614834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee265256-3e4e-42ae-8297-b85c26d5be24\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-20T02:15:28.623496Z","caller":"traceutil/trace.go:171","msg":"trace[402961472] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ee265256-3e4e-42ae-8297-b85c26d5be24; range_end:; response_count:1; response_revision:5186; }","duration":"264.706887ms","start":"2026-04-20T02:15:28.358775Z","end":"2026-04-20T02:15:28.623481Z","steps":["trace[402961472] 'range keys from in-memory index tree' (duration: 264.453241ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:28.641Z","caller":"traceutil/trace.go:171","msg":"trace[1823548360] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"118.364049ms","start":"2026-04-20T02:15:28.522606Z","end":"2026-04-20T02:15:28.64097Z","steps":["trace[1823548360] 'process raft request' (duration: 118.175185ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:28.668076Z","caller":"traceutil/trace.go:171","msg":"trace[1820870911] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"129.525865ms","start":"2026-04-20T02:15:28.538511Z","end":"2026-04-20T02:15:28.668037Z","steps":["trace[1820870911] 'process raft request' (duration: 129.255809ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:29.036331Z","caller":"traceutil/trace.go:171","msg":"trace[564768889] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"212.13065ms","start":"2026-04-20T02:15:28.824181Z","end":"2026-04-20T02:15:29.036312Z","steps":["trace[564768889] 'process raft request' (duration: 211.983037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:29.268664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.741605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777473"} {"level":"info","ts":"2026-04-20T02:15:29.268767Z","caller":"traceutil/trace.go:171","msg":"trace[819302354] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5190; }","duration":"130.886988ms","start":"2026-04-20T02:15:29.137857Z","end":"2026-04-20T02:15:29.268744Z","steps":["trace[819302354] 'agreement among raft nodes before linearized reading' (duration: 51.765977ms)","trace[819302354] 'range keys from in-memory index tree' (duration: 78.559398ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:15:47.40748Z","caller":"traceutil/trace.go:171","msg":"trace[915012878] linearizableReadLoop","detail":"{readStateIndex:5736; appliedIndex:5735; }","duration":"232.844389ms","start":"2026-04-20T02:15:47.17462Z","end":"2026-04-20T02:15:47.407465Z","steps":["trace[915012878] 'read index received' (duration: 232.680965ms)","trace[915012878] 'applied index is now lower than readState.Index' (duration: 162.544µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:15:47.407595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.959541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:15:47.40762Z","caller":"traceutil/trace.go:171","msg":"trace[243992897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5537; }","duration":"233.026003ms","start":"2026-04-20T02:15:47.174586Z","end":"2026-04-20T02:15:47.407612Z","steps":["trace[243992897] 'agreement among raft nodes before linearized reading' (duration: 232.969732ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:47.407623Z","caller":"traceutil/trace.go:171","msg":"trace[1067562316] transaction","detail":"{read_only:false; response_revision:5537; number_of_response:1; }","duration":"248.661944ms","start":"2026-04-20T02:15:47.158913Z","end":"2026-04-20T02:15:47.407575Z","steps":["trace[1067562316] 'process raft request' (duration: 248.430699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:47.407887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.677431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-af197bc7-4adb-47b5-b278-f0fa4a67b482\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T02:15:47.407957Z","caller":"traceutil/trace.go:171","msg":"trace[191158422] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-af197bc7-4adb-47b5-b278-f0fa4a67b482; range_end:; response_count:1; response_revision:5537; }","duration":"151.867906ms","start":"2026-04-20T02:15:47.256072Z","end":"2026-04-20T02:15:47.40794Z","steps":["trace[191158422] 'agreement among raft nodes before linearized reading' (duration: 151.547979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:47.818096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.382851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:15:47.818322Z","caller":"traceutil/trace.go:171","msg":"trace[1948180585] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"406.248586ms","start":"2026-04-20T02:15:47.412048Z","end":"2026-04-20T02:15:47.818297Z","steps":["trace[1948180585] 'process raft request' (duration: 223.545337ms)","trace[1948180585] 'compare' (duration: 182.211237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:15:47.818411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:15:47.412028Z","time spent":"406.341688ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:15:47.818703Z","caller":"traceutil/trace.go:171","msg":"trace[1209404438] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"406.143193ms","start":"2026-04-20T02:15:47.412521Z","end":"2026-04-20T02:15:47.818664Z","steps":["trace[1209404438] 'process raft request' (duration: 405.711763ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:47.818777Z","caller":"traceutil/trace.go:171","msg":"trace[1345936845] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"405.844037ms","start":"2026-04-20T02:15:47.412916Z","end":"2026-04-20T02:15:47.81876Z","steps":["trace[1345936845] 'process raft request' (duration: 405.670033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:47.818862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:15:47.412905Z","time spent":"405.914458ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:15:47.818906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:15:47.412502Z","time spent":"406.297938ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:15:47.818901Z","caller":"traceutil/trace.go:171","msg":"trace[1430187380] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"403.282535ms","start":"2026-04-20T02:15:47.415595Z","end":"2026-04-20T02:15:47.818878Z","steps":["trace[1430187380] 'process raft request' (duration: 403.160803ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:15:47.819062Z","caller":"traceutil/trace.go:171","msg":"trace[494704055] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"405.831016ms","start":"2026-04-20T02:15:47.413199Z","end":"2026-04-20T02:15:47.81903Z","steps":["trace[494704055] 'process raft request' (duration: 405.500028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:47.819076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:15:47.41558Z","time spent":"403.400609ms","remote":"127.0.0.1:43646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:15:47.819203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:15:47.413185Z","time spent":"405.970579ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:15:47.840032Z","caller":"traceutil/trace.go:171","msg":"trace[1055772903] linearizableReadLoop","detail":"{readStateIndex:5743; appliedIndex:5742; }","duration":"145.30363ms","start":"2026-04-20T02:15:47.694702Z","end":"2026-04-20T02:15:47.840005Z","steps":["trace[1055772903] 'read index received' (duration: 145.031004ms)","trace[1055772903] 'applied index is now lower than readState.Index' (duration: 271.446µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:15:47.840081Z","caller":"traceutil/trace.go:171","msg":"trace[1284633312] transaction","detail":"{read_only:false; response_revision:5544; number_of_response:1; }","duration":"147.83167ms","start":"2026-04-20T02:15:47.692221Z","end":"2026-04-20T02:15:47.840053Z","steps":["trace[1284633312] 'process raft request' (duration: 147.646876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:15:47.840266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.550906ms","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-20T02:15:47.840308Z","caller":"traceutil/trace.go:171","msg":"trace[1901594687] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5544; }","duration":"145.643608ms","start":"2026-04-20T02:15:47.69465Z","end":"2026-04-20T02:15:47.840293Z","steps":["trace[1901594687] 'agreement among raft nodes before linearized reading' (duration: 145.459354ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:16:01.694735Z","caller":"traceutil/trace.go:171","msg":"trace[1176962369] transaction","detail":"{read_only:false; response_revision:5735; number_of_response:1; }","duration":"253.611052ms","start":"2026-04-20T02:16:01.441091Z","end":"2026-04-20T02:16:01.694702Z","steps":["trace[1176962369] 'process raft request' (duration: 187.165714ms)","trace[1176962369] 'compare' (duration: 65.937575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:16:01.695022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.062271ms","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-20T02:16:01.695114Z","caller":"traceutil/trace.go:171","msg":"trace[1987302972] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5735; }","duration":"171.220806ms","start":"2026-04-20T02:16:01.523875Z","end":"2026-04-20T02:16:01.695095Z","steps":["trace[1987302972] 'agreement among raft nodes before linearized reading' (duration: 170.923829ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:16:01.69474Z","caller":"traceutil/trace.go:171","msg":"trace[617370240] linearizableReadLoop","detail":"{readStateIndex:5941; appliedIndex:5940; }","duration":"170.722144ms","start":"2026-04-20T02:16:01.523943Z","end":"2026-04-20T02:16:01.694666Z","steps":["trace[617370240] 'read index received' (duration: 104.329437ms)","trace[617370240] 'applied index is now lower than readState.Index' (duration: 66.390557ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:16:01.703405Z","caller":"traceutil/trace.go:171","msg":"trace[428895530] transaction","detail":"{read_only:false; response_revision:5736; number_of_response:1; }","duration":"167.476716ms","start":"2026-04-20T02:16:01.5359Z","end":"2026-04-20T02:16:01.703377Z","steps":["trace[428895530] 'process raft request' (duration: 167.054056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:16:01.703751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.345268ms","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-20T02:16:01.703856Z","caller":"traceutil/trace.go:171","msg":"trace[1769535797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5736; }","duration":"139.513882ms","start":"2026-04-20T02:16:01.564317Z","end":"2026-04-20T02:16:01.703831Z","steps":["trace[1769535797] 'agreement among raft nodes before linearized reading' (duration: 139.199605ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:16:41.41917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3020} {"level":"info","ts":"2026-04-20T02:16:41.510745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3020,"took":"89.757209ms","hash":3640163764,"current-db-size-bytes":57753600,"current-db-size":"58 MB","current-db-size-in-use-bytes":31072256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-20T02:16:41.510816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3640163764,"revision":3020,"compact-revision":-1} {"level":"info","ts":"2026-04-20T02:21:41.427453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6072} {"level":"info","ts":"2026-04-20T02:21:41.526228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6072,"took":"96.256812ms","hash":1440573266,"current-db-size-bytes":57753600,"current-db-size":"58 MB","current-db-size-in-use-bytes":21794816,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-20T02:21:41.526343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1440573266,"revision":6072,"compact-revision":3020} {"level":"info","ts":"2026-04-20T02:22:12.153641Z","caller":"traceutil/trace.go:171","msg":"trace[1646562679] linearizableReadLoop","detail":"{readStateIndex:8792; appliedIndex:8791; }","duration":"288.057052ms","start":"2026-04-20T02:22:11.865561Z","end":"2026-04-20T02:22:12.153618Z","steps":["trace[1646562679] 'read index received' (duration: 287.820527ms)","trace[1646562679] 'applied index is now lower than readState.Index' (duration: 235.375µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:22:12.15375Z","caller":"traceutil/trace.go:171","msg":"trace[1951987551] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"335.141869ms","start":"2026-04-20T02:22:11.818551Z","end":"2026-04-20T02:22:12.153693Z","steps":["trace[1951987551] 'process raft request' (duration: 334.884693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:12.153903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.333409ms","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-20T02:22:12.15396Z","caller":"traceutil/trace.go:171","msg":"trace[1858889678] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8492; }","duration":"288.431251ms","start":"2026-04-20T02:22:11.865512Z","end":"2026-04-20T02:22:12.153943Z","steps":["trace[1858889678] 'agreement among raft nodes before linearized reading' (duration: 288.273697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:12.154064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:22:11.818527Z","time spent":"335.386214ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:22:12.154169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.736838ms","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-20T02:22:12.154275Z","caller":"traceutil/trace.go:171","msg":"trace[391034820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8492; }","duration":"261.885731ms","start":"2026-04-20T02:22:11.892365Z","end":"2026-04-20T02:22:12.154251Z","steps":["trace[391034820] 'agreement among raft nodes before linearized reading' (duration: 261.643985ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:12.271481Z","caller":"traceutil/trace.go:171","msg":"trace[518794556] transaction","detail":"{read_only:false; response_revision:8493; number_of_response:1; }","duration":"112.480178ms","start":"2026-04-20T02:22:12.158967Z","end":"2026-04-20T02:22:12.271447Z","steps":["trace[518794556] 'process raft request' (duration: 91.620043ms)","trace[518794556] 'compare' (duration: 20.734462ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:22:12.277982Z","caller":"traceutil/trace.go:171","msg":"trace[1331594649] linearizableReadLoop","detail":"{readStateIndex:8797; appliedIndex:8792; }","duration":"104.596501ms","start":"2026-04-20T02:22:12.173359Z","end":"2026-04-20T02:22:12.277955Z","steps":["trace[1331594649] 'read index received' (duration: 77.297054ms)","trace[1331594649] 'applied index is now lower than readState.Index' (duration: 27.298407ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:22:12.27814Z","caller":"traceutil/trace.go:171","msg":"trace[101415598] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"118.296775ms","start":"2026-04-20T02:22:12.159832Z","end":"2026-04-20T02:22:12.278129Z","steps":["trace[101415598] 'process raft request' (duration: 118.08054ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:12.277984Z","caller":"traceutil/trace.go:171","msg":"trace[642080067] transaction","detail":"{read_only:false; response_revision:8494; number_of_response:1; }","duration":"118.420709ms","start":"2026-04-20T02:22:12.15952Z","end":"2026-04-20T02:22:12.277941Z","steps":["trace[642080067] 'process raft request' (duration: 118.176713ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:12.278151Z","caller":"traceutil/trace.go:171","msg":"trace[1589135433] transaction","detail":"{read_only:false; response_revision:8495; number_of_response:1; }","duration":"118.385088ms","start":"2026-04-20T02:22:12.159727Z","end":"2026-04-20T02:22:12.278112Z","steps":["trace[1589135433] 'process raft request' (duration: 118.119522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:12.278307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.900968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:22:12.278399Z","caller":"traceutil/trace.go:171","msg":"trace[148122470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8497; }","duration":"105.059082ms","start":"2026-04-20T02:22:12.173294Z","end":"2026-04-20T02:22:12.278353Z","steps":["trace[148122470] 'agreement among raft nodes before linearized reading' (duration: 104.908558ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:12.278423Z","caller":"traceutil/trace.go:171","msg":"trace[373683642] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"118.527251ms","start":"2026-04-20T02:22:12.159812Z","end":"2026-04-20T02:22:12.278339Z","steps":["trace[373683642] 'process raft request' (duration: 118.073061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:25.032245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.461904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T02:22:25.032359Z","caller":"traceutil/trace.go:171","msg":"trace[2126679121] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8566; }","duration":"173.653059ms","start":"2026-04-20T02:22:24.858682Z","end":"2026-04-20T02:22:25.032335Z","steps":["trace[2126679121] 'count revisions from in-memory index tree' (duration: 173.329361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:25.032327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.989128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p2qnk\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T02:22:25.032443Z","caller":"traceutil/trace.go:171","msg":"trace[1230024720] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p2qnk; range_end:; response_count:1; response_revision:8566; }","duration":"204.183044ms","start":"2026-04-20T02:22:24.828234Z","end":"2026-04-20T02:22:25.032417Z","steps":["trace[1230024720] 'range keys from in-memory index tree' (duration: 203.761523ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:26.507942Z","caller":"traceutil/trace.go:171","msg":"trace[1803235928] linearizableReadLoop","detail":"{readStateIndex:8873; appliedIndex:8872; }","duration":"116.599136ms","start":"2026-04-20T02:22:26.391309Z","end":"2026-04-20T02:22:26.507908Z","steps":["trace[1803235928] 'read index received' (duration: 116.483953ms)","trace[1803235928] 'applied index is now lower than readState.Index' (duration: 113.983µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:22:26.50812Z","caller":"traceutil/trace.go:171","msg":"trace[2069606529] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"116.846971ms","start":"2026-04-20T02:22:26.391225Z","end":"2026-04-20T02:22:26.508071Z","steps":["trace[2069606529] 'process raft request' (duration: 116.35347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:26.508242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.901233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T02:22:26.508288Z","caller":"traceutil/trace.go:171","msg":"trace[1014663897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8570; }","duration":"117.023975ms","start":"2026-04-20T02:22:26.391252Z","end":"2026-04-20T02:22:26.508276Z","steps":["trace[1014663897] 'agreement among raft nodes before linearized reading' (duration: 116.827231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:26.509568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.211734ms","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-20T02:22:26.509677Z","caller":"traceutil/trace.go:171","msg":"trace[1820655640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8572; }","duration":"118.340037ms","start":"2026-04-20T02:22:26.391314Z","end":"2026-04-20T02:22:26.509654Z","steps":["trace[1820655640] 'agreement among raft nodes before linearized reading' (duration: 118.04514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:26.509917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.544002ms","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-20T02:22:26.509958Z","caller":"traceutil/trace.go:171","msg":"trace[935085968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8572; }","duration":"118.616343ms","start":"2026-04-20T02:22:26.391332Z","end":"2026-04-20T02:22:26.509948Z","steps":["trace[935085968] 'agreement among raft nodes before linearized reading' (duration: 118.50786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:26.509953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.06061ms","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-20T02:22:26.510037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.24263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:22:26.510054Z","caller":"traceutil/trace.go:171","msg":"trace[2057764418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8572; }","duration":"118.198084ms","start":"2026-04-20T02:22:26.391832Z","end":"2026-04-20T02:22:26.51003Z","steps":["trace[2057764418] 'agreement among raft nodes before linearized reading' (duration: 117.923657ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:26.51011Z","caller":"traceutil/trace.go:171","msg":"trace[526428261] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:8572; }","duration":"106.373783ms","start":"2026-04-20T02:22:26.403719Z","end":"2026-04-20T02:22:26.510093Z","steps":["trace[526428261] 'agreement among raft nodes before linearized reading' (duration: 106.24425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:53.188678Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367128871328158,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T02:22:53.322656Z","caller":"traceutil/trace.go:171","msg":"trace[1138215089] linearizableReadLoop","detail":"{readStateIndex:9076; appliedIndex:9075; }","duration":"634.214282ms","start":"2026-04-20T02:22:52.688412Z","end":"2026-04-20T02:22:53.322626Z","steps":["trace[1138215089] 'read index received' (duration: 634.012677ms)","trace[1138215089] 'applied index is now lower than readState.Index' (duration: 200.395µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:22:53.322806Z","caller":"traceutil/trace.go:171","msg":"trace[1495036830] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"644.348973ms","start":"2026-04-20T02:22:52.678443Z","end":"2026-04-20T02:22:53.322792Z","steps":["trace[1495036830] 'process raft request' (duration: 644.007575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:53.323138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:22:52.678424Z","time spent":"644.411264ms","remote":"127.0.0.1:43730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:22:53.323138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.429167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T02:22:53.323184Z","caller":"traceutil/trace.go:171","msg":"trace[1834963491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8766; }","duration":"634.473078ms","start":"2026-04-20T02:22:52.688699Z","end":"2026-04-20T02:22:53.323172Z","steps":["trace[1834963491] 'agreement among raft nodes before linearized reading' (duration: 634.101229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:53.323214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:22:52.688647Z","time spent":"634.558731ms","remote":"127.0.0.1:43730","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-20T02:22:53.323476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.059932ms","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-20T02:22:53.323502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.062443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5ff547dcfd-6xq97.18a7ef5437d5047b\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-20T02:22:53.323509Z","caller":"traceutil/trace.go:171","msg":"trace[2041532774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8766; }","duration":"635.091753ms","start":"2026-04-20T02:22:52.688406Z","end":"2026-04-20T02:22:53.323498Z","steps":["trace[2041532774] 'agreement among raft nodes before linearized reading' (duration: 635.000341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:53.323536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:22:52.688369Z","time spent":"635.160095ms","remote":"127.0.0.1:43730","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":"warn","ts":"2026-04-20T02:22:53.323692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.718679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:22:53.323724Z","caller":"traceutil/trace.go:171","msg":"trace[102494541] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:8766; }","duration":"482.7496ms","start":"2026-04-20T02:22:52.840962Z","end":"2026-04-20T02:22:53.323712Z","steps":["trace[102494541] 'agreement among raft nodes before linearized reading' (duration: 482.693139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:53.323751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:22:52.840933Z","time spent":"482.809612ms","remote":"127.0.0.1:59742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:22:53.323807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.10532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:22:53.323839Z","caller":"traceutil/trace.go:171","msg":"trace[918306667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8766; }","duration":"150.190623ms","start":"2026-04-20T02:22:53.173639Z","end":"2026-04-20T02:22:53.323829Z","steps":["trace[918306667] 'agreement among raft nodes before linearized reading' (duration: 150.135541ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:53.323538Z","caller":"traceutil/trace.go:171","msg":"trace[563513473] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5ff547dcfd-6xq97.18a7ef5437d5047b; range_end:; response_count:1; response_revision:8766; }","duration":"307.139456ms","start":"2026-04-20T02:22:53.016384Z","end":"2026-04-20T02:22:53.323524Z","steps":["trace[563513473] 'agreement among raft nodes before linearized reading' (duration: 307.042263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:53.323971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:22:53.016359Z","time spent":"307.601416ms","remote":"127.0.0.1:43568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1158,"request content":"key:\"/registry/events/openstack/barbican-api-5ff547dcfd-6xq97.18a7ef5437d5047b\" "} {"level":"warn","ts":"2026-04-20T02:22:53.326394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.663677ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:22:53.326476Z","caller":"traceutil/trace.go:171","msg":"trace[225412144] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8765; }","duration":"347.809309ms","start":"2026-04-20T02:22:52.978655Z","end":"2026-04-20T02:22:53.326465Z","steps":["trace[225412144] 'range keys from in-memory index tree' (duration: 342.468483ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:57.422787Z","caller":"traceutil/trace.go:171","msg":"trace[1214061720] linearizableReadLoop","detail":"{readStateIndex:9101; appliedIndex:9100; }","duration":"112.741554ms","start":"2026-04-20T02:22:57.31001Z","end":"2026-04-20T02:22:57.422752Z","steps":["trace[1214061720] 'read index received' (duration: 112.496258ms)","trace[1214061720] 'applied index is now lower than readState.Index' (duration: 243.616µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:22:57.422841Z","caller":"traceutil/trace.go:171","msg":"trace[119561815] transaction","detail":"{read_only:false; response_revision:8790; number_of_response:1; }","duration":"120.783835ms","start":"2026-04-20T02:22:57.302016Z","end":"2026-04-20T02:22:57.422799Z","steps":["trace[119561815] 'process raft request' (duration: 120.527979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:57.42319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.137494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-20T02:22:57.423353Z","caller":"traceutil/trace.go:171","msg":"trace[1782972323] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8790; }","duration":"113.389189ms","start":"2026-04-20T02:22:57.309933Z","end":"2026-04-20T02:22:57.423323Z","steps":["trace[1782972323] 'agreement among raft nodes before linearized reading' (duration: 112.976219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:22:57.462729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.283261ms","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-20T02:22:57.462857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.304608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-20T02:22:57.462842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.800767ms","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-20T02:22:57.462876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.921994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p2qnk\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T02:22:57.462943Z","caller":"traceutil/trace.go:171","msg":"trace[1076727764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8791; }","duration":"116.411701ms","start":"2026-04-20T02:22:57.346507Z","end":"2026-04-20T02:22:57.462919Z","steps":["trace[1076727764] 'agreement among raft nodes before linearized reading' (duration: 116.172516ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:57.462964Z","caller":"traceutil/trace.go:171","msg":"trace[1352031944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8791; }","duration":"115.971921ms","start":"2026-04-20T02:22:57.346967Z","end":"2026-04-20T02:22:57.462939Z","steps":["trace[1352031944] 'agreement among raft nodes before linearized reading' (duration: 115.644503ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:57.462993Z","caller":"traceutil/trace.go:171","msg":"trace[1295812196] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p2qnk; range_end:; response_count:1; response_revision:8791; }","duration":"109.080258ms","start":"2026-04-20T02:22:57.353889Z","end":"2026-04-20T02:22:57.462969Z","steps":["trace[1295812196] 'agreement among raft nodes before linearized reading' (duration: 108.708909ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:22:57.462839Z","caller":"traceutil/trace.go:171","msg":"trace[1090480536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8791; }","duration":"117.464026ms","start":"2026-04-20T02:22:57.345349Z","end":"2026-04-20T02:22:57.462813Z","steps":["trace[1090480536] 'agreement among raft nodes before linearized reading' (duration: 117.086567ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:23:06.540906Z","caller":"traceutil/trace.go:171","msg":"trace[1470879082] transaction","detail":"{read_only:false; response_revision:8881; number_of_response:1; }","duration":"134.869089ms","start":"2026-04-20T02:23:06.405998Z","end":"2026-04-20T02:23:06.540867Z","steps":["trace[1470879082] 'process raft request' (duration: 134.689184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:23:06.812302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.635293ms","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-20T02:23:06.812681Z","caller":"traceutil/trace.go:171","msg":"trace[849861971] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8881; }","duration":"210.070793ms","start":"2026-04-20T02:23:06.602584Z","end":"2026-04-20T02:23:06.812655Z","steps":["trace[849861971] 'range keys from in-memory index tree' (duration: 209.455768ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:23:06.825561Z","caller":"traceutil/trace.go:171","msg":"trace[376510827] transaction","detail":"{read_only:false; response_revision:8882; number_of_response:1; }","duration":"172.945082ms","start":"2026-04-20T02:23:06.652589Z","end":"2026-04-20T02:23:06.825534Z","steps":["trace[376510827] 'process raft request' (duration: 172.778488ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:23:06.859493Z","caller":"traceutil/trace.go:171","msg":"trace[1387971651] linearizableReadLoop","detail":"{readStateIndex:9199; appliedIndex:9197; }","duration":"157.142567ms","start":"2026-04-20T02:23:06.70232Z","end":"2026-04-20T02:23:06.859462Z","steps":["trace[1387971651] 'read index received' (duration: 123.11734ms)","trace[1387971651] 'applied index is now lower than readState.Index' (duration: 34.023527ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:23:06.859634Z","caller":"traceutil/trace.go:171","msg":"trace[1538454507] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"203.537428ms","start":"2026-04-20T02:23:06.65605Z","end":"2026-04-20T02:23:06.859587Z","steps":["trace[1538454507] 'process raft request' (duration: 203.253341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:23:06.859969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.778407ms","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-20T02:23:06.860162Z","caller":"traceutil/trace.go:171","msg":"trace[90560592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8883; }","duration":"111.040734ms","start":"2026-04-20T02:23:06.749101Z","end":"2026-04-20T02:23:06.860141Z","steps":["trace[90560592] 'agreement among raft nodes before linearized reading' (duration: 110.653525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:23:06.860054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.702711ms","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-20T02:23:06.860509Z","caller":"traceutil/trace.go:171","msg":"trace[989213061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8883; }","duration":"158.227813ms","start":"2026-04-20T02:23:06.702262Z","end":"2026-04-20T02:23:06.86049Z","steps":["trace[989213061] 'agreement among raft nodes before linearized reading' (duration: 157.355933ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:23:08.025821Z","caller":"traceutil/trace.go:171","msg":"trace[971474270] linearizableReadLoop","detail":"{readStateIndex:9209; appliedIndex:9208; }","duration":"159.693496ms","start":"2026-04-20T02:23:07.866088Z","end":"2026-04-20T02:23:08.025782Z","steps":["trace[971474270] 'read index received' (duration: 159.46237ms)","trace[971474270] 'applied index is now lower than readState.Index' (duration: 229.946µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:23:08.025895Z","caller":"traceutil/trace.go:171","msg":"trace[578780693] transaction","detail":"{read_only:false; response_revision:8893; number_of_response:1; }","duration":"319.298649ms","start":"2026-04-20T02:23:07.706561Z","end":"2026-04-20T02:23:08.02586Z","steps":["trace[578780693] 'process raft request' (duration: 318.95737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:23:08.026088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:23:07.706534Z","time spent":"319.425641ms","remote":"127.0.0.1:51744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:23:08.026062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.938651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T02:23:08.026332Z","caller":"traceutil/trace.go:171","msg":"trace[1538947442] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8893; }","duration":"160.272459ms","start":"2026-04-20T02:23:07.866031Z","end":"2026-04-20T02:23:08.026303Z","steps":["trace[1538947442] 'agreement among raft nodes before linearized reading' (duration: 159.922091ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:23:25.23357Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a1.wal"} {"level":"info","ts":"2026-04-20T02:24:12.113356Z","caller":"traceutil/trace.go:171","msg":"trace[1335310334] transaction","detail":"{read_only:false; response_revision:9563; number_of_response:1; }","duration":"120.636649ms","start":"2026-04-20T02:24:11.99269Z","end":"2026-04-20T02:24:12.113327Z","steps":["trace[1335310334] 'process raft request' (duration: 120.422274ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:24:12.114077Z","caller":"traceutil/trace.go:171","msg":"trace[1718314061] transaction","detail":"{read_only:false; response_revision:9564; number_of_response:1; }","duration":"120.197579ms","start":"2026-04-20T02:24:11.993852Z","end":"2026-04-20T02:24:12.11405Z","steps":["trace[1718314061] 'process raft request' (duration: 120.021365ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:24:12.114445Z","caller":"traceutil/trace.go:171","msg":"trace[640977148] transaction","detail":"{read_only:false; response_revision:9565; number_of_response:1; }","duration":"118.97342ms","start":"2026-04-20T02:24:11.995455Z","end":"2026-04-20T02:24:12.114429Z","steps":["trace[640977148] 'process raft request' (duration: 118.54859ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:24:28.204329Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d71a1778b31d4ce","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T02:24:28.209983Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-04-20T02:24:28.2101Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-04-20T02:26:41.442865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8155} {"level":"info","ts":"2026-04-20T02:26:41.51421Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8155,"took":"68.461762ms","hash":1749299342,"current-db-size-bytes":57753600,"current-db-size":"58 MB","current-db-size-in-use-bytes":28127232,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-20T02:26:41.514285Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1749299342,"revision":8155,"compact-revision":6072} {"level":"info","ts":"2026-04-20T02:31:41.451032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10561} {"level":"info","ts":"2026-04-20T02:31:41.537355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10561,"took":"83.773335ms","hash":371073302,"current-db-size-bytes":57753600,"current-db-size":"58 MB","current-db-size-in-use-bytes":22437888,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-20T02:31:41.537558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":371073302,"revision":10561,"compact-revision":8155}