{"level":"warn","ts":"2026-06-02T06:54:24.047671Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T06:54:24.04777Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.61:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.61:2380","--initial-cluster=instance=https://199.19.213.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.61: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-06-02T06:54:24.047851Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T06:54:24.047863Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.61:2380"]} {"level":"info","ts":"2026-06-02T06:54:24.047888Z","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-06-02T06:54:24.049615Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"]} {"level":"info","ts":"2026-06-02T06:54:24.049719Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.61:2380"],"listen-peer-urls":["https://199.19.213.61:2380"],"advertise-client-urls":["https://199.19.213.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.61: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-06-02T06:54:24.05572Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.677064ms"} {"level":"info","ts":"2026-06-02T06:54:24.062646Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d1484a2210d3c2b8","cluster-id":"27fc2dec3fac595c"} {"level":"info","ts":"2026-06-02T06:54:24.062772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T06:54:24.0628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became follower at term 0"} {"level":"info","ts":"2026-06-02T06:54:24.062812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d1484a2210d3c2b8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T06:54:24.062823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became follower at term 1"} {"level":"info","ts":"2026-06-02T06:54:24.06291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=(15080384862421762744)"} {"level":"warn","ts":"2026-06-02T06:54:24.073526Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T06:54:24.075771Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T06:54:24.077168Z","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-06-02T06:54:24.082939Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d1484a2210d3c2b8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T06:54:24.083189Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d1484a2210d3c2b8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T06:54:24.083352Z","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-06-02T06:54:24.083411Z","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-06-02T06:54:24.083421Z","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-06-02T06:54:24.087751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=(15080384862421762744)"} {"level":"info","ts":"2026-06-02T06:54:24.087978Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"27fc2dec3fac595c","local-member-id":"d1484a2210d3c2b8","added-peer-id":"d1484a2210d3c2b8","added-peer-peer-urls":["https://199.19.213.61:2380"]} {"level":"info","ts":"2026-06-02T06:54:24.089368Z","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-06-02T06:54:24.089517Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.61:2380"} {"level":"info","ts":"2026-06-02T06:54:24.089569Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d1484a2210d3c2b8","initial-advertise-peer-urls":["https://199.19.213.61:2380"],"listen-peer-urls":["https://199.19.213.61:2380"],"advertise-client-urls":["https://199.19.213.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T06:54:24.089616Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T06:54:24.089563Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.61:2380"} {"level":"info","ts":"2026-06-02T06:54:24.566199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T06:54:24.566251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T06:54:24.566264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 received MsgPreVoteResp from d1484a2210d3c2b8 at term 1"} {"level":"info","ts":"2026-06-02T06:54:24.566276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became candidate at term 2"} {"level":"info","ts":"2026-06-02T06:54:24.566282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 received MsgVoteResp from d1484a2210d3c2b8 at term 2"} {"level":"info","ts":"2026-06-02T06:54:24.566291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became leader at term 2"} {"level":"info","ts":"2026-06-02T06:54:24.566298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d1484a2210d3c2b8 elected leader d1484a2210d3c2b8 at term 2"} {"level":"info","ts":"2026-06-02T06:54:24.569153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T06:54:24.56916Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d1484a2210d3c2b8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.61:2379]}","request-path":"/0/members/d1484a2210d3c2b8/attributes","cluster-id":"27fc2dec3fac595c","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T06:54:24.569174Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:54:24.569293Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T06:54:24.569545Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T06:54:24.569623Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T06:54:24.570214Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"27fc2dec3fac595c","local-member-id":"d1484a2210d3c2b8","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:54:24.570334Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:54:24.570372Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:54:24.571113Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T06:54:24.578063Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.61:2379"} {"level":"info","ts":"2026-06-02T06:54:28.426572Z","caller":"traceutil/trace.go:171","msg":"trace[1637681793] linearizableReadLoop","detail":"{readStateIndex:203; appliedIndex:202; }","duration":"226.068177ms","start":"2026-06-02T06:54:28.200483Z","end":"2026-06-02T06:54:28.426551Z","steps":["trace[1637681793] 'read index received' (duration: 225.912013ms)","trace[1637681793] 'applied index is now lower than readState.Index' (duration: 155.614µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:54:28.426742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.243462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:54:28.426803Z","caller":"traceutil/trace.go:171","msg":"trace[1831544339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:198; }","duration":"226.350055ms","start":"2026-06-02T06:54:28.200444Z","end":"2026-06-02T06:54:28.426794Z","steps":["trace[1831544339] 'agreement among raft nodes before linearized reading' (duration: 226.19952ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:28.426782Z","caller":"traceutil/trace.go:171","msg":"trace[759846988] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"535.515654ms","start":"2026-06-02T06:54:27.891223Z","end":"2026-06-02T06:54:28.426739Z","steps":["trace[759846988] 'process raft request' (duration: 535.219325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:28.427358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:27.891206Z","time spent":"535.655538ms","remote":"127.0.0.1:36704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:54:28.728853Z","caller":"traceutil/trace.go:171","msg":"trace[1127199952] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"298.721898ms","start":"2026-06-02T06:54:28.430095Z","end":"2026-06-02T06:54:28.728817Z","steps":["trace[1127199952] 'process raft request' (duration: 236.495247ms)","trace[1127199952] 'compare' (duration: 62.040645ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:54:28.994152Z","caller":"traceutil/trace.go:171","msg":"trace[607439179] linearizableReadLoop","detail":"{readStateIndex:205; appliedIndex:204; }","duration":"261.391174ms","start":"2026-06-02T06:54:28.732743Z","end":"2026-06-02T06:54:28.994134Z","steps":["trace[607439179] 'read index received' (duration: 261.241599ms)","trace[607439179] 'applied index is now lower than readState.Index' (duration: 148.845µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:54:28.994273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.514998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T06:54:28.994278Z","caller":"traceutil/trace.go:171","msg":"trace[1343894101] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"562.260933ms","start":"2026-06-02T06:54:28.431989Z","end":"2026-06-02T06:54:28.99425Z","steps":["trace[1343894101] 'process raft request' (duration: 562.033186ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:28.994297Z","caller":"traceutil/trace.go:171","msg":"trace[1663345815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:200; }","duration":"261.60341ms","start":"2026-06-02T06:54:28.732685Z","end":"2026-06-02T06:54:28.994289Z","steps":["trace[1663345815] 'agreement among raft nodes before linearized reading' (duration: 261.522447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:28.994343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:28.43197Z","time spent":"562.338705ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:54:29.203536Z","caller":"traceutil/trace.go:171","msg":"trace[1501236836] linearizableReadLoop","detail":"{readStateIndex:207; appliedIndex:205; }","duration":"133.295567ms","start":"2026-06-02T06:54:29.070222Z","end":"2026-06-02T06:54:29.203518Z","steps":["trace[1501236836] 'read index received' (duration: 35.480841ms)","trace[1501236836] 'applied index is now lower than readState.Index' (duration: 97.814156ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:54:29.203731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.501713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:54:29.203767Z","caller":"traceutil/trace.go:171","msg":"trace[1078566241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:202; }","duration":"133.549244ms","start":"2026-06-02T06:54:29.07021Z","end":"2026-06-02T06:54:29.203759Z","steps":["trace[1078566241] 'agreement among raft nodes before linearized reading' (duration: 133.44394ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:29.204018Z","caller":"traceutil/trace.go:171","msg":"trace[1805420241] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"204.66217ms","start":"2026-06-02T06:54:28.999329Z","end":"2026-06-02T06:54:29.203991Z","steps":["trace[1805420241] 'process raft request' (duration: 204.151055ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:29.204102Z","caller":"traceutil/trace.go:171","msg":"trace[592113688] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"204.225847ms","start":"2026-06-02T06:54:28.999321Z","end":"2026-06-02T06:54:29.203546Z","steps":["trace[592113688] 'process raft request' (duration: 106.485414ms)","trace[592113688] 'compare' (duration: 97.571808ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:55:03.627761Z","caller":"traceutil/trace.go:171","msg":"trace[25712154] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"159.975492ms","start":"2026-06-02T06:55:03.467765Z","end":"2026-06-02T06:55:03.62774Z","steps":["trace[25712154] 'process raft request' (duration: 159.826668ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:03.804681Z","caller":"traceutil/trace.go:171","msg":"trace[1151139178] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"238.326792ms","start":"2026-06-02T06:55:03.566328Z","end":"2026-06-02T06:55:03.804655Z","steps":["trace[1151139178] 'process raft request' (duration: 217.774495ms)","trace[1151139178] 'compare' (duration: 18.477248ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:55:05.281902Z","caller":"traceutil/trace.go:171","msg":"trace[1365197463] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"486.589099ms","start":"2026-06-02T06:55:04.795284Z","end":"2026-06-02T06:55:05.281873Z","steps":["trace[1365197463] 'process raft request' (duration: 486.378593ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:05.281959Z","caller":"traceutil/trace.go:171","msg":"trace[414928040] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:661; }","duration":"319.562154ms","start":"2026-06-02T06:55:04.962363Z","end":"2026-06-02T06:55:05.281925Z","steps":["trace[414928040] 'read index received' (duration: 319.553414ms)","trace[414928040] 'applied index is now lower than readState.Index' (duration: 6.94µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:55:05.282292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.901853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-06-02T06:55:05.282321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:04.795247Z","time spent":"486.743893ms","remote":"127.0.0.1:36652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:55:05.282342Z","caller":"traceutil/trace.go:171","msg":"trace[1928100593] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:636; }","duration":"319.986217ms","start":"2026-06-02T06:55:04.962341Z","end":"2026-06-02T06:55:05.282327Z","steps":["trace[1928100593] 'agreement among raft nodes before linearized reading' (duration: 319.72535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:05.282511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:04.962327Z","time spent":"320.169252ms","remote":"127.0.0.1:36652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-06-02T06:55:05.285705Z","caller":"traceutil/trace.go:171","msg":"trace[1703914073] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"120.858305ms","start":"2026-06-02T06:55:05.164824Z","end":"2026-06-02T06:55:05.285682Z","steps":["trace[1703914073] 'process raft request' (duration: 120.761362ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:10.538463Z","caller":"traceutil/trace.go:171","msg":"trace[1496601562] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"116.920712ms","start":"2026-06-02T06:55:10.421516Z","end":"2026-06-02T06:55:10.538437Z","steps":["trace[1496601562] 'read index received' (duration: 116.038606ms)","trace[1496601562] 'applied index is now lower than readState.Index' (duration: 880.986µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:55:10.538676Z","caller":"traceutil/trace.go:171","msg":"trace[192141131] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"301.327113ms","start":"2026-06-02T06:55:10.237208Z","end":"2026-06-02T06:55:10.538535Z","steps":["trace[192141131] 'process raft request' (duration: 300.587032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:10.538945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:10.237176Z","time spent":"301.646592ms","remote":"127.0.0.1:36696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:55:10.53954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.967731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T06:55:10.539627Z","caller":"traceutil/trace.go:171","msg":"trace[2104135791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"118.161757ms","start":"2026-06-02T06:55:10.421443Z","end":"2026-06-02T06:55:10.539605Z","steps":["trace[2104135791] 'agreement among raft nodes before linearized reading' (duration: 117.146749ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:11.267968Z","caller":"traceutil/trace.go:171","msg":"trace[436237779] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"410.436661ms","start":"2026-06-02T06:55:10.857507Z","end":"2026-06-02T06:55:11.267943Z","steps":["trace[436237779] 'read index received' (duration: 403.650677ms)","trace[436237779] 'applied index is now lower than readState.Index' (duration: 6.784744ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:55:11.268078Z","caller":"traceutil/trace.go:171","msg":"trace[855898920] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"669.64018ms","start":"2026-06-02T06:55:10.59841Z","end":"2026-06-02T06:55:11.26805Z","steps":["trace[855898920] 'process raft request' (duration: 662.788225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:11.26819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.098032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4r4n8\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-06-02T06:55:11.26821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:10.598381Z","time spent":"669.766373ms","remote":"127.0.0.1:36946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:55:11.268229Z","caller":"traceutil/trace.go:171","msg":"trace[1653836130] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4r4n8; range_end:; response_count:1; response_revision:684; }","duration":"402.182425ms","start":"2026-06-02T06:55:10.866036Z","end":"2026-06-02T06:55:11.268219Z","steps":["trace[1653836130] 'agreement among raft nodes before linearized reading' (duration: 402.045212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:11.268266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:10.866011Z","time spent":"402.246027ms","remote":"127.0.0.1:36778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-4r4n8\" "} {"level":"warn","ts":"2026-06-02T06:55:11.268249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.729009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T06:55:11.268328Z","caller":"traceutil/trace.go:171","msg":"trace[1458786067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:684; }","duration":"410.873143ms","start":"2026-06-02T06:55:10.857436Z","end":"2026-06-02T06:55:11.26831Z","steps":["trace[1458786067] 'agreement among raft nodes before linearized reading' (duration: 410.619995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:11.268372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:10.857397Z","time spent":"410.964706ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T06:55:21.50257Z","caller":"traceutil/trace.go:171","msg":"trace[1919917268] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"116.033127ms","start":"2026-06-02T06:55:21.38651Z","end":"2026-06-02T06:55:21.502543Z","steps":["trace[1919917268] 'process raft request' (duration: 115.7985ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:21.502549Z","caller":"traceutil/trace.go:171","msg":"trace[1360588317] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"114.474802ms","start":"2026-06-02T06:55:21.388051Z","end":"2026-06-02T06:55:21.502526Z","steps":["trace[1360588317] 'process raft request' (duration: 114.359529ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:27.990177Z","caller":"traceutil/trace.go:171","msg":"trace[199866183] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"201.955853ms","start":"2026-06-02T06:55:27.788185Z","end":"2026-06-02T06:55:27.990141Z","steps":["trace[199866183] 'process raft request' (duration: 201.680756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:33.426966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.973301ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T06:55:33.427124Z","caller":"traceutil/trace.go:171","msg":"trace[1074959031] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:858; }","duration":"724.195271ms","start":"2026-06-02T06:55:32.702907Z","end":"2026-06-02T06:55:33.427103Z","steps":["trace[1074959031] 'read index received' (duration: 385.900141ms)","trace[1074959031] 'applied index is now lower than readState.Index' (duration: 338.29322ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:55:33.427377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.460427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-02T06:55:33.427418Z","caller":"traceutil/trace.go:171","msg":"trace[1741938931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:826; }","duration":"724.56927ms","start":"2026-06-02T06:55:32.702837Z","end":"2026-06-02T06:55:33.427406Z","steps":["trace[1741938931] 'agreement among raft nodes before linearized reading' (duration: 724.364204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:33.427465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:32.702808Z","time spent":"724.645063ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T06:55:33.498066Z","caller":"traceutil/trace.go:171","msg":"trace[693217089] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"483.442788ms","start":"2026-06-02T06:55:33.014587Z","end":"2026-06-02T06:55:33.49803Z","steps":["trace[693217089] 'process raft request' (duration: 483.230432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:33.498136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.746004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T06:55:33.498199Z","caller":"traceutil/trace.go:171","msg":"trace[1122459491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:827; }","duration":"655.892877ms","start":"2026-06-02T06:55:32.842291Z","end":"2026-06-02T06:55:33.498184Z","steps":["trace[1122459491] 'agreement among raft nodes before linearized reading' (duration: 655.678941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:33.498278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:33.01457Z","time spent":"483.611983ms","remote":"127.0.0.1:36836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:55:33.498295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:32.842186Z","time spent":"656.101953ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T06:55:33.498202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.427095ms","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":"info","ts":"2026-06-02T06:55:33.49858Z","caller":"traceutil/trace.go:171","msg":"trace[280004320] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:827; }","duration":"246.865576ms","start":"2026-06-02T06:55:33.251688Z","end":"2026-06-02T06:55:33.498554Z","steps":["trace[280004320] 'agreement among raft nodes before linearized reading' (duration: 246.354483ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:08.310439Z","caller":"traceutil/trace.go:171","msg":"trace[1428900976] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"102.331754ms","start":"2026-06-02T06:56:08.208067Z","end":"2026-06-02T06:56:08.310399Z","steps":["trace[1428900976] 'process raft request' (duration: 101.763647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:08.756959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.831697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:56:08.757154Z","caller":"traceutil/trace.go:171","msg":"trace[1223940359] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"395.926154ms","start":"2026-06-02T06:56:08.361209Z","end":"2026-06-02T06:56:08.757135Z","steps":["trace[1223940359] 'process raft request' (duration: 395.867543ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:08.757154Z","caller":"traceutil/trace.go:171","msg":"trace[449538651] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"545.950379ms","start":"2026-06-02T06:56:08.211163Z","end":"2026-06-02T06:56:08.757113Z","steps":["trace[449538651] 'process raft request' (duration: 279.881415ms)","trace[449538651] 'compare' (duration: 265.713204ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:08.757252Z","caller":"traceutil/trace.go:171","msg":"trace[1232767610] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1083; }","duration":"450.976808ms","start":"2026-06-02T06:56:08.306263Z","end":"2026-06-02T06:56:08.75724Z","steps":["trace[1232767610] 'read index received' (duration: 3.487031ms)","trace[1232767610] 'applied index is now lower than readState.Index' (duration: 447.488697ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:08.757249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:08.361186Z","time spent":"396.010588ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:56:08.757343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.097321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T06:56:08.757343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:08.211157Z","time spent":"546.084802ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:08.757371Z","caller":"traceutil/trace.go:171","msg":"trace[932445919] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1044; }","duration":"451.155425ms","start":"2026-06-02T06:56:08.306208Z","end":"2026-06-02T06:56:08.757363Z","steps":["trace[932445919] 'agreement among raft nodes before linearized reading' (duration: 451.105043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:08.757521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.101205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:56:08.75761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:08.306189Z","time spent":"451.357979ms","remote":"127.0.0.1:36770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-06-02T06:56:08.757682Z","caller":"traceutil/trace.go:171","msg":"trace[95367959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"219.314021ms","start":"2026-06-02T06:56:08.53834Z","end":"2026-06-02T06:56:08.757654Z","steps":["trace[95367959] 'agreement among raft nodes before linearized reading' (duration: 219.042863ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:29.078617Z","caller":"traceutil/trace.go:171","msg":"trace[292050559] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"224.181412ms","start":"2026-06-02T06:56:28.854416Z","end":"2026-06-02T06:56:29.078597Z","steps":["trace[292050559] 'process raft request' (duration: 224.069589ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:29.494811Z","caller":"traceutil/trace.go:171","msg":"trace[644453492] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"187.235902ms","start":"2026-06-02T06:56:29.307555Z","end":"2026-06-02T06:56:29.494791Z","steps":["trace[644453492] 'process raft request' (duration: 187.118168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:30.297519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.936679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-02T06:56:30.297588Z","caller":"traceutil/trace.go:171","msg":"trace[1065606401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"171.054294ms","start":"2026-06-02T06:56:30.126517Z","end":"2026-06-02T06:56:30.297572Z","steps":["trace[1065606401] 'range keys from in-memory index tree' (duration: 170.785605ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:30.521628Z","caller":"traceutil/trace.go:171","msg":"trace[1769067328] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"220.815484ms","start":"2026-06-02T06:56:30.300795Z","end":"2026-06-02T06:56:30.52161Z","steps":["trace[1769067328] 'process raft request' (duration: 220.67178ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:30.632402Z","caller":"traceutil/trace.go:171","msg":"trace[984624090] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1160; }","duration":"107.854914ms","start":"2026-06-02T06:56:30.524524Z","end":"2026-06-02T06:56:30.632379Z","steps":["trace[984624090] 'read index received' (duration: 100.868382ms)","trace[984624090] 'applied index is now lower than readState.Index' (duration: 6.985622ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:30.632512Z","caller":"traceutil/trace.go:171","msg":"trace[1115287275] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"241.571945ms","start":"2026-06-02T06:56:30.390921Z","end":"2026-06-02T06:56:30.632492Z","steps":["trace[1115287275] 'process raft request' (duration: 234.46885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:30.632548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.004218ms","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":"info","ts":"2026-06-02T06:56:30.63259Z","caller":"traceutil/trace.go:171","msg":"trace[1594720279] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1115; }","duration":"108.097471ms","start":"2026-06-02T06:56:30.524483Z","end":"2026-06-02T06:56:30.63258Z","steps":["trace[1594720279] 'agreement among raft nodes before linearized reading' (duration: 107.995358ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:32.095605Z","caller":"traceutil/trace.go:171","msg":"trace[584214726] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"270.370069ms","start":"2026-06-02T06:56:31.825196Z","end":"2026-06-02T06:56:32.095566Z","steps":["trace[584214726] 'read index received' (duration: 270.100662ms)","trace[584214726] 'applied index is now lower than readState.Index' (duration: 268.167µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:32.095703Z","caller":"traceutil/trace.go:171","msg":"trace[735227463] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"281.253305ms","start":"2026-06-02T06:56:31.814424Z","end":"2026-06-02T06:56:32.095678Z","steps":["trace[735227463] 'process raft request' (duration: 280.941346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:32.09599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.72535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T06:56:32.096093Z","caller":"traceutil/trace.go:171","msg":"trace[638326000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1117; }","duration":"270.908355ms","start":"2026-06-02T06:56:31.825168Z","end":"2026-06-02T06:56:32.096076Z","steps":["trace[638326000] 'agreement among raft nodes before linearized reading' (duration: 270.594366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:32.096471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.146442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T06:56:32.096523Z","caller":"traceutil/trace.go:171","msg":"trace[1315757007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1117; }","duration":"175.227254ms","start":"2026-06-02T06:56:31.921282Z","end":"2026-06-02T06:56:32.096509Z","steps":["trace[1315757007] 'agreement among raft nodes before linearized reading' (duration: 175.085869ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:32.209183Z","caller":"traceutil/trace.go:171","msg":"trace[2093595261] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"108.625865ms","start":"2026-06-02T06:56:32.100528Z","end":"2026-06-02T06:56:32.209153Z","steps":["trace[2093595261] 'process raft request' (duration: 98.025058ms)","trace[2093595261] 'compare' (duration: 10.323289ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:32.20939Z","caller":"traceutil/trace.go:171","msg":"trace[1599631858] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"107.970026ms","start":"2026-06-02T06:56:32.101388Z","end":"2026-06-02T06:56:32.209358Z","steps":["trace[1599631858] 'process raft request' (duration: 107.800321ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:46.935386Z","caller":"traceutil/trace.go:171","msg":"trace[470500450] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"337.209054ms","start":"2026-06-02T06:56:46.598161Z","end":"2026-06-02T06:56:46.93537Z","steps":["trace[470500450] 'process raft request' (duration: 337.108521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:46.9355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.598141Z","time spent":"337.303998ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:47.232206Z","caller":"traceutil/trace.go:171","msg":"trace[1269910352] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"351.422386ms","start":"2026-06-02T06:56:46.880757Z","end":"2026-06-02T06:56:47.232179Z","steps":["trace[1269910352] 'read index received' (duration: 54.627352ms)","trace[1269910352] 'applied index is now lower than readState.Index' (duration: 296.793994ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:47.232325Z","caller":"traceutil/trace.go:171","msg":"trace[126921341] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"536.719751ms","start":"2026-06-02T06:56:46.695591Z","end":"2026-06-02T06:56:47.232311Z","steps":["trace[126921341] 'process raft request' (duration: 536.428913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.232488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.695554Z","time spent":"536.798063ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:56:47.232584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.789147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:47.232726Z","caller":"traceutil/trace.go:171","msg":"trace[2114872442] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1219; }","duration":"351.974423ms","start":"2026-06-02T06:56:46.880725Z","end":"2026-06-02T06:56:47.232699Z","steps":["trace[2114872442] 'agreement among raft nodes before linearized reading' (duration: 351.767927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.23278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.88071Z","time spent":"352.057884ms","remote":"127.0.0.1:37106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-02T06:57:05.115423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.032879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:57:05.11552Z","caller":"traceutil/trace.go:171","msg":"trace[204706647] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1299; }","duration":"280.163402ms","start":"2026-06-02T06:57:04.835338Z","end":"2026-06-02T06:57:05.115502Z","steps":["trace[204706647] 'range keys from in-memory index tree' (duration: 279.976638ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:05.183093Z","caller":"traceutil/trace.go:171","msg":"trace[1467202708] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"149.984833ms","start":"2026-06-02T06:57:05.033091Z","end":"2026-06-02T06:57:05.183075Z","steps":["trace[1467202708] 'process raft request' (duration: 149.87616ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:05.37396Z","caller":"traceutil/trace.go:171","msg":"trace[991333456] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"244.675795ms","start":"2026-06-02T06:57:05.129265Z","end":"2026-06-02T06:57:05.373941Z","steps":["trace[991333456] 'process raft request' (duration: 244.631393ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:05.374276Z","caller":"traceutil/trace.go:171","msg":"trace[91845766] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"247.335452ms","start":"2026-06-02T06:57:05.126737Z","end":"2026-06-02T06:57:05.374073Z","steps":["trace[91845766] 'process raft request' (duration: 241.72853ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:05.38141Z","caller":"traceutil/trace.go:171","msg":"trace[648438653] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"195.197222ms","start":"2026-06-02T06:57:05.186196Z","end":"2026-06-02T06:57:05.381393Z","steps":["trace[648438653] 'process raft request' (duration: 194.989866ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:16.696971Z","caller":"traceutil/trace.go:171","msg":"trace[1520389947] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"142.29438ms","start":"2026-06-02T06:57:16.554642Z","end":"2026-06-02T06:57:16.696936Z","steps":["trace[1520389947] 'process raft request' (duration: 137.264655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:17.424096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.872439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T06:57:17.424206Z","caller":"traceutil/trace.go:171","msg":"trace[1013410647] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1384; }","duration":"333.048094ms","start":"2026-06-02T06:57:17.091135Z","end":"2026-06-02T06:57:17.424183Z","steps":["trace[1013410647] 'range keys from in-memory index tree' (duration: 332.663723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:17.424245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:17.091083Z","time spent":"333.154088ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-02T06:57:17.535373Z","caller":"traceutil/trace.go:171","msg":"trace[1425945738] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"106.820843ms","start":"2026-06-02T06:57:17.428514Z","end":"2026-06-02T06:57:17.535335Z","steps":["trace[1425945738] 'process raft request' (duration: 99.78995ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:14.925914Z","caller":"traceutil/trace.go:171","msg":"trace[1477949545] linearizableReadLoop","detail":"{readStateIndex:1718; appliedIndex:1717; }","duration":"213.286267ms","start":"2026-06-02T06:58:14.712601Z","end":"2026-06-02T06:58:14.925887Z","steps":["trace[1477949545] 'read index received' (duration: 213.068881ms)","trace[1477949545] 'applied index is now lower than readState.Index' (duration: 216.166µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:14.926006Z","caller":"traceutil/trace.go:171","msg":"trace[1202343877] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"246.792489ms","start":"2026-06-02T06:58:14.679193Z","end":"2026-06-02T06:58:14.925985Z","steps":["trace[1202343877] 'process raft request' (duration: 246.511691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:14.926217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.590796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-02T06:58:14.926292Z","caller":"traceutil/trace.go:171","msg":"trace[115338250] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1649; }","duration":"213.745901ms","start":"2026-06-02T06:58:14.712529Z","end":"2026-06-02T06:58:14.926275Z","steps":["trace[115338250] 'agreement among raft nodes before linearized reading' (duration: 213.464363ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:16.218557Z","caller":"traceutil/trace.go:171","msg":"trace[1943366364] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"189.400049ms","start":"2026-06-02T06:58:16.029137Z","end":"2026-06-02T06:58:16.218537Z","steps":["trace[1943366364] 'process raft request' (duration: 189.238545ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:21.777111Z","caller":"traceutil/trace.go:171","msg":"trace[1295160927] transaction","detail":"{read_only:false; response_revision:1681; number_of_response:1; }","duration":"101.446282ms","start":"2026-06-02T06:58:21.675132Z","end":"2026-06-02T06:58:21.776578Z","steps":["trace[1295160927] 'process raft request' (duration: 101.263577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:22.19157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.319603ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:22.191721Z","caller":"traceutil/trace.go:171","msg":"trace[854415507] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1682; }","duration":"304.500649ms","start":"2026-06-02T06:58:21.887199Z","end":"2026-06-02T06:58:22.1917Z","steps":["trace[854415507] 'range keys from in-memory index tree' (duration: 304.298983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:22.191777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.482813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-02T06:58:22.191836Z","caller":"traceutil/trace.go:171","msg":"trace[1289475847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1682; }","duration":"307.592386ms","start":"2026-06-02T06:58:21.884227Z","end":"2026-06-02T06:58:22.19182Z","steps":["trace[1289475847] 'range keys from in-memory index tree' (duration: 307.310619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:22.191869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.992351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:58:22.191887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:21.884222Z","time spent":"307.653137ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T06:58:22.191941Z","caller":"traceutil/trace.go:171","msg":"trace[1921142687] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1682; }","duration":"118.126115ms","start":"2026-06-02T06:58:22.073794Z","end":"2026-06-02T06:58:22.19192Z","steps":["trace[1921142687] 'count revisions from in-memory index tree' (duration: 117.902879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:22.191981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.71625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-02T06:58:22.19212Z","caller":"traceutil/trace.go:171","msg":"trace[6070212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1682; }","duration":"307.960756ms","start":"2026-06-02T06:58:21.884129Z","end":"2026-06-02T06:58:22.192089Z","steps":["trace[6070212] 'range keys from in-memory index tree' (duration: 307.362719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:22.192181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:21.884108Z","time spent":"308.058459ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T06:58:24.208492Z","caller":"traceutil/trace.go:171","msg":"trace[1630526550] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"425.69372ms","start":"2026-06-02T06:58:23.782779Z","end":"2026-06-02T06:58:24.208473Z","steps":["trace[1630526550] 'process raft request' (duration: 425.600377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:24.208715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:23.78276Z","time spent":"425.785251ms","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:00:17.641257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.515811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:17.641346Z","caller":"traceutil/trace.go:171","msg":"trace[2000290127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2211; }","duration":"103.659786ms","start":"2026-06-02T07:00:17.537666Z","end":"2026-06-02T07:00:17.641326Z","steps":["trace[2000290127] 'range keys from in-memory index tree' (duration: 103.402899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:17.641423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.229276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-02T07:00:17.641495Z","caller":"traceutil/trace.go:171","msg":"trace[13838904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2211; }","duration":"149.363119ms","start":"2026-06-02T07:00:17.492117Z","end":"2026-06-02T07:00:17.64148Z","steps":["trace[13838904] 'range keys from in-memory index tree' (duration: 149.02863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:17.641558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.391755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T07:00:17.641758Z","caller":"traceutil/trace.go:171","msg":"trace[1263000135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2211; }","duration":"105.538709ms","start":"2026-06-02T07:00:17.536099Z","end":"2026-06-02T07:00:17.641638Z","steps":["trace[1263000135] 'range keys from in-memory index tree' (duration: 105.22391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:17.641757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.543946ms","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-06-02T07:00:17.641919Z","caller":"traceutil/trace.go:171","msg":"trace[162572104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2211; }","duration":"149.690859ms","start":"2026-06-02T07:00:17.492139Z","end":"2026-06-02T07:00:17.641829Z","steps":["trace[162572104] 'range keys from in-memory index tree' (duration: 149.124152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:18.393622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:18.89453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:19.395443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:19.896025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:20.396524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:20.538355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00045134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-02T07:00:20.538498Z","caller":"traceutil/trace.go:171","msg":"trace[1083949396] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000651216s","start":"2026-06-02T07:00:18.537818Z","end":"2026-06-02T07:00:20.538469Z","steps":["trace[1083949396] 'agreement among raft nodes before linearized reading' (duration: 2.000469772s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:20.538582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:18.537802Z","time spent":"2.000766669s","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/02 07:00:20 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:20.897531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:21.398656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:21.89931Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:22.40028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:22.538604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999827136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-02T07:00:22.53894Z","caller":"traceutil/trace.go:171","msg":"trace[646060280] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000279158s","start":"2026-06-02T07:00:20.538634Z","end":"2026-06-02T07:00:22.538913Z","steps":["trace[646060280] 'agreement among raft nodes before linearized reading' (duration: 1.999894728s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:22.539001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:20.538608Z","time spent":"2.000381922s","remote":"127.0.0.1:36590","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-06-02T07:00:22.646817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:17.647634Z","time spent":"4.999151902s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-02T07:00:22.646764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:17.646759Z","time spent":"4.999992397s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-02T07:00:22.901061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:23.402258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:23.580601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:18.581308Z","time spent":"4.99928413s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/02 07:00:23 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:23.735043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999104298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:23.735139Z","caller":"traceutil/trace.go:171","msg":"trace[2016604585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999258162s","start":"2026-06-02T07:00:18.735857Z","end":"2026-06-02T07:00:23.735115Z","steps":["trace[2016604585] 'agreement among raft nodes before linearized reading' (duration: 4.999141438s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:23.735194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:18.735832Z","time spent":"4.999349004s","remote":"127.0.0.1:36868","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/06/02 07:00:23 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:23.902766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:24.403789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674890,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:24.546896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999877244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-02T07:00:24.54699Z","caller":"traceutil/trace.go:171","msg":"trace[1360183400] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000002777s","start":"2026-06-02T07:00:22.546961Z","end":"2026-06-02T07:00:24.546964Z","steps":["trace[1360183400] 'agreement among raft nodes before linearized reading' (duration: 1.999891704s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:24.547092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:22.546949Z","time spent":"2.0001307s","remote":"127.0.0.1:36574","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-06-02T07:00:24.645317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:17.645122Z","time spent":"7.000183628s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-02T07:00:24.71932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:17.718315Z","time spent":"7.000999008s","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-02T07:00:24.893461Z","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-06-02T07:00:24.893655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000575369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-06-02T07:00:24.893694Z","caller":"traceutil/trace.go:171","msg":"trace[471143774] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; }","duration":"7.000673551s","start":"2026-06-02T07:00:17.893002Z","end":"2026-06-02T07:00:24.893676Z","steps":["trace[471143774] 'agreement among raft nodes before linearized reading' (duration: 7.000611639s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:24.893732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:17.89298Z","time spent":"7.000745173s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-02T07:00:25.394613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:25.895492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:26.396184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:26.548258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000217333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-02T07:00:26.548363Z","caller":"traceutil/trace.go:171","msg":"trace[1204344810] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000374877s","start":"2026-06-02T07:00:24.547968Z","end":"2026-06-02T07:00:26.548343Z","steps":["trace[1204344810] 'agreement among raft nodes before linearized reading' (duration: 2.000241033s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:26.548426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:24.547948Z","time spent":"2.00045513s","remote":"127.0.0.1:36590","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-06-02T07:00:26.897089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:27.397537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:27.491081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.236161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:27.491145Z","caller":"traceutil/trace.go:171","msg":"trace[1660306968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"841.365173ms","start":"2026-06-02T07:00:26.649766Z","end":"2026-06-02T07:00:27.491131Z","steps":["trace[1660306968] 'agreement among raft nodes before linearized reading' (duration: 841.282601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:27.491189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:26.649727Z","time spent":"841.448897ms","remote":"127.0.0.1:36868","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/06/02 07:00:27 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:27.491723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.840416993s","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-06-02T07:00:27.491805Z","caller":"traceutil/trace.go:171","msg":"trace[1157473373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.840578168s","start":"2026-06-02T07:00:24.651206Z","end":"2026-06-02T07:00:27.491785Z","steps":["trace[1157473373] 'agreement among raft nodes before linearized reading' (duration: 2.840469645s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:27.491861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:24.651172Z","time spent":"2.840678531s","remote":"127.0.0.1:36868","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/06/02 07:00:27 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:27.531413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.881418588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:27.531504Z","caller":"traceutil/trace.go:171","msg":"trace[682209193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.881545492s","start":"2026-06-02T07:00:24.64994Z","end":"2026-06-02T07:00:27.531485Z","steps":["trace[682209193] 'agreement among raft nodes before linearized reading' (duration: 2.881441469s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:27.531556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:24.649926Z","time spent":"2.881613654s","remote":"127.0.0.1:36868","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/06/02 07:00:27 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:27.668222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000565953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-02T07:00:27.668317Z","caller":"traceutil/trace.go:171","msg":"trace[232670400] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000735917s","start":"2026-06-02T07:00:25.66756Z","end":"2026-06-02T07:00:27.668296Z","steps":["trace[232670400] 'agreement among raft nodes before linearized reading' (duration: 2.000595754s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:27.668382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:25.667539Z","time spent":"2.000823911s","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/02 07:00:27 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:27.898721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:28.398864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:28.557075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000025829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:28.55721Z","caller":"traceutil/trace.go:171","msg":"trace[418097092] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000227504s","start":"2026-06-02T07:00:26.556955Z","end":"2026-06-02T07:00:28.557183Z","steps":["trace[418097092] 'agreement among raft nodes before linearized reading' (duration: 2.000059739s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:28.55727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:26.556934Z","time spent":"2.000324936s","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/02 07:00:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:28.580105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.996967947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:28.580177Z","caller":"traceutil/trace.go:171","msg":"trace[1466310445] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.997095709s","start":"2026-06-02T07:00:23.583066Z","end":"2026-06-02T07:00:28.580162Z","steps":["trace[1466310445] 'agreement among raft nodes before linearized reading' (duration: 4.997000147s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:28.580223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:23.583049Z","time spent":"4.997154282s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/06/02 07:00:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:28.688699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.997988953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:28.688764Z","caller":"traceutil/trace.go:171","msg":"trace[1187219316] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; }","duration":"9.998112298s","start":"2026-06-02T07:00:18.690637Z","end":"2026-06-02T07:00:28.688749Z","steps":["trace[1187219316] 'agreement among raft nodes before linearized reading' (duration: 9.998022745s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:28.688797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:18.690615Z","time spent":"9.998176029s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} 2026/06/02 07:00:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:28.736994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.000243392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:28.737121Z","caller":"traceutil/trace.go:171","msg":"trace[1343401952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"3.000434988s","start":"2026-06-02T07:00:25.736657Z","end":"2026-06-02T07:00:28.737092Z","steps":["trace[1343401952] 'agreement among raft nodes before linearized reading' (duration: 3.000269183s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:28.737186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:25.736638Z","time spent":"3.00053601s","remote":"127.0.0.1:36868","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/06/02 07:00:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:28.872837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.998856473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:28.87293Z","caller":"traceutil/trace.go:171","msg":"trace[916875423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"9.999016028s","start":"2026-06-02T07:00:18.873893Z","end":"2026-06-02T07:00:28.872909Z","steps":["trace[916875423] 'agreement among raft nodes before linearized reading' (duration: 9.998886754s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:28.872982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:18.873872Z","time spent":"9.999100169s","remote":"127.0.0.1:36868","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/06/02 07:00:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:28.882756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.004393501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:28.882897Z","caller":"traceutil/trace.go:171","msg":"trace[1524071879] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; }","duration":"10.004585235s","start":"2026-06-02T07:00:18.878292Z","end":"2026-06-02T07:00:28.882877Z","steps":["trace[1524071879] 'agreement among raft nodes before linearized reading' (duration: 10.004443082s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:28.882939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:18.878265Z","time spent":"10.004663078s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} 2026/06/02 07:00:28 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:28.899112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:29.39941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:29.545882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:22.545373Z","time spent":"7.000495823s","remote":"127.0.0.1:36672","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-02T07:00:29.866867Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"42b89e871cad5291","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-06-02T07:00:29.900603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:30.180369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000684648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:30.180462Z","caller":"traceutil/trace.go:171","msg":"trace[678413741] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; }","duration":"10.000818284s","start":"2026-06-02T07:00:20.179622Z","end":"2026-06-02T07:00:30.180441Z","steps":["trace[678413741] 'agreement among raft nodes before linearized reading' (duration: 10.00070284s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:30.180513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:20.179607Z","time spent":"10.000895635s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} 2026/06/02 07:00:30 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:30.400919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:30.565302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000689945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:30.56539Z","caller":"traceutil/trace.go:171","msg":"trace[1684641079] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000812759s","start":"2026-06-02T07:00:28.564558Z","end":"2026-06-02T07:00:30.565371Z","steps":["trace[1684641079] 'agreement among raft nodes before linearized reading' (duration: 2.000698375s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:30.565439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:28.564553Z","time spent":"2.000876542s","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/02 07:00:30 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:30.846401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.998983023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T07:00:30.846523Z","caller":"traceutil/trace.go:171","msg":"trace[322964171] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; }","duration":"9.999154458s","start":"2026-06-02T07:00:20.847344Z","end":"2026-06-02T07:00:30.846499Z","steps":["trace[322964171] 'agreement among raft nodes before linearized reading' (duration: 9.999012625s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:30.846582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:20.847325Z","time spent":"9.999244171s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} 2026/06/02 07:00:30 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:30.90159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:31.14271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:24.141885Z","time spent":"7.000817971s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-02T07:00:31.401804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674897,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:31.893981Z","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-06-02T07:00:31.894287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.008488433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-06-02T07:00:31.894341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.635010181s","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":"info","ts":"2026-06-02T07:00:31.894393Z","caller":"traceutil/trace.go:171","msg":"trace[452829473] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; }","duration":"12.008630866s","start":"2026-06-02T07:00:19.88574Z","end":"2026-06-02T07:00:31.894371Z","steps":["trace[452829473] 'agreement among raft nodes before linearized reading' (duration: 12.008478433s)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:31.894419Z","caller":"traceutil/trace.go:171","msg":"trace[313315659] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; }","duration":"7.635201406s","start":"2026-06-02T07:00:24.259191Z","end":"2026-06-02T07:00:31.894393Z","steps":["trace[313315659] 'agreement among raft nodes before linearized reading' (duration: 7.63499343s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:31.894522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.779904291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-06-02T07:00:31.894565Z","caller":"traceutil/trace.go:171","msg":"trace[519123219] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; }","duration":"9.779985254s","start":"2026-06-02T07:00:22.114569Z","end":"2026-06-02T07:00:31.894554Z","steps":["trace[519123219] 'agreement among raft nodes before linearized reading' (duration: 9.779940653s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:31.8946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:22.11455Z","time spent":"9.780039676s","remote":"127.0.0.1:36742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":0,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:31.894589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:24.259171Z","time spent":"7.635365301s","remote":"127.0.0.1:37030","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":"warn","ts":"2026-06-02T07:00:31.894666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.029541141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-06-02T07:00:31.8947Z","caller":"traceutil/trace.go:171","msg":"trace[927891176] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; }","duration":"10.029622793s","start":"2026-06-02T07:00:21.865068Z","end":"2026-06-02T07:00:31.89469Z","steps":["trace[927891176] 'agreement among raft nodes before linearized reading' (duration: 10.029586222s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:31.894697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.754531179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-06-02T07:00:31.894737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:21.865044Z","time spent":"10.029680166s","remote":"127.0.0.1:36920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":0,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:31.894707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.165076298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-06-02T07:00:31.894761Z","caller":"traceutil/trace.go:171","msg":"trace[747769857] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; }","duration":"12.754654913s","start":"2026-06-02T07:00:19.140092Z","end":"2026-06-02T07:00:31.894747Z","steps":["trace[747769857] 'agreement among raft nodes before linearized reading' (duration: 12.75458892s)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:31.894802Z","caller":"traceutil/trace.go:171","msg":"trace[1198579762] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; }","duration":"12.165232021s","start":"2026-06-02T07:00:19.729548Z","end":"2026-06-02T07:00:31.89478Z","steps":["trace[1198579762] 'agreement among raft nodes before linearized reading' (duration: 12.165092978s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:31.894827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:19.140056Z","time spent":"12.754757177s","remote":"127.0.0.1:36652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":0,"response size":0,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:31.894802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.780110075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-06-02T07:00:31.894862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:19.729533Z","time spent":"12.165315804s","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:31.894477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:19.885695Z","time spent":"12.00876481s","remote":"127.0.0.1:36938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":0,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:31.894894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.914269493s","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":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-06-02T07:00:31.894931Z","caller":"traceutil/trace.go:171","msg":"trace[2021321708] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; }","duration":"11.780348012s","start":"2026-06-02T07:00:20.114549Z","end":"2026-06-02T07:00:31.894897Z","steps":["trace[2021321708] 'agreement among raft nodes before linearized reading' (duration: 11.780146647s)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:31.894986Z","caller":"traceutil/trace.go:171","msg":"trace[1483612190] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; }","duration":"12.914428068s","start":"2026-06-02T07:00:18.980532Z","end":"2026-06-02T07:00:31.89496Z","steps":["trace[1483612190] 'agreement among raft nodes before linearized reading' (duration: 12.914324115s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:31.895004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:20.114519Z","time spent":"11.780465005s","remote":"127.0.0.1:57572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:31.895058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:18.980498Z","time spent":"12.914542261s","remote":"127.0.0.1:37138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":0,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:31.894688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.343201262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-06-02T07:00:31.895509Z","caller":"traceutil/trace.go:171","msg":"trace[1418661317] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; }","duration":"12.344073516s","start":"2026-06-02T07:00:19.551336Z","end":"2026-06-02T07:00:31.89541Z","steps":["trace[1418661317] 'agreement among raft nodes before linearized reading' (duration: 12.343252714s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:31.895599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:19.551295Z","time spent":"12.344257621s","remote":"127.0.0.1:36952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":0,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:32.394261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:32.492807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:27.494815Z","time spent":"4.997978562s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/02 07:00:32 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:32.533074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:27.533673Z","time spent":"4.999389482s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/02 07:00:32 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:32.565635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999874312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-02T07:00:32.565733Z","caller":"traceutil/trace.go:171","msg":"trace[1698578906] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000039816s","start":"2026-06-02T07:00:30.565673Z","end":"2026-06-02T07:00:32.565712Z","steps":["trace[1698578906] 'agreement among raft nodes before linearized reading' (duration: 1.999909963s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:32.565793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:30.565639Z","time spent":"2.000137768s","remote":"127.0.0.1:36590","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-06-02T07:00:32.894492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:33.395257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:33.895666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:34.396386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:34.495269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:27.494139Z","time spent":"7.001116277s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-02T07:00:34.502666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:28.874821Z","time spent":"5.627834739s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/06/02 07:00:34 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T07:00:34.573691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00016727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-02T07:00:34.573813Z","caller":"traceutil/trace.go:171","msg":"trace[1675732973] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000376405s","start":"2026-06-02T07:00:32.573406Z","end":"2026-06-02T07:00:34.573782Z","steps":["trace[1675732973] 'agreement among raft nodes before linearized reading' (duration: 2.000211851s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:34.573891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:32.573383Z","time spent":"2.000497379s","remote":"127.0.0.1:36574","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-06-02T07:00:34.808512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:27.807452Z","time spent":"7.001050746s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-06-02T07:00:34.896899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:35.397467Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:35.898243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:36.399092Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:36.548295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:29.547581Z","time spent":"7.000700085s","remote":"127.0.0.1:36672","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-02T07:00:36.574761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999966586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-02T07:00:36.574856Z","caller":"traceutil/trace.go:171","msg":"trace[1966748348] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00012689s","start":"2026-06-02T07:00:34.574712Z","end":"2026-06-02T07:00:36.574839Z","steps":["trace[1966748348] 'agreement among raft nodes before linearized reading' (duration: 2.000007057s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:36.574904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:34.574684Z","time spent":"2.000210961s","remote":"127.0.0.1:36590","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-06-02T07:00:36.787784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"14.899686765s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:36.787892Z","caller":"traceutil/trace.go:171","msg":"trace[959929966] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2211; }","duration":"14.899802758s","start":"2026-06-02T07:00:21.888069Z","end":"2026-06-02T07:00:36.787872Z","steps":["trace[959929966] 'range keys from in-memory index tree' (duration: 14.899672505s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:36.875488Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"42b89e871cad5291","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-06-02T07:00:36.899372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138942211674907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T07:00:37.024794Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"19.272570228s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-02T07:00:37.237513Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"26.38µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"info","ts":"2026-06-02T07:00:37.238407Z","caller":"traceutil/trace.go:171","msg":"trace[465497990] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2219; }","duration":"6.090676362s","start":"2026-06-02T07:00:31.147697Z","end":"2026-06-02T07:00:37.238374Z","steps":["trace[465497990] 'process raft request' (duration: 6.090438555s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.23859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:31.147654Z","time spent":"6.090840926s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":720,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:00:37.270807Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"34.86µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"info","ts":"2026-06-02T07:00:37.270935Z","caller":"traceutil/trace.go:171","msg":"trace[1542439673] linearizableReadLoop","detail":"{readStateIndex:2324; appliedIndex:2305; }","duration":"5.376773469s","start":"2026-06-02T07:00:31.894142Z","end":"2026-06-02T07:00:37.270915Z","steps":["trace[1542439673] 'read index received' (duration: 5.130931398s)","trace[1542439673] 'applied index is now lower than readState.Index' (duration: 245.841121ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:00:37.271069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.756002692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T07:00:37.2711Z","caller":"traceutil/trace.go:171","msg":"trace[1077782642] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2220; }","duration":"9.756106573s","start":"2026-06-02T07:00:27.514984Z","end":"2026-06-02T07:00:37.271091Z","steps":["trace[1077782642] 'agreement among raft nodes before linearized reading' (duration: 9.756027781s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.271137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:27.51495Z","time spent":"9.756177955s","remote":"127.0.0.1:57744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:37.271138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.470951352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-02T07:00:37.271215Z","caller":"traceutil/trace.go:171","msg":"trace[2111833778] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2220; }","duration":"11.471092106s","start":"2026-06-02T07:00:25.800106Z","end":"2026-06-02T07:00:37.271198Z","steps":["trace[2111833778] 'agreement among raft nodes before linearized reading' (duration: 11.470920671s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.271226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.252937962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T07:00:37.271266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:25.800084Z","time spent":"11.47117474s","remote":"127.0.0.1:36660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-06-02T07:00:37.271275Z","caller":"traceutil/trace.go:171","msg":"trace[1474661658] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2220; }","duration":"10.253019206s","start":"2026-06-02T07:00:27.018239Z","end":"2026-06-02T07:00:37.271259Z","steps":["trace[1474661658] 'agreement among raft nodes before linearized reading' (duration: 10.252953803s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.271315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:27.018217Z","time spent":"10.253089367s","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:37.27147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.248274208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:37.2715Z","caller":"traceutil/trace.go:171","msg":"trace[1868700674] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2220; }","duration":"12.24836079s","start":"2026-06-02T07:00:25.023134Z","end":"2026-06-02T07:00:37.271494Z","steps":["trace[1868700674] 'agreement among raft nodes before linearized reading' (duration: 12.248314938s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.271521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:25.023098Z","time spent":"12.248418372s","remote":"127.0.0.1:36740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:37.27146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.677331416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.61\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T07:00:37.271686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.212242237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T07:00:37.271701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.468513733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115887"} {"level":"warn","ts":"2026-06-02T07:00:37.271705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.13808149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T07:00:37.27172Z","caller":"traceutil/trace.go:171","msg":"trace[1582480675] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2220; }","duration":"5.212345039s","start":"2026-06-02T07:00:32.059364Z","end":"2026-06-02T07:00:37.271709Z","steps":["trace[1582480675] 'agreement among raft nodes before linearized reading' (duration: 5.212299047s)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:37.271732Z","caller":"traceutil/trace.go:171","msg":"trace[296777076] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2220; }","duration":"11.468573125s","start":"2026-06-02T07:00:25.803152Z","end":"2026-06-02T07:00:37.271725Z","steps":["trace[296777076] 'agreement among raft nodes before linearized reading' (duration: 11.468399699s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.271744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:32.059324Z","time spent":"5.212414092s","remote":"127.0.0.1:57698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:37.271756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:25.80314Z","time spent":"11.468612066s","remote":"127.0.0.1:36654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":115910,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-06-02T07:00:37.271773Z","caller":"traceutil/trace.go:171","msg":"trace[1935747024] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2220; }","duration":"6.138121091s","start":"2026-06-02T07:00:31.13361Z","end":"2026-06-02T07:00:37.271731Z","steps":["trace[1935747024] 'agreement among raft nodes before linearized reading' (duration: 6.138056718s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.271815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:31.133551Z","time spent":"6.138256194s","remote":"127.0.0.1:36754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-06-02T07:00:37.271896Z","caller":"traceutil/trace.go:171","msg":"trace[145783864] range","detail":"{range_begin:/registry/masterleases/199.19.213.61; range_end:; response_count:0; response_revision:2220; }","duration":"9.677701388s","start":"2026-06-02T07:00:27.593993Z","end":"2026-06-02T07:00:37.271694Z","steps":["trace[145783864] 'agreement among raft nodes before linearized reading' (duration: 9.677014998s)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:37.272007Z","caller":"traceutil/trace.go:171","msg":"trace[1861280267] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2220; }","duration":"2.45752892s","start":"2026-06-02T07:00:34.814465Z","end":"2026-06-02T07:00:37.271994Z","steps":["trace[1861280267] 'process raft request' (duration: 2.456180632s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.272037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.605785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:37.272062Z","caller":"traceutil/trace.go:171","msg":"trace[1620925585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2220; }","duration":"688.661707ms","start":"2026-06-02T07:00:36.583393Z","end":"2026-06-02T07:00:37.272055Z","steps":["trace[1620925585] 'agreement among raft nodes before linearized reading' (duration: 688.624016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.272092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:36.583354Z","time spent":"688.733538ms","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T07:00:37.272107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:34.814431Z","time spent":"2.457610872s","remote":"127.0.0.1:36868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T07:00:37.272194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.606143682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:37.272224Z","caller":"traceutil/trace.go:171","msg":"trace[2113189063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2220; }","duration":"1.606206384s","start":"2026-06-02T07:00:35.666009Z","end":"2026-06-02T07:00:37.272216Z","steps":["trace[2113189063] 'agreement among raft nodes before linearized reading' (duration: 1.606166183s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.272241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:35.66599Z","time spent":"1.606248015s","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T07:00:37.272262Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"42b89e871cad5291","error":"lease not found"} {"level":"warn","ts":"2026-06-02T07:00:37.272175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:27.593962Z","time spent":"9.678055337s","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.19.213.61\" "} {"level":"warn","ts":"2026-06-02T07:00:37.27236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.226622291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T07:00:37.272382Z","caller":"traceutil/trace.go:171","msg":"trace[1574192005] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2220; }","duration":"3.226703693s","start":"2026-06-02T07:00:34.045672Z","end":"2026-06-02T07:00:37.272376Z","steps":["trace[1574192005] 'agreement among raft nodes before linearized reading' (duration: 3.226665592s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.272406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:34.045637Z","time spent":"3.226764124s","remote":"127.0.0.1:37090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:37.271143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.356042329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:37.272507Z","caller":"traceutil/trace.go:171","msg":"trace[1862243915] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2220; }","duration":"11.35748267s","start":"2026-06-02T07:00:25.915008Z","end":"2026-06-02T07:00:37.272491Z","steps":["trace[1862243915] 'agreement among raft nodes before linearized reading' (duration: 11.3560516s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.272634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:25.914977Z","time spent":"11.357610114s","remote":"127.0.0.1:57728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:37.272791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.72203486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18b53178c97138b8\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T07:00:37.272798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.878770119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T07:00:37.272811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.711924341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7102"} {"level":"info","ts":"2026-06-02T07:00:37.272821Z","caller":"traceutil/trace.go:171","msg":"trace[924931296] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18b53178c97138b8; range_end:; response_count:0; response_revision:2220; }","duration":"7.722114872s","start":"2026-06-02T07:00:29.550699Z","end":"2026-06-02T07:00:37.272813Z","steps":["trace[924931296] 'agreement among raft nodes before linearized reading' (duration: 7.722064s)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:37.272829Z","caller":"traceutil/trace.go:171","msg":"trace[1352885768] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2220; }","duration":"8.87884568s","start":"2026-06-02T07:00:28.393973Z","end":"2026-06-02T07:00:37.272819Z","steps":["trace[1352885768] 'agreement among raft nodes before linearized reading' (duration: 8.87879918s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.272857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:29.550672Z","time spent":"7.722181223s","remote":"127.0.0.1:36672","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.18b53178c97138b8\" "} {"level":"info","ts":"2026-06-02T07:00:37.272858Z","caller":"traceutil/trace.go:171","msg":"trace[1901967674] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2220; }","duration":"8.712008933s","start":"2026-06-02T07:00:28.560838Z","end":"2026-06-02T07:00:37.272847Z","steps":["trace[1901967674] 'agreement among raft nodes before linearized reading' (duration: 8.71191891s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.272863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:28.393925Z","time spent":"8.878931333s","remote":"127.0.0.1:57708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-02T07:00:37.27289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:28.560813Z","time spent":"8.712071395s","remote":"127.0.0.1:36778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7125,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-06-02T07:00:37.272453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.334745619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T07:00:37.273035Z","caller":"traceutil/trace.go:171","msg":"trace[241744930] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2220; }","duration":"4.335371206s","start":"2026-06-02T07:00:32.937656Z","end":"2026-06-02T07:00:37.273027Z","steps":["trace[241744930] 'agreement among raft nodes before linearized reading' (duration: 4.334766379s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:37.273062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:32.937632Z","time spent":"4.335421867s","remote":"127.0.0.1:36782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-06-02T07:00:37.469771Z","caller":"traceutil/trace.go:171","msg":"trace[1308187702] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"102.350247ms","start":"2026-06-02T07:00:37.367395Z","end":"2026-06-02T07:00:37.469745Z","steps":["trace[1308187702] 'process raft request' (duration: 102.194412ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:38.244076Z","caller":"traceutil/trace.go:171","msg":"trace[1924799995] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"207.608076ms","start":"2026-06-02T07:00:38.036429Z","end":"2026-06-02T07:00:38.244037Z","steps":["trace[1924799995] 'process raft request' (duration: 207.430302ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:38.403761Z","caller":"traceutil/trace.go:171","msg":"trace[1255810389] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"355.35364ms","start":"2026-06-02T07:00:38.048384Z","end":"2026-06-02T07:00:38.403738Z","steps":["trace[1255810389] 'process raft request' (duration: 355.207067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:38.403912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:00:38.048369Z","time spent":"355.465435ms","remote":"127.0.0.1:36778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4409,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:00:38.426075Z","caller":"traceutil/trace.go:171","msg":"trace[1905628988] linearizableReadLoop","detail":"{readStateIndex:2346; appliedIndex:2344; }","duration":"139.372838ms","start":"2026-06-02T07:00:38.286676Z","end":"2026-06-02T07:00:38.426048Z","steps":["trace[1905628988] 'read index received' (duration: 117.004883ms)","trace[1905628988] 'applied index is now lower than readState.Index' (duration: 22.366835ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:00:38.426463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.771599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363682"} {"level":"info","ts":"2026-06-02T07:00:38.426566Z","caller":"traceutil/trace.go:171","msg":"trace[506349189] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2239; }","duration":"139.917413ms","start":"2026-06-02T07:00:38.286626Z","end":"2026-06-02T07:00:38.426543Z","steps":["trace[506349189] 'agreement among raft nodes before linearized reading' (duration: 139.510201ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:38.426195Z","caller":"traceutil/trace.go:171","msg":"trace[1322674889] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"177.916443ms","start":"2026-06-02T07:00:38.248173Z","end":"2026-06-02T07:00:38.42609Z","steps":["trace[1322674889] 'process raft request' (duration: 177.718207ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:38.535325Z","caller":"traceutil/trace.go:171","msg":"trace[126029475] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"103.919551ms","start":"2026-06-02T07:00:38.431382Z","end":"2026-06-02T07:00:38.535301Z","steps":["trace[126029475] 'process raft request' (duration: 37.913246ms)","trace[126029475] 'compare' (duration: 65.85679ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:38.54891Z","caller":"traceutil/trace.go:171","msg":"trace[1590400842] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"112.220527ms","start":"2026-06-02T07:00:38.436671Z","end":"2026-06-02T07:00:38.548892Z","steps":["trace[1590400842] 'process raft request' (duration: 112.106393ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:38.794391Z","caller":"traceutil/trace.go:171","msg":"trace[1988420600] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"158.958185ms","start":"2026-06-02T07:00:38.635409Z","end":"2026-06-02T07:00:38.794367Z","steps":["trace[1988420600] 'process raft request' (duration: 158.799361ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:41.031683Z","caller":"traceutil/trace.go:171","msg":"trace[747394922] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"189.07865ms","start":"2026-06-02T07:00:40.842574Z","end":"2026-06-02T07:00:41.031653Z","steps":["trace[747394922] 'process raft request' (duration: 188.910305ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:41.15732Z","caller":"traceutil/trace.go:171","msg":"trace[2007615491] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"116.555799ms","start":"2026-06-02T07:00:41.040737Z","end":"2026-06-02T07:00:41.157293Z","steps":["trace[2007615491] 'process raft request' (duration: 116.474807ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:41.157386Z","caller":"traceutil/trace.go:171","msg":"trace[2085739341] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"117.191478ms","start":"2026-06-02T07:00:41.04015Z","end":"2026-06-02T07:00:41.157342Z","steps":["trace[2085739341] 'process raft request' (duration: 86.493116ms)","trace[2085739341] 'compare' (duration: 30.327141ms)"],"step_count":2}