{"level":"warn","ts":"2026-04-13T01:23:45.815861Z","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-13T01:23:45.815998Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.65: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.65:2380","--initial-cluster=instance=https://199.204.45.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.65: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-13T01:23:45.816092Z","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-13T01:23:45.81612Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-13T01:23:45.816147Z","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-13T01:23:45.81707Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"]} {"level":"info","ts":"2026-04-13T01:23:45.817219Z","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":8,"max-cpu-available":8,"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.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.65: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-13T01:23:45.822683Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.168933ms"} {"level":"info","ts":"2026-04-13T01:23:45.831989Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cf8c3a138c9a77a2","cluster-id":"c278183e1f634dff"} {"level":"info","ts":"2026-04-13T01:23:45.832137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T01:23:45.832192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 0"} {"level":"info","ts":"2026-04-13T01:23:45.832218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cf8c3a138c9a77a2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T01:23:45.832231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became follower at term 1"} {"level":"info","ts":"2026-04-13T01:23:45.832299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"warn","ts":"2026-04-13T01:23:45.842281Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T01:23:45.848252Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T01:23:45.860911Z","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-13T01:23:45.863666Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cf8c3a138c9a77a2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T01:23:45.86392Z","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-13T01:23:45.864065Z","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-13T01:23:45.86408Z","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-13T01:23:45.864524Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cf8c3a138c9a77a2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T01:23:45.878993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 switched to configuration voters=(14955392318228297634)"} {"level":"info","ts":"2026-04-13T01:23:45.879128Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","added-peer-id":"cf8c3a138c9a77a2","added-peer-peer-urls":["https://199.204.45.65:2380"]} {"level":"info","ts":"2026-04-13T01:23:45.88008Z","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-13T01:23:45.880238Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-13T01:23:45.880284Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.65:2380"} {"level":"info","ts":"2026-04-13T01:23:45.880448Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cf8c3a138c9a77a2","initial-advertise-peer-urls":["https://199.204.45.65:2380"],"listen-peer-urls":["https://199.204.45.65:2380"],"advertise-client-urls":["https://199.204.45.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T01:23:45.880486Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T01:23:46.633541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T01:23:46.633609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T01:23:46.633646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgPreVoteResp from cf8c3a138c9a77a2 at term 1"} {"level":"info","ts":"2026-04-13T01:23:46.633667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became candidate at term 2"} {"level":"info","ts":"2026-04-13T01:23:46.633678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 received MsgVoteResp from cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-13T01:23:46.633705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cf8c3a138c9a77a2 became leader at term 2"} {"level":"info","ts":"2026-04-13T01:23:46.63372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cf8c3a138c9a77a2 elected leader cf8c3a138c9a77a2 at term 2"} {"level":"info","ts":"2026-04-13T01:23:46.634763Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T01:23:46.63548Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T01:23:46.635486Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cf8c3a138c9a77a2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.65:2379]}","request-path":"/0/members/cf8c3a138c9a77a2/attributes","cluster-id":"c278183e1f634dff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T01:23:46.635701Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T01:23:46.635979Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T01:23:46.636103Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T01:23:46.635989Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c278183e1f634dff","local-member-id":"cf8c3a138c9a77a2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T01:23:46.638443Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.65:2379"} {"level":"info","ts":"2026-04-13T01:23:46.638462Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T01:23:46.638505Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T01:23:46.640138Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-13T01:23:55.764675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.764886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T01:23:55.764864Z","caller":"traceutil/trace.go:171","msg":"trace[2106251895] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"300.00799ms","start":"2026-04-13T01:23:55.464821Z","end":"2026-04-13T01:23:55.764829Z","steps":["trace[2106251895] 'process raft request' (duration: 75.317742ms)","trace[2106251895] 'compare' (duration: 223.609544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:23:55.765012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.085756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T01:23:55.76504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:55.464801Z","time spent":"300.142961ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T01:23:55.765098Z","caller":"traceutil/trace.go:171","msg":"trace[1682121503] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:337; }","duration":"300.210656ms","start":"2026-04-13T01:23:55.464868Z","end":"2026-04-13T01:23:55.765079Z","steps":["trace[1682121503] 'agreement among raft nodes before linearized reading' (duration: 300.063465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:55.765157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:55.464853Z","time spent":"300.292062ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" "} {"level":"info","ts":"2026-04-13T01:23:55.764816Z","caller":"traceutil/trace.go:171","msg":"trace[1824975805] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"299.889951ms","start":"2026-04-13T01:23:55.464907Z","end":"2026-04-13T01:23:55.764797Z","steps":["trace[1824975805] 'read index received' (duration: 75.253747ms)","trace[1824975805] 'applied index is now lower than readState.Index' (duration: 224.634844ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:23:56.002738Z","caller":"traceutil/trace.go:171","msg":"trace[1457519158] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"134.128487ms","start":"2026-04-13T01:23:55.868591Z","end":"2026-04-13T01:23:56.002719Z","steps":["trace[1457519158] 'read index received' (duration: 133.938062ms)","trace[1457519158] 'applied index is now lower than readState.Index' (duration: 189.635µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:23:56.002948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.348134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-13T01:23:56.002942Z","caller":"traceutil/trace.go:171","msg":"trace[354059824] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"143.028491ms","start":"2026-04-13T01:23:55.8598Z","end":"2026-04-13T01:23:56.002828Z","steps":["trace[354059824] 'process raft request' (duration: 142.776752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:56.002964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.703223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-13T01:23:56.003048Z","caller":"traceutil/trace.go:171","msg":"trace[2072871413] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:339; }","duration":"114.809321ms","start":"2026-04-13T01:23:55.888225Z","end":"2026-04-13T01:23:56.003034Z","steps":["trace[2072871413] 'agreement among raft nodes before linearized reading' (duration: 114.615476ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:56.00298Z","caller":"traceutil/trace.go:171","msg":"trace[1978941344] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:339; }","duration":"134.421481ms","start":"2026-04-13T01:23:55.86855Z","end":"2026-04-13T01:23:56.002971Z","steps":["trace[1978941344] 'agreement among raft nodes before linearized reading' (duration: 134.27993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:56.003198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.487452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-13T01:23:56.003232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.746592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-13T01:23:56.003254Z","caller":"traceutil/trace.go:171","msg":"trace[229926142] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:339; }","duration":"111.797576ms","start":"2026-04-13T01:23:55.89145Z","end":"2026-04-13T01:23:56.003247Z","steps":["trace[229926142] 'agreement among raft nodes before linearized reading' (duration: 111.756673ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:56.00325Z","caller":"traceutil/trace.go:171","msg":"trace[1863933096] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:339; }","duration":"111.561958ms","start":"2026-04-13T01:23:55.891675Z","end":"2026-04-13T01:23:56.003237Z","steps":["trace[1863933096] 'agreement among raft nodes before linearized reading' (duration: 111.417047ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:56.440736Z","caller":"traceutil/trace.go:171","msg":"trace[858258257] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"356.933149ms","start":"2026-04-13T01:23:56.083781Z","end":"2026-04-13T01:23:56.440714Z","steps":["trace[858258257] 'read index received' (duration: 283.443844ms)","trace[858258257] 'applied index is now lower than readState.Index' (duration: 73.488345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:23:56.440934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.133475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-13T01:23:56.440976Z","caller":"traceutil/trace.go:171","msg":"trace[133329265] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"364.805655ms","start":"2026-04-13T01:23:56.076045Z","end":"2026-04-13T01:23:56.44085Z","steps":["trace[133329265] 'process raft request' (duration: 291.199402ms)","trace[133329265] 'compare' (duration: 73.367305ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:23:56.441026Z","caller":"traceutil/trace.go:171","msg":"trace[754138750] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:342; }","duration":"357.274096ms","start":"2026-04-13T01:23:56.083738Z","end":"2026-04-13T01:23:56.441012Z","steps":["trace[754138750] 'agreement among raft nodes before linearized reading' (duration: 357.099222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:56.441087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.083722Z","time spent":"357.33128ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-04-13T01:23:56.441211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.316343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-13T01:23:56.441263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.076035Z","time spent":"365.003601ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T01:23:56.441295Z","caller":"traceutil/trace.go:171","msg":"trace[913805231] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:342; }","duration":"107.421242ms","start":"2026-04-13T01:23:56.333856Z","end":"2026-04-13T01:23:56.441277Z","steps":["trace[913805231] 'agreement among raft nodes before linearized reading' (duration: 107.297922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:56.441378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.093236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-13T01:23:56.441414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.753395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-13T01:23:56.440988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.00547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-13T01:23:56.441567Z","caller":"traceutil/trace.go:171","msg":"trace[345905948] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:342; }","duration":"256.924218ms","start":"2026-04-13T01:23:56.184564Z","end":"2026-04-13T01:23:56.441488Z","steps":["trace[345905948] 'agreement among raft nodes before linearized reading' (duration: 256.68725ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:56.441644Z","caller":"traceutil/trace.go:171","msg":"trace[1444842994] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:342; }","duration":"207.223626ms","start":"2026-04-13T01:23:56.234238Z","end":"2026-04-13T01:23:56.441462Z","steps":["trace[1444842994] 'agreement among raft nodes before linearized reading' (duration: 206.973077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:56.441643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.439039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-13T01:23:56.441968Z","caller":"traceutil/trace.go:171","msg":"trace[789855709] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:342; }","duration":"156.808149ms","start":"2026-04-13T01:23:56.285136Z","end":"2026-04-13T01:23:56.441944Z","steps":["trace[789855709] 'agreement among raft nodes before linearized reading' (duration: 156.378615ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:56.441597Z","caller":"traceutil/trace.go:171","msg":"trace[402931613] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:342; }","duration":"307.64807ms","start":"2026-04-13T01:23:56.133933Z","end":"2026-04-13T01:23:56.441581Z","steps":["trace[402931613] 'agreement among raft nodes before linearized reading' (duration: 306.994548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:56.442166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.133915Z","time spent":"308.233666ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-13T01:23:56.985958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8620625828880853787,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T01:23:57.173324Z","caller":"traceutil/trace.go:171","msg":"trace[376640517] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"727.091479ms","start":"2026-04-13T01:23:56.446193Z","end":"2026-04-13T01:23:57.173285Z","steps":["trace[376640517] 'process raft request' (duration: 646.616601ms)","trace[376640517] 'compare' (duration: 79.548135ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:23:57.173438Z","caller":"traceutil/trace.go:171","msg":"trace[1712061964] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:355; }","duration":"687.89022ms","start":"2026-04-13T01:23:56.485521Z","end":"2026-04-13T01:23:57.173411Z","steps":["trace[1712061964] 'read index received' (duration: 607.450355ms)","trace[1712061964] 'applied index is now lower than readState.Index' (duration: 80.438655ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:23:57.173572Z","caller":"traceutil/trace.go:171","msg":"trace[1246691397] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"724.872886ms","start":"2026-04-13T01:23:56.448676Z","end":"2026-04-13T01:23:57.173549Z","steps":["trace[1246691397] 'process raft request' (duration: 724.475555ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:57.173578Z","caller":"traceutil/trace.go:171","msg":"trace[1980035112] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"723.062043ms","start":"2026-04-13T01:23:56.450405Z","end":"2026-04-13T01:23:57.173467Z","steps":["trace[1980035112] 'process raft request' (duration: 722.912002ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:57.173733Z","caller":"traceutil/trace.go:171","msg":"trace[2108933817] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"723.156571ms","start":"2026-04-13T01:23:56.450564Z","end":"2026-04-13T01:23:57.173721Z","steps":["trace[2108933817] 'process raft request' (duration: 722.795863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:57.173807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.450557Z","time spent":"723.216097ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T01:23:57.17379Z","caller":"traceutil/trace.go:171","msg":"trace[1278614361] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"723.443184ms","start":"2026-04-13T01:23:56.45032Z","end":"2026-04-13T01:23:57.173763Z","steps":["trace[1278614361] 'process raft request' (duration: 722.947715ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:57.173808Z","caller":"traceutil/trace.go:171","msg":"trace[514568334] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"723.537632ms","start":"2026-04-13T01:23:56.45025Z","end":"2026-04-13T01:23:57.173788Z","steps":["trace[514568334] 'process raft request' (duration: 722.979118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:57.173784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.450397Z","time spent":"723.287032ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T01:23:57.173841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.448657Z","time spent":"724.979624ms","remote":"127.0.0.1:57280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T01:23:57.173911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.450226Z","time spent":"723.644959ms","remote":"127.0.0.1:57280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T01:23:57.173931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.450311Z","time spent":"723.545352ms","remote":"127.0.0.1:57336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T01:23:57.174049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.53255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-13T01:23:57.174098Z","caller":"traceutil/trace.go:171","msg":"trace[1426622548] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:348; }","duration":"688.582214ms","start":"2026-04-13T01:23:56.485504Z","end":"2026-04-13T01:23:57.174087Z","steps":["trace[1426622548] 'agreement among raft nodes before linearized reading' (duration: 688.495187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:57.174132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.485454Z","time spent":"688.670501ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-04-13T01:23:57.174189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.263736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-04-13T01:23:57.173756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.446169Z","time spent":"727.313977ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T01:23:57.174328Z","caller":"traceutil/trace.go:171","msg":"trace[519066550] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:348; }","duration":"637.434899ms","start":"2026-04-13T01:23:56.53684Z","end":"2026-04-13T01:23:57.174275Z","steps":["trace[519066550] 'agreement among raft nodes before linearized reading' (duration: 637.257525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:57.174415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.997494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T01:23:57.174426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.536806Z","time spent":"637.56976ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-04-13T01:23:57.17446Z","caller":"traceutil/trace.go:171","msg":"trace[13081990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:348; }","duration":"136.07715ms","start":"2026-04-13T01:23:57.038374Z","end":"2026-04-13T01:23:57.174451Z","steps":["trace[13081990] 'agreement among raft nodes before linearized reading' (duration: 136.017515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:57.174458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.589013ms","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-13T01:23:57.174517Z","caller":"traceutil/trace.go:171","msg":"trace[1675126109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"418.718363ms","start":"2026-04-13T01:23:56.755787Z","end":"2026-04-13T01:23:57.174505Z","steps":["trace[1675126109] 'agreement among raft nodes before linearized reading' (duration: 418.599054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:57.174553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.755769Z","time spent":"418.776967ms","remote":"127.0.0.1:57412","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-13T01:23:57.174538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.166719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-13T01:23:57.174697Z","caller":"traceutil/trace.go:171","msg":"trace[1417108320] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:348; }","duration":"489.342723ms","start":"2026-04-13T01:23:56.685302Z","end":"2026-04-13T01:23:57.174645Z","steps":["trace[1417108320] 'agreement among raft nodes before linearized reading' (duration: 489.113545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:57.174734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.395689ms","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-13T01:23:57.174766Z","caller":"traceutil/trace.go:171","msg":"trace[65963265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:348; }","duration":"282.469195ms","start":"2026-04-13T01:23:56.892287Z","end":"2026-04-13T01:23:57.174756Z","steps":["trace[65963265] 'agreement among raft nodes before linearized reading' (duration: 282.417981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:57.174884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.68528Z","time spent":"489.512837ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-13T01:23:57.17512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.42971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-13T01:23:57.175209Z","caller":"traceutil/trace.go:171","msg":"trace[212361108] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"589.55548ms","start":"2026-04-13T01:23:56.585636Z","end":"2026-04-13T01:23:57.175192Z","steps":["trace[212361108] 'agreement among raft nodes before linearized reading' (duration: 589.385277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:57.175259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.585624Z","time spent":"589.626656ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-04-13T01:23:57.175296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.215347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-13T01:23:57.175403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.588378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-13T01:23:57.175436Z","caller":"traceutil/trace.go:171","msg":"trace[1719452309] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:348; }","duration":"539.640263ms","start":"2026-04-13T01:23:56.635787Z","end":"2026-04-13T01:23:57.175427Z","steps":["trace[1719452309] 'agreement among raft nodes before linearized reading' (duration: 539.587498ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:57.175432Z","caller":"traceutil/trace.go:171","msg":"trace[1213900734] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:348; }","duration":"440.37906ms","start":"2026-04-13T01:23:56.734999Z","end":"2026-04-13T01:23:57.175379Z","steps":["trace[1213900734] 'agreement among raft nodes before linearized reading' (duration: 440.199336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:23:57.175467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.635776Z","time spent":"539.685476ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-04-13T01:23:57.175533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:23:56.734978Z","time spent":"440.498129ms","remote":"127.0.0.1:57366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-04-13T01:23:57.334833Z","caller":"traceutil/trace.go:171","msg":"trace[76810704] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"130.283474ms","start":"2026-04-13T01:23:57.204501Z","end":"2026-04-13T01:23:57.334785Z","steps":["trace[76810704] 'process raft request' (duration: 111.240047ms)","trace[76810704] 'compare' (duration: 18.604842ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:23:57.47101Z","caller":"traceutil/trace.go:171","msg":"trace[770009907] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"120.033068ms","start":"2026-04-13T01:23:57.350926Z","end":"2026-04-13T01:23:57.470959Z","steps":["trace[770009907] 'process raft request' (duration: 105.929139ms)","trace[770009907] 'compare' (duration: 13.895563ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:23:57.471121Z","caller":"traceutil/trace.go:171","msg":"trace[1279779704] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"119.959082ms","start":"2026-04-13T01:23:57.351129Z","end":"2026-04-13T01:23:57.471052Z","steps":["trace[1279779704] 'process raft request' (duration: 119.774608ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:57.471549Z","caller":"traceutil/trace.go:171","msg":"trace[601220583] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"120.255726ms","start":"2026-04-13T01:23:57.351266Z","end":"2026-04-13T01:23:57.471521Z","steps":["trace[601220583] 'process raft request' (duration: 119.697192ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:57.471604Z","caller":"traceutil/trace.go:171","msg":"trace[728858619] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"117.679413ms","start":"2026-04-13T01:23:57.353903Z","end":"2026-04-13T01:23:57.471582Z","steps":["trace[728858619] 'process raft request' (duration: 117.310174ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:57.471645Z","caller":"traceutil/trace.go:171","msg":"trace[777079477] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"119.804711ms","start":"2026-04-13T01:23:57.351831Z","end":"2026-04-13T01:23:57.471636Z","steps":["trace[777079477] 'process raft request' (duration: 119.248887ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:57.471831Z","caller":"traceutil/trace.go:171","msg":"trace[765832983] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"117.555083ms","start":"2026-04-13T01:23:57.354254Z","end":"2026-04-13T01:23:57.471809Z","steps":["trace[765832983] 'process raft request' (duration: 117.045883ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:57.471585Z","caller":"traceutil/trace.go:171","msg":"trace[1927912257] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"120.169569ms","start":"2026-04-13T01:23:57.351398Z","end":"2026-04-13T01:23:57.471568Z","steps":["trace[1927912257] 'process raft request' (duration: 119.613405ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:23:57.471984Z","caller":"traceutil/trace.go:171","msg":"trace[328012435] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"119.357256ms","start":"2026-04-13T01:23:57.352608Z","end":"2026-04-13T01:23:57.471965Z","steps":["trace[328012435] 'process raft request' (duration: 118.513629ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:24:03.274886Z","caller":"traceutil/trace.go:171","msg":"trace[944121982] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"100.929424ms","start":"2026-04-13T01:24:03.173922Z","end":"2026-04-13T01:24:03.274852Z","steps":["trace[944121982] 'read index received' (duration: 100.716497ms)","trace[944121982] 'applied index is now lower than readState.Index' (duration: 211.807µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:24:03.275058Z","caller":"traceutil/trace.go:171","msg":"trace[293499852] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"188.618323ms","start":"2026-04-13T01:24:03.086427Z","end":"2026-04-13T01:24:03.275045Z","steps":["trace[293499852] 'process raft request' (duration: 188.247943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:03.275415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.470557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:24:03.275538Z","caller":"traceutil/trace.go:171","msg":"trace[1525534990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"101.635031ms","start":"2026-04-13T01:24:03.173866Z","end":"2026-04-13T01:24:03.275501Z","steps":["trace[1525534990] 'agreement among raft nodes before linearized reading' (duration: 101.447436ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:24:25.156672Z","caller":"traceutil/trace.go:171","msg":"trace[341203779] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"350.258821ms","start":"2026-04-13T01:24:24.806377Z","end":"2026-04-13T01:24:25.156635Z","steps":["trace[341203779] 'process raft request' (duration: 349.477765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:25.15693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:24:24.806349Z","time spent":"350.433776ms","remote":"127.0.0.1:57272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T01:24:25.797557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.346577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T01:24:25.798097Z","caller":"traceutil/trace.go:171","msg":"trace[1536524260] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"585.808088ms","start":"2026-04-13T01:24:25.212268Z","end":"2026-04-13T01:24:25.798076Z","steps":["trace[1536524260] 'process raft request' (duration: 585.388733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:25.798217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:24:25.212245Z","time spent":"585.915027ms","remote":"127.0.0.1:57400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T01:24:25.798079Z","caller":"traceutil/trace.go:171","msg":"trace[712253037] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"625.074641ms","start":"2026-04-13T01:24:25.172974Z","end":"2026-04-13T01:24:25.798049Z","steps":["trace[712253037] 'process raft request' (duration: 321.161345ms)","trace[712253037] 'compare' (duration: 303.211497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:24:25.798381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:24:25.172957Z","time spent":"625.387388ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T01:24:25.833271Z","caller":"traceutil/trace.go:171","msg":"trace[623494356] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"463.803695ms","start":"2026-04-13T01:24:25.369443Z","end":"2026-04-13T01:24:25.833247Z","steps":["trace[623494356] 'read index received' (duration: 124.702492ms)","trace[623494356] 'applied index is now lower than readState.Index' (duration: 339.099823ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:24:25.833731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.277655ms","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-13T01:24:25.833794Z","caller":"traceutil/trace.go:171","msg":"trace[1557255454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:620; }","duration":"464.398536ms","start":"2026-04-13T01:24:25.369385Z","end":"2026-04-13T01:24:25.833783Z","steps":["trace[1557255454] 'agreement among raft nodes before linearized reading' (duration: 464.032565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:25.833905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:24:25.369362Z","time spent":"464.530917ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T01:24:25.833973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.200928ms","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-13T01:24:25.834029Z","caller":"traceutil/trace.go:171","msg":"trace[1646037304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"386.300787ms","start":"2026-04-13T01:24:25.447712Z","end":"2026-04-13T01:24:25.834013Z","steps":["trace[1646037304] 'agreement among raft nodes before linearized reading' (duration: 386.123552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:25.834066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:24:25.447693Z","time spent":"386.366423ms","remote":"127.0.0.1:57412","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-13T01:24:25.834159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.558237ms","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-13T01:24:25.834265Z","caller":"traceutil/trace.go:171","msg":"trace[235627619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"381.716089ms","start":"2026-04-13T01:24:25.452531Z","end":"2026-04-13T01:24:25.834247Z","steps":["trace[235627619] 'agreement among raft nodes before linearized reading' (duration: 381.3687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:25.834341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:24:25.452524Z","time spent":"381.805117ms","remote":"127.0.0.1:57412","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-13T01:24:27.370433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.671776ms","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":"warn","ts":"2026-04-13T01:24:27.370477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.66831ms","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-13T01:24:27.370659Z","caller":"traceutil/trace.go:171","msg":"trace[399736105] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:644; }","duration":"125.886564ms","start":"2026-04-13T01:24:27.244627Z","end":"2026-04-13T01:24:27.370513Z","steps":["trace[399736105] 'count revisions from in-memory index tree' (duration: 125.621062ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:24:27.370574Z","caller":"traceutil/trace.go:171","msg":"trace[385697230] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:644; }","duration":"126.78028ms","start":"2026-04-13T01:24:27.24376Z","end":"2026-04-13T01:24:27.37054Z","steps":["trace[385697230] 'range keys from in-memory index tree' (duration: 126.633838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:27.370843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.169383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:24:27.370849Z","caller":"traceutil/trace.go:171","msg":"trace[561784118] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"125.425345ms","start":"2026-04-13T01:24:27.245405Z","end":"2026-04-13T01:24:27.37083Z","steps":["trace[561784118] 'process raft request' (duration: 120.77244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:27.370905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.424615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:24:27.371127Z","caller":"traceutil/trace.go:171","msg":"trace[994009187] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:644; }","duration":"127.647324ms","start":"2026-04-13T01:24:27.243464Z","end":"2026-04-13T01:24:27.371111Z","steps":["trace[994009187] 'range keys from in-memory index tree' (duration: 127.36883ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:24:27.371005Z","caller":"traceutil/trace.go:171","msg":"trace[2004334603] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:644; }","duration":"127.306445ms","start":"2026-04-13T01:24:27.243637Z","end":"2026-04-13T01:24:27.370943Z","steps":["trace[2004334603] 'range keys from in-memory index tree' (duration: 127.109548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:27.372437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.190271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:24:27.374738Z","caller":"traceutil/trace.go:171","msg":"trace[112253581] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:644; }","duration":"130.591124ms","start":"2026-04-13T01:24:27.244124Z","end":"2026-04-13T01:24:27.374716Z","steps":["trace[112253581] 'range keys from in-memory index tree' (duration: 126.146627ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:24:27.381096Z","caller":"traceutil/trace.go:171","msg":"trace[302569429] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:667; }","duration":"135.053853ms","start":"2026-04-13T01:24:27.246024Z","end":"2026-04-13T01:24:27.381078Z","steps":["trace[302569429] 'read index received' (duration: 120.241676ms)","trace[302569429] 'applied index is now lower than readState.Index' (duration: 14.811167ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:24:27.381134Z","caller":"traceutil/trace.go:171","msg":"trace[990894165] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"135.49396ms","start":"2026-04-13T01:24:27.245629Z","end":"2026-04-13T01:24:27.381123Z","steps":["trace[990894165] 'process raft request' (duration: 135.409442ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:24:27.381106Z","caller":"traceutil/trace.go:171","msg":"trace[1034643263] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"135.512101ms","start":"2026-04-13T01:24:27.245581Z","end":"2026-04-13T01:24:27.381094Z","steps":["trace[1034643263] 'process raft request' (duration: 135.363088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:27.381237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.202855ms","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-13T01:24:27.381312Z","caller":"traceutil/trace.go:171","msg":"trace[22253686] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:647; }","duration":"135.300923ms","start":"2026-04-13T01:24:27.245998Z","end":"2026-04-13T01:24:27.381299Z","steps":["trace[22253686] 'agreement among raft nodes before linearized reading' (duration: 135.159021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:27.385096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.869284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:24:27.385171Z","caller":"traceutil/trace.go:171","msg":"trace[1432982416] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:649; }","duration":"121.967981ms","start":"2026-04-13T01:24:27.263184Z","end":"2026-04-13T01:24:27.385152Z","steps":["trace[1432982416] 'agreement among raft nodes before linearized reading' (duration: 121.83529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:27.38566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.243813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:24:27.385722Z","caller":"traceutil/trace.go:171","msg":"trace[583808361] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:649; }","duration":"101.341921ms","start":"2026-04-13T01:24:27.284369Z","end":"2026-04-13T01:24:27.385711Z","steps":["trace[583808361] 'agreement among raft nodes before linearized reading' (duration: 101.252113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:27.385963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.580371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T01:24:27.386099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.829785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:24:27.386003Z","caller":"traceutil/trace.go:171","msg":"trace[896681990] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:649; }","duration":"101.647007ms","start":"2026-04-13T01:24:27.284346Z","end":"2026-04-13T01:24:27.385993Z","steps":["trace[896681990] 'agreement among raft nodes before linearized reading' (duration: 101.56109ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:24:27.386136Z","caller":"traceutil/trace.go:171","msg":"trace[2111642387] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:649; }","duration":"122.878679ms","start":"2026-04-13T01:24:27.263246Z","end":"2026-04-13T01:24:27.386124Z","steps":["trace[2111642387] 'agreement among raft nodes before linearized reading' (duration: 122.821754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:27.386214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.826627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xzcrb\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-04-13T01:24:27.386241Z","caller":"traceutil/trace.go:171","msg":"trace[605538974] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-xzcrb; range_end:; response_count:1; response_revision:649; }","duration":"102.890772ms","start":"2026-04-13T01:24:27.283344Z","end":"2026-04-13T01:24:27.386235Z","steps":["trace[605538974] 'agreement among raft nodes before linearized reading' (duration: 102.838938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:27.386344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.051484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:24:27.386404Z","caller":"traceutil/trace.go:171","msg":"trace[40903607] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:649; }","duration":"123.11886ms","start":"2026-04-13T01:24:27.263271Z","end":"2026-04-13T01:24:27.38639Z","steps":["trace[40903607] 'agreement among raft nodes before linearized reading' (duration: 123.052724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:40.517951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.888662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:24:40.518106Z","caller":"traceutil/trace.go:171","msg":"trace[50696765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"345.156032ms","start":"2026-04-13T01:24:40.172923Z","end":"2026-04-13T01:24:40.518079Z","steps":["trace[50696765] 'range keys from in-memory index tree' (duration: 344.799626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:24:40.518168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:24:40.172906Z","time spent":"345.250308ms","remote":"127.0.0.1:57176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T01:24:40.518148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.729642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vfcmb\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-13T01:24:40.518264Z","caller":"traceutil/trace.go:171","msg":"trace[1487102837] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vfcmb; range_end:; response_count:1; response_revision:778; }","duration":"286.906335ms","start":"2026-04-13T01:24:40.231335Z","end":"2026-04-13T01:24:40.518241Z","steps":["trace[1487102837] 'range keys from in-memory index tree' (duration: 286.53345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:25:57.19929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.11738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T01:25:57.199642Z","caller":"traceutil/trace.go:171","msg":"trace[1585817234] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1934; }","duration":"479.871653ms","start":"2026-04-13T01:25:56.719754Z","end":"2026-04-13T01:25:57.199625Z","steps":["trace[1585817234] 'read index received' (duration: 339.313446ms)","trace[1585817234] 'applied index is now lower than readState.Index' (duration: 140.557107ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:25:57.199737Z","caller":"traceutil/trace.go:171","msg":"trace[57543589] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"528.283077ms","start":"2026-04-13T01:25:56.671443Z","end":"2026-04-13T01:25:57.199726Z","steps":["trace[57543589] 'process raft request' (duration: 387.643526ms)","trace[57543589] 'compare' (duration: 139.875151ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:25:57.199805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:25:56.671429Z","time spent":"528.34298ms","remote":"127.0.0.1:57334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T01:25:57.200061Z","caller":"traceutil/trace.go:171","msg":"trace[871242600] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"524.97303ms","start":"2026-04-13T01:25:56.675074Z","end":"2026-04-13T01:25:57.200047Z","steps":["trace[871242600] 'process raft request' (duration: 524.484481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:25:57.200129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:25:56.675062Z","time spent":"525.035052ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T01:25:57.200308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.55205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ddc4927-7ea3-47d3-b185-0c40d33335d1\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-04-13T01:25:57.200349Z","caller":"traceutil/trace.go:171","msg":"trace[1918416873] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7ddc4927-7ea3-47d3-b185-0c40d33335d1; range_end:; response_count:1; response_revision:1867; }","duration":"480.624593ms","start":"2026-04-13T01:25:56.719713Z","end":"2026-04-13T01:25:57.200337Z","steps":["trace[1918416873] 'agreement among raft nodes before linearized reading' (duration: 480.55508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:25:57.20038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:25:56.719697Z","time spent":"480.676415ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ddc4927-7ea3-47d3-b185-0c40d33335d1\" "} {"level":"warn","ts":"2026-04-13T01:25:57.20056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.469987ms","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-13T01:25:57.200596Z","caller":"traceutil/trace.go:171","msg":"trace[663260618] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"418.525979ms","start":"2026-04-13T01:25:56.782062Z","end":"2026-04-13T01:25:57.200588Z","steps":["trace[663260618] 'agreement among raft nodes before linearized reading' (duration: 418.413115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:25:57.200622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:25:56.782052Z","time spent":"418.563881ms","remote":"127.0.0.1:57626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-13T01:25:57.201126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.255687ms","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-13T01:25:57.201171Z","caller":"traceutil/trace.go:171","msg":"trace[1257274980] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1867; }","duration":"210.334241ms","start":"2026-04-13T01:25:56.990827Z","end":"2026-04-13T01:25:57.201162Z","steps":["trace[1257274980] 'agreement among raft nodes before linearized reading' (duration: 210.221846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:25:57.201308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.692442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:25:57.20134Z","caller":"traceutil/trace.go:171","msg":"trace[1080982439] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1867; }","duration":"235.751264ms","start":"2026-04-13T01:25:56.96558Z","end":"2026-04-13T01:25:57.201332Z","steps":["trace[1080982439] 'agreement among raft nodes before linearized reading' (duration: 235.703982ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:25:57.201981Z","caller":"traceutil/trace.go:171","msg":"trace[714544135] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"530.320477ms","start":"2026-04-13T01:25:56.671624Z","end":"2026-04-13T01:25:57.201944Z","steps":["trace[714544135] 'process raft request' (duration: 527.792368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:25:57.204128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:25:56.671608Z","time spent":"532.102047ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T01:25:57.202311Z","caller":"traceutil/trace.go:171","msg":"trace[2097470549] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"530.597219ms","start":"2026-04-13T01:25:56.67169Z","end":"2026-04-13T01:25:57.202287Z","steps":["trace[2097470549] 'process raft request' (duration: 527.811409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:25:57.204433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:25:56.671673Z","time spent":"532.718951ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T01:26:36.350566Z","caller":"traceutil/trace.go:171","msg":"trace[1062178069] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"161.814593ms","start":"2026-04-13T01:26:36.188728Z","end":"2026-04-13T01:26:36.350543Z","steps":["trace[1062178069] 'process raft request' (duration: 161.76648ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:26:36.351357Z","caller":"traceutil/trace.go:171","msg":"trace[1107081806] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"179.152066ms","start":"2026-04-13T01:26:36.172188Z","end":"2026-04-13T01:26:36.35134Z","steps":["trace[1107081806] 'process raft request' (duration: 122.601778ms)","trace[1107081806] 'compare' (duration: 55.347122ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:26:36.351411Z","caller":"traceutil/trace.go:171","msg":"trace[1972512293] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2209; }","duration":"178.411495ms","start":"2026-04-13T01:26:36.172991Z","end":"2026-04-13T01:26:36.351402Z","steps":["trace[1972512293] 'read index received' (duration: 121.716599ms)","trace[1972512293] 'applied index is now lower than readState.Index' (duration: 56.694026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:26:36.351478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.468208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:26:36.351945Z","caller":"traceutil/trace.go:171","msg":"trace[828342987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2132; }","duration":"178.973176ms","start":"2026-04-13T01:26:36.172957Z","end":"2026-04-13T01:26:36.35193Z","steps":["trace[828342987] 'agreement among raft nodes before linearized reading' (duration: 178.471819ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:26:38.14768Z","caller":"traceutil/trace.go:171","msg":"trace[1320017627] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"103.238593ms","start":"2026-04-13T01:26:38.044406Z","end":"2026-04-13T01:26:38.147644Z","steps":["trace[1320017627] 'process raft request' (duration: 102.763757ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:28:00.364832Z","caller":"traceutil/trace.go:171","msg":"trace[1182917297] linearizableReadLoop","detail":"{readStateIndex:2790; appliedIndex:2789; }","duration":"189.231711ms","start":"2026-04-13T01:28:00.17554Z","end":"2026-04-13T01:28:00.364771Z","steps":["trace[1182917297] 'read index received' (duration: 189.20845ms)","trace[1182917297] 'applied index is now lower than readState.Index' (duration: 19.001µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:28:00.365047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.476558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:28:00.365106Z","caller":"traceutil/trace.go:171","msg":"trace[1354076495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2691; }","duration":"189.612257ms","start":"2026-04-13T01:28:00.175477Z","end":"2026-04-13T01:28:00.365089Z","steps":["trace[1354076495] 'agreement among raft nodes before linearized reading' (duration: 189.456386ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:28:00.415119Z","caller":"traceutil/trace.go:171","msg":"trace[1095714082] transaction","detail":"{read_only:false; response_revision:2694; number_of_response:1; }","duration":"108.424872ms","start":"2026-04-13T01:28:00.306659Z","end":"2026-04-13T01:28:00.415083Z","steps":["trace[1095714082] 'process raft request' (duration: 108.374238ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:28:00.415185Z","caller":"traceutil/trace.go:171","msg":"trace[583297979] transaction","detail":"{read_only:false; response_revision:2693; number_of_response:1; }","duration":"124.965356ms","start":"2026-04-13T01:28:00.290165Z","end":"2026-04-13T01:28:00.41513Z","steps":["trace[583297979] 'process raft request' (duration: 124.749891ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:28:00.415112Z","caller":"traceutil/trace.go:171","msg":"trace[1908022355] transaction","detail":"{read_only:false; response_revision:2692; number_of_response:1; }","duration":"125.053401ms","start":"2026-04-13T01:28:00.290025Z","end":"2026-04-13T01:28:00.415078Z","steps":["trace[1908022355] 'process raft request' (duration: 124.618472ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:30:57.230073Z","caller":"traceutil/trace.go:171","msg":"trace[2097012923] transaction","detail":"{read_only:false; response_revision:3775; number_of_response:1; }","duration":"107.998054ms","start":"2026-04-13T01:30:57.122038Z","end":"2026-04-13T01:30:57.230037Z","steps":["trace[2097012923] 'process raft request' (duration: 74.406564ms)","trace[2097012923] 'compare' (duration: 33.387186ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:30:57.233101Z","caller":"traceutil/trace.go:171","msg":"trace[1303884870] transaction","detail":"{read_only:false; response_revision:3776; number_of_response:1; }","duration":"109.990381ms","start":"2026-04-13T01:30:57.123085Z","end":"2026-04-13T01:30:57.233076Z","steps":["trace[1303884870] 'process raft request' (duration: 109.907039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:44.448704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.796089ms","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-13T01:31:44.448736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.680392ms","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-13T01:31:44.448812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.941528ms","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-13T01:31:44.448826Z","caller":"traceutil/trace.go:171","msg":"trace[1709731712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4493; }","duration":"169.826776ms","start":"2026-04-13T01:31:44.278985Z","end":"2026-04-13T01:31:44.448811Z","steps":["trace[1709731712] 'range keys from in-memory index tree' (duration: 169.529846ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:44.448766Z","caller":"traceutil/trace.go:171","msg":"trace[643583452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4493; }","duration":"156.877291ms","start":"2026-04-13T01:31:44.291877Z","end":"2026-04-13T01:31:44.448754Z","steps":["trace[643583452] 'range keys from in-memory index tree' (duration: 156.703637ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:44.452265Z","caller":"traceutil/trace.go:171","msg":"trace[1589349511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4493; }","duration":"164.388496ms","start":"2026-04-13T01:31:44.287848Z","end":"2026-04-13T01:31:44.452236Z","steps":["trace[1589349511] 'range keys from in-memory index tree' (duration: 160.717651ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:44.603761Z","caller":"traceutil/trace.go:171","msg":"trace[729911301] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"149.329976ms","start":"2026-04-13T01:31:44.45441Z","end":"2026-04-13T01:31:44.60374Z","steps":["trace[729911301] 'process raft request' (duration: 149.176762ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:44.612244Z","caller":"traceutil/trace.go:171","msg":"trace[699894426] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"157.035547ms","start":"2026-04-13T01:31:44.455196Z","end":"2026-04-13T01:31:44.612232Z","steps":["trace[699894426] 'process raft request' (duration: 156.908553ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:44.612519Z","caller":"traceutil/trace.go:171","msg":"trace[1532308643] linearizableReadLoop","detail":"{readStateIndex:4663; appliedIndex:4660; }","duration":"156.629945ms","start":"2026-04-13T01:31:44.45587Z","end":"2026-04-13T01:31:44.6125Z","steps":["trace[1532308643] 'read index received' (duration: 147.82856ms)","trace[1532308643] 'applied index is now lower than readState.Index' (duration: 8.800715ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:31:44.612569Z","caller":"traceutil/trace.go:171","msg":"trace[2137185041] transaction","detail":"{read_only:false; response_revision:4496; number_of_response:1; }","duration":"156.822531ms","start":"2026-04-13T01:31:44.455735Z","end":"2026-04-13T01:31:44.612558Z","steps":["trace[2137185041] 'process raft request' (duration: 156.455599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:44.614133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.234195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5a1940b4-5ba7-49ef-84ae-a5258ae6ed20\" ","response":"range_response_count:1 size:3773"} {"level":"info","ts":"2026-04-13T01:31:44.614235Z","caller":"traceutil/trace.go:171","msg":"trace[450554459] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5a1940b4-5ba7-49ef-84ae-a5258ae6ed20; range_end:; response_count:1; response_revision:4497; }","duration":"158.370809ms","start":"2026-04-13T01:31:44.455845Z","end":"2026-04-13T01:31:44.614215Z","steps":["trace[450554459] 'agreement among raft nodes before linearized reading' (duration: 157.846883ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:44.614223Z","caller":"traceutil/trace.go:171","msg":"trace[2108167359] transaction","detail":"{read_only:false; response_revision:4497; number_of_response:1; }","duration":"157.992107ms","start":"2026-04-13T01:31:44.45617Z","end":"2026-04-13T01:31:44.614162Z","steps":["trace[2108167359] 'process raft request' (duration: 156.242372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:44.624053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.093975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-13T01:31:44.624143Z","caller":"traceutil/trace.go:171","msg":"trace[1994651345] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4497; }","duration":"128.228449ms","start":"2026-04-13T01:31:44.495885Z","end":"2026-04-13T01:31:44.624114Z","steps":["trace[1994651345] 'agreement among raft nodes before linearized reading' (duration: 117.99893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:46.932046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.486199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-patch-drc6m\" ","response":"range_response_count:1 size:4568"} {"level":"info","ts":"2026-04-13T01:31:46.932134Z","caller":"traceutil/trace.go:171","msg":"trace[895678179] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-patch-drc6m; range_end:; response_count:1; response_revision:4528; }","duration":"125.653634ms","start":"2026-04-13T01:31:46.806459Z","end":"2026-04-13T01:31:46.932113Z","steps":["trace[895678179] 'range keys from in-memory index tree' (duration: 125.250131ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:49.811219Z","caller":"traceutil/trace.go:171","msg":"trace[1130120768] linearizableReadLoop","detail":"{readStateIndex:4762; appliedIndex:4761; }","duration":"119.864099ms","start":"2026-04-13T01:31:49.691335Z","end":"2026-04-13T01:31:49.811199Z","steps":["trace[1130120768] 'read index received' (duration: 83.579178ms)","trace[1130120768] 'applied index is now lower than readState.Index' (duration: 36.284261ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:31:49.811329Z","caller":"traceutil/trace.go:171","msg":"trace[1241393088] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"127.856817ms","start":"2026-04-13T01:31:49.683463Z","end":"2026-04-13T01:31:49.81132Z","steps":["trace[1241393088] 'process raft request' (duration: 91.336268ms)","trace[1241393088] 'compare' (duration: 35.852667ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:31:49.811479Z","caller":"traceutil/trace.go:171","msg":"trace[940961719] transaction","detail":"{read_only:false; response_revision:4593; number_of_response:1; }","duration":"118.315639ms","start":"2026-04-13T01:31:49.693144Z","end":"2026-04-13T01:31:49.811459Z","steps":["trace[940961719] 'process raft request' (duration: 117.980028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:49.811603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.247832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-13T01:31:49.811641Z","caller":"traceutil/trace.go:171","msg":"trace[394597474] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4593; }","duration":"120.381916ms","start":"2026-04-13T01:31:49.691251Z","end":"2026-04-13T01:31:49.811632Z","steps":["trace[394597474] 'agreement among raft nodes before linearized reading' (duration: 120.106497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:49.811657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.340362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-13T01:31:49.811757Z","caller":"traceutil/trace.go:171","msg":"trace[1809260464] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4593; }","duration":"119.475506ms","start":"2026-04-13T01:31:49.69226Z","end":"2026-04-13T01:31:49.811736Z","steps":["trace[1809260464] 'agreement among raft nodes before linearized reading' (duration: 119.160496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:49.811705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.989824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-57trk-tls\" ","response":"range_response_count:1 size:2129"} {"level":"info","ts":"2026-04-13T01:31:49.81188Z","caller":"traceutil/trace.go:171","msg":"trace[923152521] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-57trk-tls; range_end:; response_count:1; response_revision:4593; }","duration":"116.19541ms","start":"2026-04-13T01:31:49.695664Z","end":"2026-04-13T01:31:49.811859Z","steps":["trace[923152521] 'agreement among raft nodes before linearized reading' (duration: 115.792037ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:50.008713Z","caller":"traceutil/trace.go:171","msg":"trace[810941755] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"104.732242ms","start":"2026-04-13T01:31:49.903951Z","end":"2026-04-13T01:31:50.008683Z","steps":["trace[810941755] 'process raft request' (duration: 104.108392ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:50.008842Z","caller":"traceutil/trace.go:171","msg":"trace[1010217917] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"104.799044ms","start":"2026-04-13T01:31:49.904038Z","end":"2026-04-13T01:31:50.008837Z","steps":["trace[1010217917] 'process raft request' (duration: 104.494954ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:50.009144Z","caller":"traceutil/trace.go:171","msg":"trace[1160924604] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"103.783252ms","start":"2026-04-13T01:31:49.905348Z","end":"2026-04-13T01:31:50.009131Z","steps":["trace[1160924604] 'process raft request' (duration: 103.222834ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:50.008743Z","caller":"traceutil/trace.go:171","msg":"trace[1834255759] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"104.764993ms","start":"2026-04-13T01:31:49.903949Z","end":"2026-04-13T01:31:50.008714Z","steps":["trace[1834255759] 'process raft request' (duration: 104.026919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:50.009299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.252309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60438"} {"level":"info","ts":"2026-04-13T01:31:50.009367Z","caller":"traceutil/trace.go:171","msg":"trace[699572449] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4603; }","duration":"105.323592ms","start":"2026-04-13T01:31:49.904022Z","end":"2026-04-13T01:31:50.009346Z","steps":["trace[699572449] 'agreement among raft nodes before linearized reading' (duration: 104.826586ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:50.00874Z","caller":"traceutil/trace.go:171","msg":"trace[1043756657] transaction","detail":"{read_only:false; response_revision:4596; number_of_response:1; }","duration":"105.136235ms","start":"2026-04-13T01:31:49.903566Z","end":"2026-04-13T01:31:50.008702Z","steps":["trace[1043756657] 'process raft request' (duration: 25.034538ms)","trace[1043756657] 'compare' (duration: 78.945509ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:31:50.009575Z","caller":"traceutil/trace.go:171","msg":"trace[2013356950] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"101.465317ms","start":"2026-04-13T01:31:49.908099Z","end":"2026-04-13T01:31:50.009565Z","steps":["trace[2013356950] 'process raft request' (duration: 100.554407ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:50.008795Z","caller":"traceutil/trace.go:171","msg":"trace[747065868] linearizableReadLoop","detail":"{readStateIndex:4769; appliedIndex:4765; }","duration":"104.744682ms","start":"2026-04-13T01:31:49.904028Z","end":"2026-04-13T01:31:50.008772Z","steps":["trace[747065868] 'read index received' (duration: 24.580423ms)","trace[747065868] 'applied index is now lower than readState.Index' (duration: 80.163069ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:31:50.008815Z","caller":"traceutil/trace.go:171","msg":"trace[1216091975] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"101.27411ms","start":"2026-04-13T01:31:49.907534Z","end":"2026-04-13T01:31:50.008808Z","steps":["trace[1216091975] 'process raft request' (duration: 101.092294ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:56.775063Z","caller":"traceutil/trace.go:171","msg":"trace[1362162126] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"202.056519ms","start":"2026-04-13T01:31:56.572981Z","end":"2026-04-13T01:31:56.775038Z","steps":["trace[1362162126] 'process raft request' (duration: 201.911134ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.111986Z","caller":"traceutil/trace.go:171","msg":"trace[1148341948] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"531.715226ms","start":"2026-04-13T01:31:56.580249Z","end":"2026-04-13T01:31:57.111964Z","steps":["trace[1148341948] 'process raft request' (duration: 531.55242ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.112028Z","caller":"traceutil/trace.go:171","msg":"trace[972456158] linearizableReadLoop","detail":"{readStateIndex:4901; appliedIndex:4899; }","duration":"448.954133ms","start":"2026-04-13T01:31:56.663049Z","end":"2026-04-13T01:31:57.112003Z","steps":["trace[972456158] 'read index received' (duration: 111.849635ms)","trace[972456158] 'applied index is now lower than readState.Index' (duration: 337.102118ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:31:57.112136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:31:56.580234Z","time spent":"531.821749ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16537,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T01:31:57.112231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.17281ms","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-13T01:31:57.112265Z","caller":"traceutil/trace.go:171","msg":"trace[587611441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4728; }","duration":"449.260704ms","start":"2026-04-13T01:31:56.662993Z","end":"2026-04-13T01:31:57.112254Z","steps":["trace[587611441] 'agreement among raft nodes before linearized reading' (duration: 449.121479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:57.112301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:31:56.662977Z","time spent":"449.314695ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T01:31:57.282444Z","caller":"traceutil/trace.go:171","msg":"trace[532565095] linearizableReadLoop","detail":"{readStateIndex:4902; appliedIndex:4901; }","duration":"170.329043ms","start":"2026-04-13T01:31:57.112085Z","end":"2026-04-13T01:31:57.282414Z","steps":["trace[532565095] 'read index received' (duration: 170.26038ms)","trace[532565095] 'applied index is now lower than readState.Index' (duration: 67.093µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:31:57.282742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.992829ms","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-13T01:31:57.282744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.217471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f87da801-5510-4435-8359-187d77217766\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-04-13T01:31:57.282758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.425719ms","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-13T01:31:57.282852Z","caller":"traceutil/trace.go:171","msg":"trace[431350810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4729; }","duration":"608.549104ms","start":"2026-04-13T01:31:56.674288Z","end":"2026-04-13T01:31:57.282837Z","steps":["trace[431350810] 'agreement among raft nodes before linearized reading' (duration: 608.197112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:57.282877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.981705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-13T01:31:57.282913Z","caller":"traceutil/trace.go:171","msg":"trace[320869884] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4729; }","duration":"619.027917ms","start":"2026-04-13T01:31:56.663873Z","end":"2026-04-13T01:31:57.282901Z","steps":["trace[320869884] 'agreement among raft nodes before linearized reading' (duration: 618.936324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:57.282952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:31:56.663866Z","time spent":"619.075658ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-13T01:31:57.282847Z","caller":"traceutil/trace.go:171","msg":"trace[1877562605] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f87da801-5510-4435-8359-187d77217766; range_end:; response_count:1; response_revision:4729; }","duration":"349.334545ms","start":"2026-04-13T01:31:56.933482Z","end":"2026-04-13T01:31:57.282817Z","steps":["trace[1877562605] 'agreement among raft nodes before linearized reading' (duration: 349.031425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:57.283038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:31:56.933416Z","time spent":"349.599504ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f87da801-5510-4435-8359-187d77217766\" "} {"level":"warn","ts":"2026-04-13T01:31:57.282958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:31:56.674271Z","time spent":"608.667377ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T01:31:57.282788Z","caller":"traceutil/trace.go:171","msg":"trace[1213190676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4729; }","duration":"609.094022ms","start":"2026-04-13T01:31:56.67368Z","end":"2026-04-13T01:31:57.282774Z","steps":["trace[1213190676] 'agreement among raft nodes before linearized reading' (duration: 608.876314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:57.283337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:31:56.673663Z","time spent":"609.656771ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T01:31:57.2833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.914262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:31:57.283543Z","caller":"traceutil/trace.go:171","msg":"trace[1875347745] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"273.961842ms","start":"2026-04-13T01:31:57.009542Z","end":"2026-04-13T01:31:57.283504Z","steps":["trace[1875347745] 'process raft request' (duration: 272.659067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:57.283267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.585981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T01:31:57.283753Z","caller":"traceutil/trace.go:171","msg":"trace[1115687019] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4729; }","duration":"584.395269ms","start":"2026-04-13T01:31:56.699143Z","end":"2026-04-13T01:31:57.283539Z","steps":["trace[1115687019] 'agreement among raft nodes before linearized reading' (duration: 583.896472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:31:57.284104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:31:56.699128Z","time spent":"584.929686ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-13T01:31:57.283974Z","caller":"traceutil/trace.go:171","msg":"trace[443237403] 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:4729; }","duration":"181.663698ms","start":"2026-04-13T01:31:57.102285Z","end":"2026-04-13T01:31:57.283949Z","steps":["trace[443237403] 'agreement among raft nodes before linearized reading' (duration: 180.142216ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.306069Z","caller":"traceutil/trace.go:171","msg":"trace[488311355] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"187.894909ms","start":"2026-04-13T01:31:57.118157Z","end":"2026-04-13T01:31:57.306052Z","steps":["trace[488311355] 'process raft request' (duration: 187.730154ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.306544Z","caller":"traceutil/trace.go:171","msg":"trace[1414406263] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"187.743254ms","start":"2026-04-13T01:31:57.118789Z","end":"2026-04-13T01:31:57.306532Z","steps":["trace[1414406263] 'process raft request' (duration: 187.205786ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.306565Z","caller":"traceutil/trace.go:171","msg":"trace[5790880] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"187.043491ms","start":"2026-04-13T01:31:57.119496Z","end":"2026-04-13T01:31:57.30654Z","steps":["trace[5790880] 'process raft request' (duration: 186.924237ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.30678Z","caller":"traceutil/trace.go:171","msg":"trace[177565564] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"187.713733ms","start":"2026-04-13T01:31:57.119059Z","end":"2026-04-13T01:31:57.306773Z","steps":["trace[177565564] 'process raft request' (duration: 187.299139ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.306555Z","caller":"traceutil/trace.go:171","msg":"trace[370976351] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"186.924776ms","start":"2026-04-13T01:31:57.119619Z","end":"2026-04-13T01:31:57.306544Z","steps":["trace[370976351] 'process raft request' (duration: 186.863754ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.718807Z","caller":"traceutil/trace.go:171","msg":"trace[672280483] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"125.465959ms","start":"2026-04-13T01:31:57.593313Z","end":"2026-04-13T01:31:57.718779Z","steps":["trace[672280483] 'process raft request' (duration: 125.004934ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.884899Z","caller":"traceutil/trace.go:171","msg":"trace[345900855] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"104.965723ms","start":"2026-04-13T01:31:57.779902Z","end":"2026-04-13T01:31:57.884868Z","steps":["trace[345900855] 'process raft request' (duration: 104.126855ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.88518Z","caller":"traceutil/trace.go:171","msg":"trace[1701462299] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"105.234733ms","start":"2026-04-13T01:31:57.779921Z","end":"2026-04-13T01:31:57.885156Z","steps":["trace[1701462299] 'process raft request' (duration: 104.84463ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:31:57.885435Z","caller":"traceutil/trace.go:171","msg":"trace[1934178235] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"105.080168ms","start":"2026-04-13T01:31:57.780332Z","end":"2026-04-13T01:31:57.885412Z","steps":["trace[1934178235] 'process raft request' (duration: 104.641553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:32:15.85273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.292324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T01:32:15.85281Z","caller":"traceutil/trace.go:171","msg":"trace[1528904033] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:4926; }","duration":"420.4285ms","start":"2026-04-13T01:32:15.432364Z","end":"2026-04-13T01:32:15.852792Z","steps":["trace[1528904033] 'count revisions from in-memory index tree' (duration: 420.17686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:32:15.852861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:32:15.432343Z","time spent":"420.501813ms","remote":"127.0.0.1:57448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-13T01:32:15.853288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.070257ms","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-13T01:32:15.853345Z","caller":"traceutil/trace.go:171","msg":"trace[1179938552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4926; }","duration":"431.185611ms","start":"2026-04-13T01:32:15.422147Z","end":"2026-04-13T01:32:15.853332Z","steps":["trace[1179938552] 'range keys from in-memory index tree' (duration: 430.872709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:32:15.853359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.249415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-13T01:32:15.853378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:32:15.422115Z","time spent":"431.255414ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T01:32:15.853395Z","caller":"traceutil/trace.go:171","msg":"trace[396073690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4926; }","duration":"457.314668ms","start":"2026-04-13T01:32:15.396064Z","end":"2026-04-13T01:32:15.853379Z","steps":["trace[396073690] 'range keys from in-memory index tree' (duration: 457.11789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:32:15.85342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:32:15.396041Z","time spent":"457.374449ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T01:32:15.853284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.626712ms","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-13T01:32:15.853513Z","caller":"traceutil/trace.go:171","msg":"trace[2106309340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4926; }","duration":"433.918454ms","start":"2026-04-13T01:32:15.41957Z","end":"2026-04-13T01:32:15.853488Z","steps":["trace[2106309340] 'range keys from in-memory index tree' (duration: 433.366363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:32:15.853635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:32:15.419539Z","time spent":"434.036058ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T01:32:15.9498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.689526ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-13T01:32:15.950094Z","caller":"traceutil/trace.go:171","msg":"trace[871530061] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"302.275108ms","start":"2026-04-13T01:32:15.64778Z","end":"2026-04-13T01:32:15.950055Z","steps":["trace[871530061] 'process raft request' (duration: 302.099501ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:32:15.950153Z","caller":"traceutil/trace.go:171","msg":"trace[1396961478] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"299.995253ms","start":"2026-04-13T01:32:15.650131Z","end":"2026-04-13T01:32:15.950127Z","steps":["trace[1396961478] 'process raft request' (duration: 299.838007ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:32:15.950224Z","caller":"traceutil/trace.go:171","msg":"trace[1857524931] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"297.706817ms","start":"2026-04-13T01:32:15.652484Z","end":"2026-04-13T01:32:15.950191Z","steps":["trace[1857524931] 'process raft request' (duration: 297.50904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:32:15.950295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:32:15.650107Z","time spent":"300.110476ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T01:32:15.950266Z","caller":"traceutil/trace.go:171","msg":"trace[1627704854] linearizableReadLoop","detail":"{readStateIndex:5106; appliedIndex:5105; }","duration":"319.22588ms","start":"2026-04-13T01:32:15.630996Z","end":"2026-04-13T01:32:15.950222Z","steps":["trace[1627704854] 'read index received' (duration: 57.162µs)","trace[1627704854] 'applied index is now lower than readState.Index' (duration: 319.164788ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T01:32:15.950484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:32:15.647728Z","time spent":"302.444994ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T01:32:15.950773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.775401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-04-13T01:32:15.950861Z","caller":"traceutil/trace.go:171","msg":"trace[685897578] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4930; }","duration":"319.895365ms","start":"2026-04-13T01:32:15.630923Z","end":"2026-04-13T01:32:15.950818Z","steps":["trace[685897578] 'agreement among raft nodes before linearized reading' (duration: 319.417638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T01:32:15.95091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T01:32:15.630889Z","time spent":"320.009459ms","remote":"127.0.0.1:35798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3340,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-13T01:32:16.00331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.747106ms","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-13T01:32:16.003382Z","caller":"traceutil/trace.go:171","msg":"trace[1747319321] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4933; }","duration":"159.866441ms","start":"2026-04-13T01:32:15.843499Z","end":"2026-04-13T01:32:16.003365Z","steps":["trace[1747319321] 'agreement among raft nodes before linearized reading' (duration: 159.670703ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:32:16.003482Z","caller":"traceutil/trace.go:171","msg":"trace[1730702805] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"143.981587ms","start":"2026-04-13T01:32:15.859493Z","end":"2026-04-13T01:32:16.003475Z","steps":["trace[1730702805] 'process raft request' (duration: 143.607183ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:32:16.003322Z","caller":"traceutil/trace.go:171","msg":"trace[1434428104] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"144.259527ms","start":"2026-04-13T01:32:15.85904Z","end":"2026-04-13T01:32:16.0033Z","steps":["trace[1434428104] 'process raft request' (duration: 131.629245ms)","trace[1434428104] 'compare' (duration: 12.130973ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:33:06.256479Z","caller":"traceutil/trace.go:171","msg":"trace[1577177278] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"108.008911ms","start":"2026-04-13T01:33:06.148419Z","end":"2026-04-13T01:33:06.256428Z","steps":["trace[1577177278] 'process raft request' (duration: 80.074692ms)","trace[1577177278] 'compare' (duration: 27.588204ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T01:33:06.256503Z","caller":"traceutil/trace.go:171","msg":"trace[2145008315] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"107.902596ms","start":"2026-04-13T01:33:06.14857Z","end":"2026-04-13T01:33:06.256473Z","steps":["trace[2145008315] 'process raft request' (duration: 107.614423ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T01:33:46.664345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2939} {"level":"info","ts":"2026-04-13T01:33:46.778892Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2939,"took":"112.942207ms","hash":1416054665,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":29528064,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-13T01:33:46.778955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1416054665,"revision":2939,"compact-revision":-1} {"level":"info","ts":"2026-04-13T01:38:46.669392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5684} {"level":"info","ts":"2026-04-13T01:38:46.763568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5684,"took":"92.807928ms","hash":895110348,"current-db-size-bytes":52731904,"current-db-size":"53 MB","current-db-size-in-use-bytes":18300928,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-13T01:38:46.763613Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":895110348,"revision":5684,"compact-revision":2939}