{"level":"warn","ts":"2026-02-25T02:13:00.398908Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T02:13:00.399003Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-02-25T02:13:00.399075Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T02:13:00.399088Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-25T02:13:00.399134Z","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-02-25T02:13:00.399736Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-02-25T02:13:00.399807Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-02-25T02:13:00.405518Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.46717ms"} {"level":"info","ts":"2026-02-25T02:13:00.412638Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-02-25T02:13:00.412785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:13:00.412856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-02-25T02:13:00.412884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:13:00.412927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-02-25T02:13:00.413012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-02-25T02:13:00.423658Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:13:00.425601Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:13:00.426942Z","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-02-25T02:13:00.430145Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:13:00.430469Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:13:00.430659Z","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-02-25T02:13:00.430822Z","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-02-25T02:13:00.430879Z","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-02-25T02:13:00.431899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-02-25T02:13:00.432299Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-25T02:13:00.434032Z","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-02-25T02:13:00.434185Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-25T02:13:00.434246Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-25T02:13:00.43427Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:13:00.434306Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:13:00.820172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:13:00.820263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:13:00.820293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-02-25T02:13:00.820326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:13:00.820341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-25T02:13:00.820357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-02-25T02:13:00.820374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-25T02:13:00.821246Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:00.821897Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:13:00.821903Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:13:00.821957Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:13:00.822432Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:00.822458Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:13:00.82253Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:13:00.822537Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:00.822568Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:13:00.825813Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-02-25T02:13:00.825875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:13:28.757545Z","caller":"traceutil/trace.go:171","msg":"trace[1885179501] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"109.531309ms","start":"2026-02-25T02:13:28.647984Z","end":"2026-02-25T02:13:28.757515Z","steps":["trace[1885179501] 'process raft request' (duration: 109.283653ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:38.489774Z","caller":"traceutil/trace.go:171","msg":"trace[2067843674] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"551.457931ms","start":"2026-02-25T02:13:37.93825Z","end":"2026-02-25T02:13:38.489708Z","steps":["trace[2067843674] 'process raft request' (duration: 551.064932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:38.493012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:37.938223Z","time spent":"551.684495ms","remote":"127.0.0.1:53224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:40.209356Z","caller":"traceutil/trace.go:171","msg":"trace[917614101] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"104.232011ms","start":"2026-02-25T02:13:40.1051Z","end":"2026-02-25T02:13:40.209332Z","steps":["trace[917614101] 'process raft request' (duration: 104.092157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:57.164699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.479969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:13:57.165055Z","caller":"traceutil/trace.go:171","msg":"trace[2000597458] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"163.249908ms","start":"2026-02-25T02:13:57.001764Z","end":"2026-02-25T02:13:57.165014Z","steps":["trace[2000597458] 'process raft request' (duration: 163.148785ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:57.165346Z","caller":"traceutil/trace.go:171","msg":"trace[1649259104] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"191.059827ms","start":"2026-02-25T02:13:56.974256Z","end":"2026-02-25T02:13:57.165316Z","steps":["trace[1649259104] 'process raft request' (duration: 14.870957ms)","trace[1649259104] 'compare' (duration: 173.333906ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:57.46049Z","caller":"traceutil/trace.go:171","msg":"trace[124312870] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"235.788281ms","start":"2026-02-25T02:13:57.224665Z","end":"2026-02-25T02:13:57.460453Z","steps":["trace[124312870] 'process raft request' (duration: 182.510955ms)","trace[124312870] 'compare' (duration: 53.134853ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:57.46047Z","caller":"traceutil/trace.go:171","msg":"trace[454741791] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"175.538606ms","start":"2026-02-25T02:13:57.284902Z","end":"2026-02-25T02:13:57.46044Z","steps":["trace[454741791] 'read index received' (duration: 122.2641ms)","trace[454741791] 'applied index is now lower than readState.Index' (duration: 53.273096ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:57.460737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.788601ms","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-02-25T02:13:57.460799Z","caller":"traceutil/trace.go:171","msg":"trace[651912282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"175.938225ms","start":"2026-02-25T02:13:57.284843Z","end":"2026-02-25T02:13:57.460781Z","steps":["trace[651912282] 'agreement among raft nodes before linearized reading' (duration: 175.70733ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:17.802307Z","caller":"traceutil/trace.go:171","msg":"trace[51963402] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"253.214595ms","start":"2026-02-25T02:14:17.549071Z","end":"2026-02-25T02:14:17.802286Z","steps":["trace[51963402] 'read index received' (duration: 253.021321ms)","trace[51963402] 'applied index is now lower than readState.Index' (duration: 192.194µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:14:17.802376Z","caller":"traceutil/trace.go:171","msg":"trace[1257770198] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"289.66104ms","start":"2026-02-25T02:14:17.512686Z","end":"2026-02-25T02:14:17.802347Z","steps":["trace[1257770198] 'process raft request' (duration: 289.475336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:17.802523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.42844ms","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-02-25T02:14:17.802551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.361162ms","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-02-25T02:14:17.802589Z","caller":"traceutil/trace.go:171","msg":"trace[1475068166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1075; }","duration":"253.527712ms","start":"2026-02-25T02:14:17.549046Z","end":"2026-02-25T02:14:17.802573Z","steps":["trace[1475068166] 'agreement among raft nodes before linearized reading' (duration: 253.339568ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:17.802593Z","caller":"traceutil/trace.go:171","msg":"trace[1116829738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1075; }","duration":"247.440935ms","start":"2026-02-25T02:14:17.555139Z","end":"2026-02-25T02:14:17.80258Z","steps":["trace[1116829738] 'agreement among raft nodes before linearized reading' (duration: 247.301191ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:56.520297Z","caller":"traceutil/trace.go:171","msg":"trace[707293522] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"131.186934ms","start":"2026-02-25T02:14:56.389085Z","end":"2026-02-25T02:14:56.520272Z","steps":["trace[707293522] 'process raft request' (duration: 131.04303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:37.558055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:38.058671Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:38.559094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:39.059864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:39.561004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:39.593012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000217045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:39.593099Z","caller":"traceutil/trace.go:171","msg":"trace[22614295] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000378979s","start":"2026-02-25T02:18:37.592699Z","end":"2026-02-25T02:18:39.593078Z","steps":["trace[22614295] 'agreement among raft nodes before linearized reading' (duration: 2.000266406s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:39.593151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:37.592668Z","time spent":"2.00047384s","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:18:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:40.062164Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:40.42694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999937678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:18:40.427178Z","caller":"traceutil/trace.go:171","msg":"trace[1362852855] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000219575s","start":"2026-02-25T02:18:38.426932Z","end":"2026-02-25T02:18:40.427152Z","steps":["trace[1362852855] 'agreement among raft nodes before linearized reading' (duration: 1.999957749s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:40.427273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:38.426915Z","time spent":"2.000342807s","remote":"127.0.0.1:53066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:18:40 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:40.5628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:41.063846Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:41.564359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:41.902886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99997946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:41.90299Z","caller":"traceutil/trace.go:171","msg":"trace[180329346] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000141634s","start":"2026-02-25T02:18:39.902827Z","end":"2026-02-25T02:18:41.902968Z","steps":["trace[180329346] 'agreement among raft nodes before linearized reading' (duration: 2.000017291s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:41.903042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:39.9028Z","time spent":"2.000231926s","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:18:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:42.056388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999153826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:42.05651Z","caller":"traceutil/trace.go:171","msg":"trace[183450917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999326819s","start":"2026-02-25T02:18:37.057157Z","end":"2026-02-25T02:18:42.056483Z","steps":["trace[183450917] 'agreement among raft nodes before linearized reading' (duration: 4.999173536s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:42.056572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:37.057143Z","time spent":"4.999417432s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/25 02:18:42 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:42.065524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:42.435489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999807287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:18:42.435601Z","caller":"traceutil/trace.go:171","msg":"trace[1938307046] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000000371s","start":"2026-02-25T02:18:40.435574Z","end":"2026-02-25T02:18:42.435574Z","steps":["trace[1938307046] 'agreement among raft nodes before linearized reading' (duration: 1.999848586s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:42.435661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:40.43554Z","time spent":"2.000108823s","remote":"127.0.0.1:53066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:18:42.566413Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:42.687736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999095145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:18:42.687832Z","caller":"traceutil/trace.go:171","msg":"trace[1427067744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.99927261s","start":"2026-02-25T02:18:37.688538Z","end":"2026-02-25T02:18:42.687811Z","steps":["trace[1427067744] 'agreement among raft nodes before linearized reading' (duration: 4.999142037s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:42.687926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:37.688512Z","time spent":"4.999399492s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/25 02:18:42 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:43.067319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:43.568125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:43.668153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99925538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:18:43.668223Z","caller":"traceutil/trace.go:171","msg":"trace[402550631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999392533s","start":"2026-02-25T02:18:38.66881Z","end":"2026-02-25T02:18:43.668202Z","steps":["trace[402550631] 'agreement among raft nodes before linearized reading' (duration: 4.99928944s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:43.668273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:38.66879Z","time spent":"4.999473204s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/25 02:18:43 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:43.95974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:36.958947Z","time spent":"7.000787733s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T02:18:44.057648Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-25T02:18:44.444002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999843466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:44.444092Z","caller":"traceutil/trace.go:171","msg":"trace[844132510] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99999082s","start":"2026-02-25T02:18:42.44408Z","end":"2026-02-25T02:18:44.444071Z","steps":["trace[844132510] 'agreement among raft nodes before linearized reading' (duration: 1.999885287s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:44.44414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:42.444053Z","time spent":"2.000077842s","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:18:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:44.557902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:45.058956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:45.559462Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:46.060149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:46.315072Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"59449c92923197f4","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T02:18:46.445023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000211405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:18:46.445109Z","caller":"traceutil/trace.go:171","msg":"trace[612904072] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000366408s","start":"2026-02-25T02:18:44.444723Z","end":"2026-02-25T02:18:46.44509Z","steps":["trace[612904072] 'agreement among raft nodes before linearized reading' (duration: 2.000259056s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:46.445159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:44.44469Z","time spent":"2.000458701s","remote":"127.0.0.1:53066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:18:46 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:46.561112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:46.5968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:39.59643Z","time spent":"7.000362734s","remote":"127.0.0.1:53118","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T02:18:46.654943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999730584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:18:46.655041Z","caller":"traceutil/trace.go:171","msg":"trace[1444778258] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999848287s","start":"2026-02-25T02:18:44.655173Z","end":"2026-02-25T02:18:46.655021Z","steps":["trace[1444778258] 'agreement among raft nodes before linearized reading' (duration: 1.999727034s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:46.655229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:44.655056Z","time spent":"2.000027591s","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:18:46 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:47.056896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998940401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:47.057012Z","caller":"traceutil/trace.go:171","msg":"trace[1874292887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.999136205s","start":"2026-02-25T02:18:44.057852Z","end":"2026-02-25T02:18:47.056988Z","steps":["trace[1874292887] 'agreement among raft nodes before linearized reading' (duration: 2.998977242s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:47.057066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:44.057817Z","time spent":"2.999233478s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/25 02:18:47 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:47.057175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999118061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:47.057259Z","caller":"traceutil/trace.go:171","msg":"trace[170281113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"9.999265034s","start":"2026-02-25T02:18:37.057975Z","end":"2026-02-25T02:18:47.05724Z","steps":["trace[170281113] 'agreement among raft nodes before linearized reading' (duration: 9.999154961s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:47.057308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:37.057957Z","time spent":"9.999340856s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/02/25 02:18:47 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:47.061702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:47.543412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:40.542426Z","time spent":"7.000978608s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T02:18:47.562195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:47.688108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997599873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:47.688183Z","caller":"traceutil/trace.go:171","msg":"trace[1224693837] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.997716216s","start":"2026-02-25T02:18:44.690448Z","end":"2026-02-25T02:18:47.688164Z","steps":["trace[1224693837] 'agreement among raft nodes before linearized reading' (duration: 2.997621034s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:47.688238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:44.690433Z","time spent":"2.997791867s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/25 02:18:47 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:48.062965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:48.3101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999854188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:48.310183Z","caller":"traceutil/trace.go:171","msg":"trace[1216568725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"9.999994091s","start":"2026-02-25T02:18:38.310168Z","end":"2026-02-25T02:18:48.310162Z","steps":["trace[1216568725] 'agreement among raft nodes before linearized reading' (duration: 9.999890659s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:48.310245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:38.310149Z","time spent":"10.000074283s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/02/25 02:18:48 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:48.453129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999836617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:18:48.453211Z","caller":"traceutil/trace.go:171","msg":"trace[1997685215] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99999219s","start":"2026-02-25T02:18:46.453206Z","end":"2026-02-25T02:18:48.453198Z","steps":["trace[1997685215] 'agreement among raft nodes before linearized reading' (duration: 1.999878267s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:48.453255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:46.453179Z","time spent":"2.000059922s","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:18:48.563455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:48.668459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997611082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:48.668542Z","caller":"traceutil/trace.go:171","msg":"trace[1036135776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.997764497s","start":"2026-02-25T02:18:45.670756Z","end":"2026-02-25T02:18:48.668521Z","steps":["trace[1036135776] 'agreement among raft nodes before linearized reading' (duration: 2.997666393s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:48.668598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:45.670727Z","time spent":"2.997858869s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/25 02:18:48 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:49.064357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:49.403425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:42.403066Z","time spent":"7.000352034s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T02:18:49.564993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:49.589041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00001588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:49.589126Z","caller":"traceutil/trace.go:171","msg":"trace[982685578] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000155624s","start":"2026-02-25T02:18:47.588952Z","end":"2026-02-25T02:18:49.589107Z","steps":["trace[982685578] 'agreement among raft nodes before linearized reading' (duration: 2.000051671s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:49.589171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:47.588936Z","time spent":"2.000226505s","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:18:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:50.06581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:50.454524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000174113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:18:50.454637Z","caller":"traceutil/trace.go:171","msg":"trace[1798890877] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000368318s","start":"2026-02-25T02:18:48.454242Z","end":"2026-02-25T02:18:50.45461Z","steps":["trace[1798890877] 'agreement among raft nodes before linearized reading' (duration: 2.000199084s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:50.454718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:48.454225Z","time spent":"2.000477031s","remote":"127.0.0.1:53066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:18:50.566735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793018,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:51.058343Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-25T02:18:51.058553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.403054535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T02:18:51.05857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.230716251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T02:18:51.058611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.398336805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T02:18:51.058618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.132171387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T02:18:51.058652Z","caller":"traceutil/trace.go:171","msg":"trace[357939541] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; }","duration":"7.230860103s","start":"2026-02-25T02:18:43.82777Z","end":"2026-02-25T02:18:51.05863Z","steps":["trace[357939541] 'agreement among raft nodes before linearized reading' (duration: 7.230744331s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:51.058671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.498649274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T02:18:51.058686Z","caller":"traceutil/trace.go:171","msg":"trace[84064950] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; }","duration":"9.398478308s","start":"2026-02-25T02:18:41.660194Z","end":"2026-02-25T02:18:51.058672Z","steps":["trace[84064950] 'agreement among raft nodes before linearized reading' (duration: 9.398383856s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:51.058696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.797671792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T02:18:51.05868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.095345224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T02:18:51.058724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:43.827749Z","time spent":"7.230961586s","remote":"127.0.0.1:53210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":0,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:18:51.058732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:41.660161Z","time spent":"9.39855857s","remote":"127.0.0.1:39028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:18:51.058742Z","caller":"traceutil/trace.go:171","msg":"trace[1293455005] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; }","duration":"12.797758294s","start":"2026-02-25T02:18:38.260965Z","end":"2026-02-25T02:18:51.058724Z","steps":["trace[1293455005] 'agreement among raft nodes before linearized reading' (duration: 12.797712623s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:51.058546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.353821206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-25T02:18:51.05877Z","caller":"traceutil/trace.go:171","msg":"trace[383442888] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; }","duration":"7.095478097s","start":"2026-02-25T02:18:43.963272Z","end":"2026-02-25T02:18:51.05875Z","steps":["trace[383442888] 'agreement among raft nodes before linearized reading' (duration: 7.095359974s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:51.058789Z","caller":"traceutil/trace.go:171","msg":"trace[939532515] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; }","duration":"11.354162324s","start":"2026-02-25T02:18:39.704617Z","end":"2026-02-25T02:18:51.05878Z","steps":["trace[939532515] 'agreement among raft nodes before linearized reading' (duration: 11.353867707s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:51.058794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:38.260933Z","time spent":"12.797847066s","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":0,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:18:51.058817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:39.704586Z","time spent":"11.354223375s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":0,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-02-25T02:18:51.058618Z","caller":"traceutil/trace.go:171","msg":"trace[2090573142] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; }","duration":"12.403195198s","start":"2026-02-25T02:18:38.655397Z","end":"2026-02-25T02:18:51.058592Z","steps":["trace[2090573142] 'agreement among raft nodes before linearized reading' (duration: 12.403090606s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:51.058653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.228168175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T02:18:51.058862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:38.655378Z","time spent":"12.403475475s","remote":"127.0.0.1:53452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":0,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:18:51.058877Z","caller":"traceutil/trace.go:171","msg":"trace[838616223] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; }","duration":"13.22843263s","start":"2026-02-25T02:18:37.830433Z","end":"2026-02-25T02:18:51.058866Z","steps":["trace[838616223] 'agreement among raft nodes before linearized reading' (duration: 13.228208046s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:51.058813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:43.963233Z","time spent":"7.095568829s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":0,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"info","ts":"2026-02-25T02:18:51.05871Z","caller":"traceutil/trace.go:171","msg":"trace[1385102340] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; }","duration":"13.498736516s","start":"2026-02-25T02:18:37.559965Z","end":"2026-02-25T02:18:51.058701Z","steps":["trace[1385102340] 'agreement among raft nodes before linearized reading' (duration: 13.498694685s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:51.05892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:37.830406Z","time spent":"13.228500522s","remote":"127.0.0.1:47270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":0,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-25T02:18:51.058699Z","caller":"traceutil/trace.go:171","msg":"trace[1748506745] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; }","duration":"10.13231598s","start":"2026-02-25T02:18:40.926362Z","end":"2026-02-25T02:18:51.058678Z","steps":["trace[1748506745] 'agreement among raft nodes before linearized reading' (duration: 10.132220378s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:51.058983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:37.559935Z","time spent":"13.499013952s","remote":"127.0.0.1:47068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":0,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:18:51.059016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:40.926335Z","time spent":"10.132663948s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":0,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-02-25T02:18:51.558996Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:51.957508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:51.065532Z","time spent":"891.963626ms","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/25 02:18:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:52.059993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:52.462586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000188734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:18:52.462691Z","caller":"traceutil/trace.go:171","msg":"trace[1105735093] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000359468s","start":"2026-02-25T02:18:50.462306Z","end":"2026-02-25T02:18:52.462665Z","steps":["trace[1105735093] 'agreement among raft nodes before linearized reading' (duration: 2.000226935s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:52.462749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:50.46227Z","time spent":"2.000468021s","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:18:52.560561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:52.689176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:47.690751Z","time spent":"4.998417141s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/25 02:18:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:53.060718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:53.321548Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"59449c92923197f4","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-25T02:18:53.560867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:53.602998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:46.602143Z","time spent":"7.000850284s","remote":"127.0.0.1:53118","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T02:18:53.66904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:48.670844Z","time spent":"4.998187956s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/25 02:18:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:54.060708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:47.059776Z","time spent":"7.000923146s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T02:18:54.06108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438321123793029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:18:54.235592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:48.312698Z","time spent":"5.922888008s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/25 02:18:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:54.266714Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"17.307503458s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T02:18:54.464009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000600772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:18:54.464098Z","caller":"traceutil/trace.go:171","msg":"trace[183142412] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000747686s","start":"2026-02-25T02:18:52.463328Z","end":"2026-02-25T02:18:54.464075Z","steps":["trace[183142412] 'agreement among raft nodes before linearized reading' (duration: 2.000635543s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:54.464147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:52.463302Z","time spent":"2.000835668s","remote":"127.0.0.1:53066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:18:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:18:54.548655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:47.548332Z","time spent":"7.000318052s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-25T02:18:54.594645Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"152.803µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-02-25T02:18:54.812968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.688286ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-25T02:18:54.8132Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"11.12µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"info","ts":"2026-02-25T02:18:54.81327Z","caller":"traceutil/trace.go:171","msg":"trace[148606163] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2310; }","duration":"5.40534478s","start":"2026-02-25T02:18:49.407891Z","end":"2026-02-25T02:18:54.813236Z","steps":["trace[148606163] 'process raft request' (duration: 5.405174566s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:54.813307Z","caller":"traceutil/trace.go:171","msg":"trace[1886243468] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2310; }","duration":"259.825512ms","start":"2026-02-25T02:18:54.55346Z","end":"2026-02-25T02:18:54.813286Z","steps":["trace[1886243468] 'process raft request' (duration: 259.790041ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:54.813314Z","caller":"traceutil/trace.go:171","msg":"trace[1867549898] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2310; }","duration":"2.852190462s","start":"2026-02-25T02:18:51.961087Z","end":"2026-02-25T02:18:54.813278Z","steps":["trace[1867549898] 'process raft request' (duration: 2.852075319s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:54.813412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.696287178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:18:54.813415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.145504843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:18:54.813398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.006496554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:18:54.813422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.751016959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:18:54.813438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:49.407854Z","time spent":"5.405495163s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:18:54.81346Z","caller":"traceutil/trace.go:171","msg":"trace[938757726] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2310; }","duration":"6.145605344s","start":"2026-02-25T02:18:48.667838Z","end":"2026-02-25T02:18:54.813443Z","steps":["trace[938757726] 'agreement among raft nodes before linearized reading' (duration: 6.145500832s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:54.813473Z","caller":"traceutil/trace.go:171","msg":"trace[1548454286] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2310; }","duration":"6.006591916s","start":"2026-02-25T02:18:48.806862Z","end":"2026-02-25T02:18:54.813454Z","steps":["trace[1548454286] 'agreement among raft nodes before linearized reading' (duration: 6.006427042s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:54.813493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.365163582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7045"} {"level":"warn","ts":"2026-02-25T02:18:54.813498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:48.667818Z","time spent":"6.145672116s","remote":"127.0.0.1:53502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:18:54.813491Z","caller":"traceutil/trace.go:171","msg":"trace[1415579467] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2310; }","duration":"7.751148862s","start":"2026-02-25T02:18:47.062325Z","end":"2026-02-25T02:18:54.813474Z","steps":["trace[1415579467] 'agreement among raft nodes before linearized reading' (duration: 7.751010489s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:54.813515Z","caller":"traceutil/trace.go:171","msg":"trace[816864575] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2310; }","duration":"8.365219074s","start":"2026-02-25T02:18:46.44829Z","end":"2026-02-25T02:18:54.813509Z","steps":["trace[816864575] 'agreement among raft nodes before linearized reading' (duration: 8.365170723s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:54.81354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:46.448267Z","time spent":"8.365267224s","remote":"127.0.0.1:53224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7068,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-25T02:18:54.81353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:48.806807Z","time spent":"6.006713139s","remote":"127.0.0.1:53570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":30,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:18:54.813549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:47.062306Z","time spent":"7.751227614s","remote":"127.0.0.1:32834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:18:54.813555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.036577238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:18:54.813554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.496708699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:18:54.81329Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"59449c92923197f4","error":"lease not found"} {"level":"warn","ts":"2026-02-25T02:18:54.813461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:51.961067Z","time spent":"2.852311474s","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":523,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:18:54.813601Z","caller":"traceutil/trace.go:171","msg":"trace[1744024497] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2310; }","duration":"5.036664689s","start":"2026-02-25T02:18:49.776923Z","end":"2026-02-25T02:18:54.813587Z","steps":["trace[1744024497] 'agreement among raft nodes before linearized reading' (duration: 5.036585768s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:54.813639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:49.776889Z","time spent":"5.036739671s","remote":"127.0.0.1:53406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:18:54.81332Z","caller":"traceutil/trace.go:171","msg":"trace[241765068] linearizableReadLoop","detail":"{readStateIndex:2430; appliedIndex:2414; }","duration":"3.754842804s","start":"2026-02-25T02:18:51.058443Z","end":"2026-02-25T02:18:54.813286Z","steps":["trace[241765068] 'read index received' (duration: 3.20864753s)","trace[241765068] 'applied index is now lower than readState.Index' (duration: 546.193384ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:18:54.813445Z","caller":"traceutil/trace.go:171","msg":"trace[1020677687] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:0; response_revision:2310; }","duration":"10.696336299s","start":"2026-02-25T02:18:44.117102Z","end":"2026-02-25T02:18:54.813438Z","steps":["trace[1020677687] 'agreement among raft nodes before linearized reading' (duration: 10.696258368s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:54.813753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:44.117058Z","time spent":"10.696688287s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-02-25T02:18:54.813854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.897899226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:18:54.813898Z","caller":"traceutil/trace.go:171","msg":"trace[1394323487] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2310; }","duration":"2.897990248s","start":"2026-02-25T02:18:51.915895Z","end":"2026-02-25T02:18:54.813885Z","steps":["trace[1394323487] 'agreement among raft nodes before linearized reading' (duration: 2.897912346s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:54.813896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.555706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:18:54.813922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.089879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:18:54.813935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:51.91587Z","time spent":"2.89805485s","remote":"127.0.0.1:32938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":12,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:18:54.81393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207059985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18975bce661f86e1\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:18:54.813931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.812196618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:18:54.81395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.928048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:18:54.813956Z","caller":"traceutil/trace.go:171","msg":"trace[919458906] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2310; }","duration":"209.658748ms","start":"2026-02-25T02:18:54.604277Z","end":"2026-02-25T02:18:54.813936Z","steps":["trace[919458906] 'agreement among raft nodes before linearized reading' (duration: 209.552256ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:54.813978Z","caller":"traceutil/trace.go:171","msg":"trace[1052092802] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18975bce661f86e1; range_end:; response_count:0; response_revision:2310; }","duration":"1.207133456s","start":"2026-02-25T02:18:53.606833Z","end":"2026-02-25T02:18:54.813966Z","steps":["trace[1052092802] 'agreement among raft nodes before linearized reading' (duration: 1.207061504s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:54.81398Z","caller":"traceutil/trace.go:171","msg":"trace[740170882] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2310; }","duration":"5.812249039s","start":"2026-02-25T02:18:49.001722Z","end":"2026-02-25T02:18:54.813971Z","steps":["trace[740170882] 'agreement among raft nodes before linearized reading' (duration: 5.812150107s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:54.813974Z","caller":"traceutil/trace.go:171","msg":"trace[996880930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2310; }","duration":"342.171562ms","start":"2026-02-25T02:18:54.471787Z","end":"2026-02-25T02:18:54.813958Z","steps":["trace[996880930] 'agreement among raft nodes before linearized reading' (duration: 342.09042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:54.814006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:53.606815Z","time spent":"1.207183977s","remote":"127.0.0.1:53118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18975bce661f86e1\" "} {"level":"warn","ts":"2026-02-25T02:18:54.814017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:49.001648Z","time spent":"5.812355302s","remote":"127.0.0.1:53246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":53,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:18:54.813987Z","caller":"traceutil/trace.go:171","msg":"trace[1035461657] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2310; }","duration":"158.000559ms","start":"2026-02-25T02:18:54.655975Z","end":"2026-02-25T02:18:54.813976Z","steps":["trace[1035461657] 'agreement among raft nodes before linearized reading' (duration: 157.925037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:54.814036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:54.47177Z","time spent":"342.251003ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:18:54.814334Z","caller":"traceutil/trace.go:171","msg":"trace[918839308] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2310; }","duration":"5.496820892s","start":"2026-02-25T02:18:49.316778Z","end":"2026-02-25T02:18:54.813599Z","steps":["trace[918839308] 'agreement among raft nodes before linearized reading' (duration: 5.496714109s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:54.814386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:49.316744Z","time spent":"5.49762665s","remote":"127.0.0.1:32902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:18:55.003411Z","caller":"traceutil/trace.go:171","msg":"trace[1203219642] linearizableReadLoop","detail":"{readStateIndex:2434; appliedIndex:2432; }","duration":"109.167895ms","start":"2026-02-25T02:18:54.894209Z","end":"2026-02-25T02:18:55.003377Z","steps":["trace[1203219642] 'read index received' (duration: 108.877189ms)","trace[1203219642] 'applied index is now lower than readState.Index' (duration: 289.496µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:18:55.003664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.267136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-02-25T02:18:55.003696Z","caller":"traceutil/trace.go:171","msg":"trace[734060183] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:2311; }","duration":"185.345857ms","start":"2026-02-25T02:18:54.818341Z","end":"2026-02-25T02:18:55.003686Z","steps":["trace[734060183] 'agreement among raft nodes before linearized reading' (duration: 185.164983ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:55.003782Z","caller":"traceutil/trace.go:171","msg":"trace[1879693702] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"177.486493ms","start":"2026-02-25T02:18:54.826265Z","end":"2026-02-25T02:18:55.003752Z","steps":["trace[1879693702] 'process raft request' (duration: 176.972161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:55.004062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.636814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-25T02:18:55.004168Z","caller":"traceutil/trace.go:171","msg":"trace[367003658] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:2311; }","duration":"185.776977ms","start":"2026-02-25T02:18:54.818369Z","end":"2026-02-25T02:18:55.004146Z","steps":["trace[367003658] 'agreement among raft nodes before linearized reading' (duration: 185.43299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:55.20306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.792842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:18:55.203342Z","caller":"traceutil/trace.go:171","msg":"trace[978655963] transaction","detail":"{read_only:false; response_revision:2312; number_of_response:1; }","duration":"307.653504ms","start":"2026-02-25T02:18:54.895655Z","end":"2026-02-25T02:18:55.203309Z","steps":["trace[978655963] 'process raft request' (duration: 191.533795ms)","trace[978655963] 'compare' (duration: 115.612138ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:18:55.203415Z","caller":"traceutil/trace.go:171","msg":"trace[540474582] linearizableReadLoop","detail":"{readStateIndex:2437; appliedIndex:2434; }","duration":"194.334877ms","start":"2026-02-25T02:18:55.009048Z","end":"2026-02-25T02:18:55.203383Z","steps":["trace[540474582] 'read index received' (duration: 78.287389ms)","trace[540474582] 'applied index is now lower than readState.Index' (duration: 116.046188ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:18:55.203434Z","caller":"traceutil/trace.go:171","msg":"trace[861195256] transaction","detail":"{read_only:false; response_revision:2313; number_of_response:1; }","duration":"305.134519ms","start":"2026-02-25T02:18:54.898269Z","end":"2026-02-25T02:18:55.203403Z","steps":["trace[861195256] 'process raft request' (duration: 304.928624ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:55.203472Z","caller":"traceutil/trace.go:171","msg":"trace[2086053957] transaction","detail":"{read_only:false; response_revision:2314; number_of_response:1; }","duration":"198.351637ms","start":"2026-02-25T02:18:55.005108Z","end":"2026-02-25T02:18:55.203459Z","steps":["trace[2086053957] 'process raft request' (duration: 198.145052ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:55.20346Z","caller":"traceutil/trace.go:171","msg":"trace[1935935363] transaction","detail":"{read_only:false; response_revision:2316; number_of_response:1; }","duration":"194.01608ms","start":"2026-02-25T02:18:55.009414Z","end":"2026-02-25T02:18:55.20343Z","steps":["trace[1935935363] 'process raft request' (duration: 193.942779ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:55.203437Z","caller":"traceutil/trace.go:171","msg":"trace[519986131] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"194.185804ms","start":"2026-02-25T02:18:55.00922Z","end":"2026-02-25T02:18:55.203406Z","steps":["trace[519986131] 'process raft request' (duration: 194.061371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:55.203576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.506701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"warn","ts":"2026-02-25T02:18:55.203603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:54.898257Z","time spent":"305.246312ms","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:18:55.203664Z","caller":"traceutil/trace.go:171","msg":"trace[1770500475] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2316; }","duration":"194.645464ms","start":"2026-02-25T02:18:55.009005Z","end":"2026-02-25T02:18:55.20365Z","steps":["trace[1770500475] 'agreement among raft nodes before linearized reading' (duration: 194.437419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:55.203724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:18:54.895627Z","time spent":"307.849759ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:18:55.402186Z","caller":"traceutil/trace.go:171","msg":"trace[1218126063] linearizableReadLoop","detail":"{readStateIndex:2441; appliedIndex:2440; }","duration":"100.111464ms","start":"2026-02-25T02:18:55.302057Z","end":"2026-02-25T02:18:55.402168Z","steps":["trace[1218126063] 'read index received' (duration: 70.897775ms)","trace[1218126063] 'applied index is now lower than readState.Index' (duration: 29.212589ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:18:55.402276Z","caller":"traceutil/trace.go:171","msg":"trace[1943100622] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"189.447189ms","start":"2026-02-25T02:18:55.212788Z","end":"2026-02-25T02:18:55.402236Z","steps":["trace[1943100622] 'process raft request' (duration: 160.110417ms)","trace[1943100622] 'compare' (duration: 29.199509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:18:55.402368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.595898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T02:18:55.402482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.576435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T02:18:55.402509Z","caller":"traceutil/trace.go:171","msg":"trace[1111395514] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2318; }","duration":"183.732611ms","start":"2026-02-25T02:18:55.218726Z","end":"2026-02-25T02:18:55.402458Z","steps":["trace[1111395514] 'agreement among raft nodes before linearized reading' (duration: 183.455285ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:55.40253Z","caller":"traceutil/trace.go:171","msg":"trace[2096315123] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2318; }","duration":"195.674787ms","start":"2026-02-25T02:18:55.206845Z","end":"2026-02-25T02:18:55.40252Z","steps":["trace[2096315123] 'agreement among raft nodes before linearized reading' (duration: 195.520883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:18:55.402678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.157128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18975bce661f86e1\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-25T02:18:55.402722Z","caller":"traceutil/trace.go:171","msg":"trace[1286159767] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18975bce661f86e1; range_end:; response_count:1; response_revision:2318; }","duration":"196.208369ms","start":"2026-02-25T02:18:55.206504Z","end":"2026-02-25T02:18:55.402712Z","steps":["trace[1286159767] 'agreement among raft nodes before linearized reading' (duration: 196.079346ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:55.633206Z","caller":"traceutil/trace.go:171","msg":"trace[1680299397] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"179.930868ms","start":"2026-02-25T02:18:55.453248Z","end":"2026-02-25T02:18:55.633179Z","steps":["trace[1680299397] 'process raft request' (duration: 179.880146ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:55.633356Z","caller":"traceutil/trace.go:171","msg":"trace[1740895113] transaction","detail":"{read_only:false; response_revision:2321; number_of_response:1; }","duration":"181.546083ms","start":"2026-02-25T02:18:55.451786Z","end":"2026-02-25T02:18:55.633332Z","steps":["trace[1740895113] 'process raft request' (duration: 181.278267ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:55.633325Z","caller":"traceutil/trace.go:171","msg":"trace[1595707250] transaction","detail":"{read_only:false; response_revision:2320; number_of_response:1; }","duration":"189.099141ms","start":"2026-02-25T02:18:55.444204Z","end":"2026-02-25T02:18:55.633303Z","steps":["trace[1595707250] 'process raft request' (duration: 132.75808ms)","trace[1595707250] 'compare' (duration: 55.949983ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:18:55.820686Z","caller":"traceutil/trace.go:171","msg":"trace[73445907] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"179.329954ms","start":"2026-02-25T02:18:55.641338Z","end":"2026-02-25T02:18:55.820668Z","steps":["trace[73445907] 'process raft request' (duration: 123.597296ms)","trace[73445907] 'compare' (duration: 55.599555ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:18:55.820837Z","caller":"traceutil/trace.go:171","msg":"trace[994677275] transaction","detail":"{read_only:false; response_revision:2325; number_of_response:1; }","duration":"152.768154ms","start":"2026-02-25T02:18:55.668046Z","end":"2026-02-25T02:18:55.820814Z","steps":["trace[994677275] 'process raft request' (duration: 152.59034ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:56.033929Z","caller":"traceutil/trace.go:171","msg":"trace[1025347689] transaction","detail":"{read_only:false; response_revision:2328; number_of_response:1; }","duration":"124.395123ms","start":"2026-02-25T02:18:55.909505Z","end":"2026-02-25T02:18:56.0339Z","steps":["trace[1025347689] 'process raft request' (duration: 124.291671ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:56.033931Z","caller":"traceutil/trace.go:171","msg":"trace[94247968] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"200.989695ms","start":"2026-02-25T02:18:55.832912Z","end":"2026-02-25T02:18:56.033902Z","steps":["trace[94247968] 'process raft request' (duration: 143.898867ms)","trace[94247968] 'compare' (duration: 56.826003ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:18:56.270323Z","caller":"traceutil/trace.go:171","msg":"trace[523236377] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"202.593081ms","start":"2026-02-25T02:18:56.067701Z","end":"2026-02-25T02:18:56.270294Z","steps":["trace[523236377] 'process raft request' (duration: 145.077113ms)","trace[523236377] 'compare' (duration: 57.389925ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:18:56.288505Z","caller":"traceutil/trace.go:171","msg":"trace[2137632317] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"184.356396ms","start":"2026-02-25T02:18:56.104119Z","end":"2026-02-25T02:18:56.288476Z","steps":["trace[2137632317] 'process raft request' (duration: 184.133161ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:56.649471Z","caller":"traceutil/trace.go:171","msg":"trace[71770093] transaction","detail":"{read_only:false; response_revision:2340; number_of_response:1; }","duration":"101.497865ms","start":"2026-02-25T02:18:56.547936Z","end":"2026-02-25T02:18:56.649433Z","steps":["trace[71770093] 'process raft request' (duration: 15.25889ms)","trace[71770093] 'compare' (duration: 85.899857ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:18:56.649752Z","caller":"traceutil/trace.go:171","msg":"trace[1346003900] transaction","detail":"{read_only:false; response_revision:2341; number_of_response:1; }","duration":"101.6925ms","start":"2026-02-25T02:18:56.54803Z","end":"2026-02-25T02:18:56.649722Z","steps":["trace[1346003900] 'process raft request' (duration: 101.223929ms)"],"step_count":1}