{"level":"warn","ts":"2026-05-01T00:51:28.964516Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-01T00:51:28.964619Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-05-01T00:51:28.964799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-01T00:51:28.964815Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-01T00:51:28.964852Z","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-05-01T00:51:28.965995Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-05-01T00:51:28.966076Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-05-01T00:51:28.970919Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.420875ms"} {"level":"info","ts":"2026-05-01T00:51:28.979383Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-05-01T00:51:28.979503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-01T00:51:28.979541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-05-01T00:51:28.979563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-01T00:51:28.979583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-05-01T00:51:28.979642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-05-01T00:51:28.985512Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-01T00:51:28.987373Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-01T00:51:28.98887Z","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-05-01T00:51:28.991825Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-01T00:51:28.992035Z","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-05-01T00:51:28.992178Z","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-05-01T00:51:28.992192Z","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-05-01T00:51:28.992641Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-01T00:51:28.995401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-05-01T00:51:28.995623Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-01T00:51:28.999435Z","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-05-01T00:51:28.999658Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-01T00:51:28.999701Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-01T00:51:28.999735Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-01T00:51:28.999733Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-01T00:51:29.879885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-01T00:51:29.879974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-01T00:51:29.880005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-05-01T00:51:29.88003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-05-01T00:51:29.880048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-01T00:51:29.880062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-05-01T00:51:29.880079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-01T00:51:29.88115Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-05-01T00:51:29.88127Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T00:51:29.881311Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T00:51:29.881399Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T00:51:29.881591Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-01T00:51:29.881621Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-01T00:51:29.882489Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T00:51:29.882565Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T00:51:29.882586Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T00:51:29.883682Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-01T00:51:29.88806Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-05-01T00:51:32.618825Z","caller":"traceutil/trace.go:171","msg":"trace[2019142164] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"120.691158ms","start":"2026-05-01T00:51:32.498081Z","end":"2026-05-01T00:51:32.618773Z","steps":["trace[2019142164] 'process raft request' (duration: 58.576653ms)","trace[2019142164] 'compare' (duration: 61.75463ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:35.38362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999809844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-01T00:51:35.383755Z","caller":"traceutil/trace.go:171","msg":"trace[1195192285] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999999797s","start":"2026-05-01T00:51:33.383731Z","end":"2026-05-01T00:51:35.383731Z","steps":["trace[1195192285] 'agreement among raft nodes before linearized reading' (duration: 1.999842715s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:35.383804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:33.383707Z","time spent":"2.000087888s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/01 00:51:35 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-01T00:51:35.395956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000357668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-01T00:51:35.396084Z","caller":"traceutil/trace.go:171","msg":"trace[2049954700] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000580511s","start":"2026-05-01T00:51:33.395477Z","end":"2026-05-01T00:51:35.396057Z","steps":["trace[2049954700] 'agreement among raft nodes before linearized reading' (duration: 2.000395499s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:35.396234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:33.395453Z","time spent":"2.000670802s","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/01 00:51:35 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-01T00:51:37.496275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000493015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-01T00:51:37.496416Z","caller":"traceutil/trace.go:171","msg":"trace[1176118950] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000682547s","start":"2026-05-01T00:51:35.495687Z","end":"2026-05-01T00:51:37.49637Z","steps":["trace[1176118950] 'agreement among raft nodes before linearized reading' (duration: 2.000523846s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:37.496479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:35.495663Z","time spent":"2.000804888s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/01 00:51:37 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-01T00:51:37.902404Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.561971531s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-01T00:51:38.628383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.075796908s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-05-01T00:51:38.629515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:34.340123Z","time spent":"4.289385676s","remote":"127.0.0.1:49002","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-01T00:51:38.629605Z","caller":"traceutil/trace.go:171","msg":"trace[954860550] linearizableReadLoop","detail":"{readStateIndex:132; appliedIndex:131; }","duration":"5.24575191s","start":"2026-05-01T00:51:33.383776Z","end":"2026-05-01T00:51:38.629528Z","steps":["trace[954860550] 'read index received' (duration: 168.182761ms)","trace[954860550] 'applied index is now lower than readState.Index' (duration: 5.077172874s)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:38.629779Z","caller":"traceutil/trace.go:171","msg":"trace[432735721] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"5.525465318s","start":"2026-05-01T00:51:33.104273Z","end":"2026-05-01T00:51:38.629738Z","steps":["trace[432735721] 'process raft request' (duration: 447.742948ms)","trace[432735721] 'compare' (duration: 5.074700396s)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:38.629919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:33.104195Z","time spent":"5.52564093s","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":709,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T00:51:38.883946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000576103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-01T00:51:38.884034Z","caller":"traceutil/trace.go:171","msg":"trace[1026270861] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000781795s","start":"2026-05-01T00:51:36.88323Z","end":"2026-05-01T00:51:38.884012Z","steps":["trace[1026270861] 'agreement among raft nodes before linearized reading' (duration: 2.000606863s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:38.884073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:36.883205Z","time spent":"2.000859096s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/01 00:51:38 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-01T00:51:39.129742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259565143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T00:51:39.496982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999734379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-01T00:51:39.497139Z","caller":"traceutil/trace.go:171","msg":"trace[1773012824] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000463488s","start":"2026-05-01T00:51:37.496635Z","end":"2026-05-01T00:51:39.497098Z","steps":["trace[1773012824] 'agreement among raft nodes before linearized reading' (duration: 2.000299326s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:39.497203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:37.496599Z","time spent":"2.000589889s","remote":"127.0.0.1:48930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/01 00:51:39 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-01T00:51:39.630464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259565143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T00:51:40.131692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259565143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T00:51:40.632825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259565143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T00:51:41.133758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259565143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T00:51:41.384299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000255051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-01T00:51:41.384382Z","caller":"traceutil/trace.go:171","msg":"trace[66881698] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000406972s","start":"2026-05-01T00:51:39.383957Z","end":"2026-05-01T00:51:41.384364Z","steps":["trace[66881698] 'agreement among raft nodes before linearized reading' (duration: 2.000299531s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:41.384442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:39.38393Z","time spent":"2.000496014s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/01 00:51:41 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-01T00:51:41.597077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000531187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-01T00:51:41.597171Z","caller":"traceutil/trace.go:171","msg":"trace[1420623255] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000704509s","start":"2026-05-01T00:51:39.596448Z","end":"2026-05-01T00:51:41.597152Z","steps":["trace[1420623255] 'agreement among raft nodes before linearized reading' (duration: 2.000564167s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:41.597218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:39.59643Z","time spent":"2.000779169s","remote":"127.0.0.1:48930","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-05-01T00:51:41.634453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259565143,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T00:51:42.118312Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.215645741s","expected-duration":"1s"} {"level":"info","ts":"2026-05-01T00:51:42.118935Z","caller":"traceutil/trace.go:171","msg":"trace[1766033681] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"5.071827642s","start":"2026-05-01T00:51:37.047082Z","end":"2026-05-01T00:51:42.118909Z","steps":["trace[1766033681] 'process raft request' (duration: 5.071793372s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:42.119255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:37.047053Z","time spent":"5.072028504s","remote":"127.0.0.1:49090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:51:42.119362Z","caller":"traceutil/trace.go:171","msg":"trace[1966915797] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"5.662811063s","start":"2026-05-01T00:51:36.456533Z","end":"2026-05-01T00:51:42.119344Z","steps":["trace[1966915797] 'process raft request' (duration: 5.662309117s)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:42.119355Z","caller":"traceutil/trace.go:171","msg":"trace[1499002761] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"6.368850225s","start":"2026-05-01T00:51:35.750411Z","end":"2026-05-01T00:51:42.119262Z","steps":["trace[1499002761] 'process raft request' (duration: 6.368240758s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:42.119467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:36.456505Z","time spent":"5.662906403s","remote":"127.0.0.1:49090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T00:51:42.119536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:35.750379Z","time spent":"6.369086188s","remote":"127.0.0.1:49090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:51:42.160502Z","caller":"traceutil/trace.go:171","msg":"trace[1959357748] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"3.510725025s","start":"2026-05-01T00:51:38.649727Z","end":"2026-05-01T00:51:42.160452Z","steps":["trace[1959357748] 'process raft request' (duration: 3.510523073s)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:42.160533Z","caller":"traceutil/trace.go:171","msg":"trace[1385718924] linearizableReadLoop","detail":"{readStateIndex:138; appliedIndex:133; }","duration":"3.530843221s","start":"2026-05-01T00:51:38.629666Z","end":"2026-05-01T00:51:42.160509Z","steps":["trace[1385718924] 'read index received' (duration: 3.488862824s)","trace[1385718924] 'applied index is now lower than readState.Index' (duration: 41.979337ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:42.160645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:38.649704Z","time spent":"3.510880446s","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:51:42.160841Z","caller":"traceutil/trace.go:171","msg":"trace[2115567517] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"662.793854ms","start":"2026-05-01T00:51:41.498034Z","end":"2026-05-01T00:51:42.160828Z","steps":["trace[2115567517] 'process raft request' (duration: 662.321039ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:42.160948Z","caller":"traceutil/trace.go:171","msg":"trace[190295504] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"443.604821ms","start":"2026-05-01T00:51:41.717325Z","end":"2026-05-01T00:51:42.16093Z","steps":["trace[190295504] 'process raft request' (duration: 443.135776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:42.161042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.64691911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:51:42.161141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:41.498015Z","time spent":"662.863705ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:51:42.161159Z","caller":"traceutil/trace.go:171","msg":"trace[790298920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:134; }","duration":"6.647075822s","start":"2026-05-01T00:51:35.514049Z","end":"2026-05-01T00:51:42.161125Z","steps":["trace[790298920] 'agreement among raft nodes before linearized reading' (duration: 6.64684434s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:42.161213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:35.514027Z","time spent":"6.647174953s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-01T00:51:42.161298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.44864629s","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-05-01T00:51:42.161336Z","caller":"traceutil/trace.go:171","msg":"trace[1286329048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:134; }","duration":"7.44872479s","start":"2026-05-01T00:51:34.712601Z","end":"2026-05-01T00:51:42.161326Z","steps":["trace[1286329048] 'agreement among raft nodes before linearized reading' (duration: 7.44866695s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:42.161369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:34.712572Z","time spent":"7.448790311s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-01T00:51:42.16143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.508744751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:51:42.161457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:41.71731Z","time spent":"443.688052ms","remote":"127.0.0.1:49086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:51:42.1615Z","caller":"traceutil/trace.go:171","msg":"trace[1264773671] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:0; response_revision:134; }","duration":"3.508804473s","start":"2026-05-01T00:51:38.652668Z","end":"2026-05-01T00:51:42.161473Z","steps":["trace[1264773671] 'agreement among raft nodes before linearized reading' (duration: 3.508719512s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:42.161464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.139573524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2918"} {"level":"warn","ts":"2026-05-01T00:51:42.161578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.757645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:51:42.161612Z","caller":"traceutil/trace.go:171","msg":"trace[857317400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:134; }","duration":"466.788966ms","start":"2026-05-01T00:51:41.694815Z","end":"2026-05-01T00:51:42.161604Z","steps":["trace[857317400] 'agreement among raft nodes before linearized reading' (duration: 466.745615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:42.16164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:41.694759Z","time spent":"466.876746ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-01T00:51:42.161648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.119803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:51:42.161686Z","caller":"traceutil/trace.go:171","msg":"trace[721957839] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:134; }","duration":"762.184533ms","start":"2026-05-01T00:51:41.39949Z","end":"2026-05-01T00:51:42.161675Z","steps":["trace[721957839] 'agreement among raft nodes before linearized reading' (duration: 762.126853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:42.161728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:41.399477Z","time spent":"762.242784ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-01T00:51:42.161708Z","caller":"traceutil/trace.go:171","msg":"trace[657877930] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:134; }","duration":"4.139745455s","start":"2026-05-01T00:51:38.021842Z","end":"2026-05-01T00:51:42.161587Z","steps":["trace[657877930] 'agreement among raft nodes before linearized reading' (duration: 4.139466502s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:42.161857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:38.021822Z","time spent":"4.140021328s","remote":"127.0.0.1:49090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2942,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-01T00:51:42.161543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:38.652657Z","time spent":"3.508877804s","remote":"127.0.0.1:49260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" "} {"level":"info","ts":"2026-05-01T00:51:42.279904Z","caller":"traceutil/trace.go:171","msg":"trace[87188710] transaction","detail":"{read_only:false; response_revision:137; number_of_response:1; }","duration":"109.564782ms","start":"2026-05-01T00:51:42.170317Z","end":"2026-05-01T00:51:42.279882Z","steps":["trace[87188710] 'process raft request' (duration: 109.429591ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:42.279905Z","caller":"traceutil/trace.go:171","msg":"trace[1949499463] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"112.415433ms","start":"2026-05-01T00:51:42.167455Z","end":"2026-05-01T00:51:42.27987Z","steps":["trace[1949499463] 'process raft request' (duration: 81.514557ms)","trace[1949499463] 'compare' (duration: 30.560853ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:42.280368Z","caller":"traceutil/trace.go:171","msg":"trace[1253866178] transaction","detail":"{read_only:false; response_revision:138; number_of_response:1; }","duration":"109.769525ms","start":"2026-05-01T00:51:42.170539Z","end":"2026-05-01T00:51:42.280309Z","steps":["trace[1253866178] 'process raft request' (duration: 109.255269ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:42.28113Z","caller":"traceutil/trace.go:171","msg":"trace[452741934] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"101.343183ms","start":"2026-05-01T00:51:42.179769Z","end":"2026-05-01T00:51:42.281112Z","steps":["trace[452741934] 'process raft request' (duration: 100.075549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:43.105497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.657709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:43.105694Z","caller":"traceutil/trace.go:171","msg":"trace[413179916] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"271.357804ms","start":"2026-05-01T00:51:42.834302Z","end":"2026-05-01T00:51:43.10566Z","steps":["trace[413179916] 'process raft request' (duration: 134.441312ms)","trace[413179916] 'compare' (duration: 136.400756ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:43.277239Z","caller":"traceutil/trace.go:171","msg":"trace[16927926] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"128.108173ms","start":"2026-05-01T00:51:43.149078Z","end":"2026-05-01T00:51:43.277186Z","steps":["trace[16927926] 'process raft request' (duration: 59.815566ms)","trace[16927926] 'compare' (duration: 67.786452ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:43.651655Z","caller":"traceutil/trace.go:171","msg":"trace[554957638] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"195.433771ms","start":"2026-05-01T00:51:43.456189Z","end":"2026-05-01T00:51:43.651623Z","steps":["trace[554957638] 'process raft request' (duration: 132.661413ms)","trace[554957638] 'compare' (duration: 62.623527ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:43.831887Z","caller":"traceutil/trace.go:171","msg":"trace[447030446] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"110.925588ms","start":"2026-05-01T00:51:43.720929Z","end":"2026-05-01T00:51:43.831855Z","steps":["trace[447030446] 'process raft request' (duration: 35.738016ms)","trace[447030446] 'compare' (duration: 75.023181ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:44.050617Z","caller":"traceutil/trace.go:171","msg":"trace[1490866594] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"120.594499ms","start":"2026-05-01T00:51:43.929984Z","end":"2026-05-01T00:51:44.050579Z","steps":["trace[1490866594] 'process raft request' (duration: 68.758783ms)","trace[1490866594] 'compare' (duration: 51.664954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:44.284226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.173596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:44.284364Z","caller":"traceutil/trace.go:171","msg":"trace[1213338197] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"140.227344ms","start":"2026-05-01T00:51:44.144112Z","end":"2026-05-01T00:51:44.284339Z","steps":["trace[1213338197] 'process raft request' (duration: 23.852756ms)","trace[1213338197] 'compare' (duration: 115.984194ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:44.410894Z","caller":"traceutil/trace.go:171","msg":"trace[433605109] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"116.143945ms","start":"2026-05-01T00:51:44.294721Z","end":"2026-05-01T00:51:44.410865Z","steps":["trace[433605109] 'read index received' (duration: 107.561673ms)","trace[433605109] 'applied index is now lower than readState.Index' (duration: 8.581072ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:44.411043Z","caller":"traceutil/trace.go:171","msg":"trace[997615101] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"118.474691ms","start":"2026-05-01T00:51:44.292524Z","end":"2026-05-01T00:51:44.410999Z","steps":["trace[997615101] 'process raft request' (duration: 109.858568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:44.411817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.802393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:51:44.411882Z","caller":"traceutil/trace.go:171","msg":"trace[434491652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:248; }","duration":"117.212747ms","start":"2026-05-01T00:51:44.294652Z","end":"2026-05-01T00:51:44.411865Z","steps":["trace[434491652] 'agreement among raft nodes before linearized reading' (duration: 116.395228ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:44.900654Z","caller":"traceutil/trace.go:171","msg":"trace[1233093459] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"186.809253ms","start":"2026-05-01T00:51:44.713817Z","end":"2026-05-01T00:51:44.900626Z","steps":["trace[1233093459] 'process raft request' (duration: 135.984708ms)","trace[1233093459] 'compare' (duration: 50.516472ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:44.900787Z","caller":"traceutil/trace.go:171","msg":"trace[1469285687] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"103.578821ms","start":"2026-05-01T00:51:44.797179Z","end":"2026-05-01T00:51:44.900758Z","steps":["trace[1469285687] 'process raft request' (duration: 103.358478ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:44.900988Z","caller":"traceutil/trace.go:171","msg":"trace[1268237064] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"100.566839ms","start":"2026-05-01T00:51:44.800388Z","end":"2026-05-01T00:51:44.900955Z","steps":["trace[1268237064] 'process raft request' (duration: 100.322566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:46.026125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.121595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:46.026408Z","caller":"traceutil/trace.go:171","msg":"trace[204576557] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:289; }","duration":"272.529661ms","start":"2026-05-01T00:51:45.753852Z","end":"2026-05-01T00:51:46.026382Z","steps":["trace[204576557] 'read index received' (duration: 48.087702ms)","trace[204576557] 'applied index is now lower than readState.Index' (duration: 224.439199ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:46.026673Z","caller":"traceutil/trace.go:171","msg":"trace[425974994] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"276.412393ms","start":"2026-05-01T00:51:45.750213Z","end":"2026-05-01T00:51:46.026625Z","steps":["trace[425974994] 'process raft request' (duration: 51.706671ms)","trace[425974994] 'compare' (duration: 223.959314ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:46.027092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.194098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-05-01T00:51:46.027234Z","caller":"traceutil/trace.go:171","msg":"trace[1903016943] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:284; }","duration":"273.3594ms","start":"2026-05-01T00:51:45.753846Z","end":"2026-05-01T00:51:46.027205Z","steps":["trace[1903016943] 'agreement among raft nodes before linearized reading' (duration: 272.847074ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:46.0586Z","caller":"traceutil/trace.go:171","msg":"trace[208054868] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"302.61708ms","start":"2026-05-01T00:51:45.755961Z","end":"2026-05-01T00:51:46.058579Z","steps":["trace[208054868] 'process raft request' (duration: 302.514699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:46.058728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:45.755948Z","time spent":"302.719151ms","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T00:51:46.425402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.352526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:46.425537Z","caller":"traceutil/trace.go:171","msg":"trace[612745082] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"307.008468ms","start":"2026-05-01T00:51:46.118505Z","end":"2026-05-01T00:51:46.425513Z","steps":["trace[612745082] 'process raft request' (duration: 96.432181ms)","trace[612745082] 'compare' (duration: 210.217513ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:46.425594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:46.11849Z","time spent":"307.083119ms","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:51:46.476074Z","caller":"traceutil/trace.go:171","msg":"trace[876628625] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"352.971624ms","start":"2026-05-01T00:51:46.123069Z","end":"2026-05-01T00:51:46.47604Z","steps":["trace[876628625] 'process raft request' (duration: 352.753141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:46.476255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:46.123055Z","time spent":"353.102605ms","remote":"127.0.0.1:49090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:51:46.745118Z","caller":"traceutil/trace.go:171","msg":"trace[826439017] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"261.605397ms","start":"2026-05-01T00:51:46.483484Z","end":"2026-05-01T00:51:46.74509Z","steps":["trace[826439017] 'process raft request' (duration: 202.34023ms)","trace[826439017] 'compare' (duration: 58.957194ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:46.745161Z","caller":"traceutil/trace.go:171","msg":"trace[1712138232] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"216.815653ms","start":"2026-05-01T00:51:46.528332Z","end":"2026-05-01T00:51:46.745148Z","steps":["trace[1712138232] 'process raft request' (duration: 216.771723ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:46.745179Z","caller":"traceutil/trace.go:171","msg":"trace[1613273926] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"256.829575ms","start":"2026-05-01T00:51:46.488331Z","end":"2026-05-01T00:51:46.745161Z","steps":["trace[1613273926] 'process raft request' (duration: 256.687784ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:46.745185Z","caller":"traceutil/trace.go:171","msg":"trace[1414505527] linearizableReadLoop","detail":"{readStateIndex:297; appliedIndex:295; }","duration":"221.898537ms","start":"2026-05-01T00:51:46.523253Z","end":"2026-05-01T00:51:46.745152Z","steps":["trace[1414505527] 'read index received' (duration: 162.752012ms)","trace[1414505527] 'applied index is now lower than readState.Index' (duration: 59.142605ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:46.745589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.654421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3689"} {"level":"warn","ts":"2026-05-01T00:51:46.745635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.135801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:51:46.745647Z","caller":"traceutil/trace.go:171","msg":"trace[722070113] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:292; }","duration":"214.749761ms","start":"2026-05-01T00:51:46.530877Z","end":"2026-05-01T00:51:46.745626Z","steps":["trace[722070113] 'agreement among raft nodes before linearized reading' (duration: 214.65842ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:46.745663Z","caller":"traceutil/trace.go:171","msg":"trace[1909679002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:292; }","duration":"260.196622ms","start":"2026-05-01T00:51:46.485457Z","end":"2026-05-01T00:51:46.745654Z","steps":["trace[1909679002] 'agreement among raft nodes before linearized reading' (duration: 259.830418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:47.087115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.965733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:47.087271Z","caller":"traceutil/trace.go:171","msg":"trace[179487954] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"264.029118ms","start":"2026-05-01T00:51:46.823218Z","end":"2026-05-01T00:51:47.087247Z","steps":["trace[179487954] 'read index received' (duration: 39.784451ms)","trace[179487954] 'applied index is now lower than readState.Index' (duration: 224.243077ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:47.087302Z","caller":"traceutil/trace.go:171","msg":"trace[1505160021] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"328.759782ms","start":"2026-05-01T00:51:46.758502Z","end":"2026-05-01T00:51:47.087262Z","steps":["trace[1505160021] 'process raft request' (duration: 104.534796ms)","trace[1505160021] 'compare' (duration: 223.423488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:47.087419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.21156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-ol3vsf\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:51:47.087558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:46.75849Z","time spent":"328.933374ms","remote":"127.0.0.1:49090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:51:47.087616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.337237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:51:47.08784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.561085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-01T00:51:47.087888Z","caller":"traceutil/trace.go:171","msg":"trace[1948283931] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:294; }","duration":"257.61146ms","start":"2026-05-01T00:51:46.830178Z","end":"2026-05-01T00:51:47.087789Z","steps":["trace[1948283931] 'agreement among raft nodes before linearized reading' (duration: 257.338567ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:47.087935Z","caller":"traceutil/trace.go:171","msg":"trace[286101454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:294; }","duration":"181.692497ms","start":"2026-05-01T00:51:46.906217Z","end":"2026-05-01T00:51:47.08791Z","steps":["trace[286101454] 'agreement among raft nodes before linearized reading' (duration: 181.553355ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:47.087588Z","caller":"traceutil/trace.go:171","msg":"trace[1929705237] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-ol3vsf; range_end:; response_count:0; response_revision:294; }","duration":"264.435801ms","start":"2026-05-01T00:51:46.823133Z","end":"2026-05-01T00:51:47.087569Z","steps":["trace[1929705237] 'agreement among raft nodes before linearized reading' (duration: 264.236139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:47.321779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.826774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:47.322131Z","caller":"traceutil/trace.go:171","msg":"trace[691116166] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"193.828387ms","start":"2026-05-01T00:51:47.128284Z","end":"2026-05-01T00:51:47.322113Z","steps":["trace[691116166] 'process raft request' (duration: 193.738786ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:47.322123Z","caller":"traceutil/trace.go:171","msg":"trace[1983945389] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"229.916526ms","start":"2026-05-01T00:51:47.092175Z","end":"2026-05-01T00:51:47.322091Z","steps":["trace[1983945389] 'process raft request' (duration: 101.689218ms)","trace[1983945389] 'compare' (duration: 127.692762ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:47.322243Z","caller":"traceutil/trace.go:171","msg":"trace[645856412] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"227.568981ms","start":"2026-05-01T00:51:47.094651Z","end":"2026-05-01T00:51:47.32222Z","steps":["trace[645856412] 'process raft request' (duration: 227.229128ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:47.322385Z","caller":"traceutil/trace.go:171","msg":"trace[820016673] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"223.490749ms","start":"2026-05-01T00:51:47.098826Z","end":"2026-05-01T00:51:47.322316Z","steps":["trace[820016673] 'process raft request' (duration: 223.108965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:48.09298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.956004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:51:48.093087Z","caller":"traceutil/trace.go:171","msg":"trace[2075010324] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:326; }","duration":"137.134335ms","start":"2026-05-01T00:51:47.955928Z","end":"2026-05-01T00:51:48.093062Z","steps":["trace[2075010324] 'agreement among raft nodes before linearized reading' (duration: 136.944063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:48.591043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.006371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:48.591161Z","caller":"traceutil/trace.go:171","msg":"trace[1972700640] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"441.085615ms","start":"2026-05-01T00:51:48.15005Z","end":"2026-05-01T00:51:48.591135Z","steps":["trace[1972700640] 'process raft request' (duration: 187.915132ms)","trace[1972700640] 'compare' (duration: 252.758229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:48.591215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:48.15003Z","time spent":"441.164917ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1933,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:51:48.592287Z","caller":"traceutil/trace.go:171","msg":"trace[1121332128] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"421.479151ms","start":"2026-05-01T00:51:48.170724Z","end":"2026-05-01T00:51:48.592203Z","steps":["trace[1121332128] 'process raft request' (duration: 421.30687ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:48.592246Z","caller":"traceutil/trace.go:171","msg":"trace[363645860] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"421.717413ms","start":"2026-05-01T00:51:48.170473Z","end":"2026-05-01T00:51:48.592191Z","steps":["trace[363645860] 'process raft request' (duration: 420.947785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:48.592511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:48.170449Z","time spent":"421.993096ms","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T00:51:48.592523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:48.170711Z","time spent":"421.716174ms","remote":"127.0.0.1:49002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:51:48.593829Z","caller":"traceutil/trace.go:171","msg":"trace[752905661] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"417.121455ms","start":"2026-05-01T00:51:48.176668Z","end":"2026-05-01T00:51:48.593789Z","steps":["trace[752905661] 'process raft request' (duration: 415.504968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:48.594079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:48.176656Z","time spent":"417.235807ms","remote":"127.0.0.1:49086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:51:48.860718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.419408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:48.86083Z","caller":"traceutil/trace.go:171","msg":"trace[2110962564] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"181.901639ms","start":"2026-05-01T00:51:48.678911Z","end":"2026-05-01T00:51:48.860813Z","steps":["trace[2110962564] 'read index received' (duration: 37.317639ms)","trace[2110962564] 'applied index is now lower than readState.Index' (duration: 144.58304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:48.860956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.897098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-01T00:51:48.860986Z","caller":"traceutil/trace.go:171","msg":"trace[1217478950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:336; }","duration":"111.97617ms","start":"2026-05-01T00:51:48.749Z","end":"2026-05-01T00:51:48.860976Z","steps":["trace[1217478950] 'agreement among raft nodes before linearized reading' (duration: 111.906199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:48.860983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.02608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:51:48.861115Z","caller":"traceutil/trace.go:171","msg":"trace[1273120824] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:336; }","duration":"182.223683ms","start":"2026-05-01T00:51:48.678878Z","end":"2026-05-01T00:51:48.861102Z","steps":["trace[1273120824] 'agreement among raft nodes before linearized reading' (duration: 181.99485ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:48.860888Z","caller":"traceutil/trace.go:171","msg":"trace[2097895103] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"183.338384ms","start":"2026-05-01T00:51:48.677503Z","end":"2026-05-01T00:51:48.860841Z","steps":["trace[2097895103] 'process raft request' (duration: 38.727884ms)","trace[2097895103] 'compare' (duration: 144.213086ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:49.221649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.70341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:49.221818Z","caller":"traceutil/trace.go:171","msg":"trace[63433412] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"355.863962ms","start":"2026-05-01T00:51:48.865936Z","end":"2026-05-01T00:51:49.2218Z","steps":["trace[63433412] 'process raft request' (duration: 355.802832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.221901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:48.865929Z","time spent":"355.927823ms","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:51:49.221904Z","caller":"traceutil/trace.go:171","msg":"trace[966434602] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"356.874763ms","start":"2026-05-01T00:51:48.864968Z","end":"2026-05-01T00:51:49.221842Z","steps":["trace[966434602] 'process raft request' (duration: 188.9546ms)","trace[966434602] 'compare' (duration: 167.457678ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:49.222111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:48.864947Z","time spent":"357.078415ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:51:49.23362Z","caller":"traceutil/trace.go:171","msg":"trace[1103012104] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:348; }","duration":"294.865744ms","start":"2026-05-01T00:51:48.938743Z","end":"2026-05-01T00:51:49.233609Z","steps":["trace[1103012104] 'read index received' (duration: 115.158419ms)","trace[1103012104] 'applied index is now lower than readState.Index' (duration: 179.706475ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:49.233641Z","caller":"traceutil/trace.go:171","msg":"trace[206563827] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"337.222796ms","start":"2026-05-01T00:51:48.896402Z","end":"2026-05-01T00:51:49.233625Z","steps":["trace[206563827] 'process raft request' (duration: 337.062314ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:49.233641Z","caller":"traceutil/trace.go:171","msg":"trace[1502869385] transaction","detail":"{read_only:false; number_of_response:0; response_revision:340; }","duration":"294.765843ms","start":"2026-05-01T00:51:48.938862Z","end":"2026-05-01T00:51:49.233628Z","steps":["trace[1502869385] 'process raft request' (duration: 294.654172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.233773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.024515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-05-01T00:51:49.233806Z","caller":"traceutil/trace.go:171","msg":"trace[1906486] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:340; }","duration":"295.089307ms","start":"2026-05-01T00:51:48.938707Z","end":"2026-05-01T00:51:49.233796Z","steps":["trace[1906486] 'agreement among raft nodes before linearized reading' (duration: 295.014596ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:49.233873Z","caller":"traceutil/trace.go:171","msg":"trace[812435347] transaction","detail":"{read_only:false; number_of_response:0; response_revision:340; }","duration":"294.776293ms","start":"2026-05-01T00:51:48.93898Z","end":"2026-05-01T00:51:49.233756Z","steps":["trace[812435347] 'process raft request' (duration: 294.583431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.234174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:48.896385Z","time spent":"337.287417ms","remote":"127.0.0.1:49240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":302,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:51:49.233887Z","caller":"traceutil/trace.go:171","msg":"trace[2142824268] transaction","detail":"{read_only:false; number_of_response:0; response_revision:340; }","duration":"294.4618ms","start":"2026-05-01T00:51:48.939272Z","end":"2026-05-01T00:51:49.233734Z","steps":["trace[2142824268] 'process raft request' (duration: 294.312208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.516623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.291345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:49.516746Z","caller":"traceutil/trace.go:171","msg":"trace[294636996] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"211.248281ms","start":"2026-05-01T00:51:49.305479Z","end":"2026-05-01T00:51:49.516727Z","steps":["trace[294636996] 'process raft request' (duration: 18.778924ms)","trace[294636996] 'compare' (duration: 192.104554ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:49.51732Z","caller":"traceutil/trace.go:171","msg":"trace[796050864] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:358; }","duration":"190.941881ms","start":"2026-05-01T00:51:49.326369Z","end":"2026-05-01T00:51:49.517311Z","steps":["trace[796050864] 'read index received' (duration: 190.82184ms)","trace[796050864] 'applied index is now lower than readState.Index' (duration: 119.381µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:49.517417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.038412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-01T00:51:49.517434Z","caller":"traceutil/trace.go:171","msg":"trace[752128884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:345; }","duration":"191.099853ms","start":"2026-05-01T00:51:49.326327Z","end":"2026-05-01T00:51:49.517427Z","steps":["trace[752128884] 'agreement among raft nodes before linearized reading' (duration: 191.024512ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:49.517527Z","caller":"traceutil/trace.go:171","msg":"trace[1910212328] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"208.759896ms","start":"2026-05-01T00:51:49.308703Z","end":"2026-05-01T00:51:49.517463Z","steps":["trace[1910212328] 'process raft request' (duration: 208.549944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.748749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.979585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:49.748922Z","caller":"traceutil/trace.go:171","msg":"trace[155874204] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"219.148124ms","start":"2026-05-01T00:51:49.529735Z","end":"2026-05-01T00:51:49.748883Z","steps":["trace[155874204] 'process raft request' (duration: 99.934367ms)","trace[155874204] 'compare' (duration: 118.837013ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:49.748869Z","caller":"traceutil/trace.go:171","msg":"trace[1112523584] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"185.163142ms","start":"2026-05-01T00:51:49.563688Z","end":"2026-05-01T00:51:49.748851Z","steps":["trace[1112523584] 'read index received' (duration: 65.876684ms)","trace[1112523584] 'applied index is now lower than readState.Index' (duration: 119.282278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:49.749077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.373524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-01T00:51:49.749257Z","caller":"traceutil/trace.go:171","msg":"trace[356960093] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:348; }","duration":"185.598046ms","start":"2026-05-01T00:51:49.563641Z","end":"2026-05-01T00:51:49.749239Z","steps":["trace[356960093] 'agreement among raft nodes before linearized reading' (duration: 185.339563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.749391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.108012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-05-01T00:51:49.749463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.100745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-01T00:51:49.749477Z","caller":"traceutil/trace.go:171","msg":"trace[1310309710] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:348; }","duration":"135.224883ms","start":"2026-05-01T00:51:49.614233Z","end":"2026-05-01T00:51:49.749458Z","steps":["trace[1310309710] 'agreement among raft nodes before linearized reading' (duration: 135.049831ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:49.749543Z","caller":"traceutil/trace.go:171","msg":"trace[820639932] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:348; }","duration":"144.198785ms","start":"2026-05-01T00:51:49.605305Z","end":"2026-05-01T00:51:49.749504Z","steps":["trace[820639932] 'agreement among raft nodes before linearized reading' (duration: 144.029154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.749581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.823665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-01T00:51:49.749661Z","caller":"traceutil/trace.go:171","msg":"trace[115836782] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:348; }","duration":"152.883886ms","start":"2026-05-01T00:51:49.596731Z","end":"2026-05-01T00:51:49.749615Z","steps":["trace[115836782] 'agreement among raft nodes before linearized reading' (duration: 152.721024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.749626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.244081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-01T00:51:49.74975Z","caller":"traceutil/trace.go:171","msg":"trace[1890139835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"155.325871ms","start":"2026-05-01T00:51:49.594369Z","end":"2026-05-01T00:51:49.749695Z","steps":["trace[1890139835] 'agreement among raft nodes before linearized reading' (duration: 155.21572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.749378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.48528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-01T00:51:49.749941Z","caller":"traceutil/trace.go:171","msg":"trace[1355422846] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"173.075726ms","start":"2026-05-01T00:51:49.576849Z","end":"2026-05-01T00:51:49.749925Z","steps":["trace[1355422846] 'agreement among raft nodes before linearized reading' (duration: 172.388829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.749983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.25142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-01T00:51:49.75004Z","caller":"traceutil/trace.go:171","msg":"trace[679333528] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:348; }","duration":"128.324581ms","start":"2026-05-01T00:51:49.621699Z","end":"2026-05-01T00:51:49.750024Z","steps":["trace[679333528] 'agreement among raft nodes before linearized reading' (duration: 128.211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:49.750361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.453018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-01T00:51:49.75041Z","caller":"traceutil/trace.go:171","msg":"trace[107522669] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:348; }","duration":"141.577859ms","start":"2026-05-01T00:51:49.608819Z","end":"2026-05-01T00:51:49.750397Z","steps":["trace[107522669] 'agreement among raft nodes before linearized reading' (duration: 141.051063ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.014201Z","caller":"traceutil/trace.go:171","msg":"trace[678927533] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"252.772692ms","start":"2026-05-01T00:51:49.761391Z","end":"2026-05-01T00:51:50.014163Z","steps":["trace[678927533] 'process raft request' (duration: 252.64473ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.014259Z","caller":"traceutil/trace.go:171","msg":"trace[519794332] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:362; }","duration":"249.294916ms","start":"2026-05-01T00:51:49.764948Z","end":"2026-05-01T00:51:50.014243Z","steps":["trace[519794332] 'read index received' (duration: 36.208566ms)","trace[519794332] 'applied index is now lower than readState.Index' (duration: 213.08577ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:50.014311Z","caller":"traceutil/trace.go:171","msg":"trace[1755016690] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"253.893974ms","start":"2026-05-01T00:51:49.760341Z","end":"2026-05-01T00:51:50.014235Z","steps":["trace[1755016690] 'process raft request' (duration: 218.559698ms)","trace[1755016690] 'compare' (duration: 35.003582ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:50.014504Z","caller":"traceutil/trace.go:171","msg":"trace[192250760] transaction","detail":"{read_only:false; number_of_response:1; response_revision:356; }","duration":"248.956362ms","start":"2026-05-01T00:51:49.765519Z","end":"2026-05-01T00:51:50.014475Z","steps":["trace[192250760] 'process raft request' (duration: 248.659059ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.014577Z","caller":"traceutil/trace.go:171","msg":"trace[427902691] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"248.196955ms","start":"2026-05-01T00:51:49.766373Z","end":"2026-05-01T00:51:50.01457Z","steps":["trace[427902691] 'process raft request' (duration: 247.823841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:50.014587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.614389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4129"} {"level":"info","ts":"2026-05-01T00:51:50.01465Z","caller":"traceutil/trace.go:171","msg":"trace[1631197824] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:358; }","duration":"249.69778ms","start":"2026-05-01T00:51:49.764934Z","end":"2026-05-01T00:51:50.014632Z","steps":["trace[1631197824] 'agreement among raft nodes before linearized reading' (duration: 249.549378ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.014728Z","caller":"traceutil/trace.go:171","msg":"trace[1751605652] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"248.078943ms","start":"2026-05-01T00:51:49.766635Z","end":"2026-05-01T00:51:50.014714Z","steps":["trace[1751605652] 'process raft request' (duration: 247.583588ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.014768Z","caller":"traceutil/trace.go:171","msg":"trace[1848360695] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"251.198666ms","start":"2026-05-01T00:51:49.763563Z","end":"2026-05-01T00:51:50.014762Z","steps":["trace[1848360695] 'process raft request' (duration: 250.548229ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.014773Z","caller":"traceutil/trace.go:171","msg":"trace[609041867] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"249.371117ms","start":"2026-05-01T00:51:49.765395Z","end":"2026-05-01T00:51:50.014766Z","steps":["trace[609041867] 'process raft request' (duration: 248.766641ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.014841Z","caller":"traceutil/trace.go:171","msg":"trace[1536544509] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"253.206097ms","start":"2026-05-01T00:51:49.76163Z","end":"2026-05-01T00:51:50.014837Z","steps":["trace[1536544509] 'process raft request' (duration: 252.433129ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.014853Z","caller":"traceutil/trace.go:171","msg":"trace[242928598] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"249.546419ms","start":"2026-05-01T00:51:49.765298Z","end":"2026-05-01T00:51:50.014844Z","steps":["trace[242928598] 'process raft request' (duration: 248.838391ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.015001Z","caller":"traceutil/trace.go:171","msg":"trace[1830337190] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"251.852082ms","start":"2026-05-01T00:51:49.76313Z","end":"2026-05-01T00:51:50.014982Z","steps":["trace[1830337190] 'process raft request' (duration: 250.958283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:50.015591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.606233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-01T00:51:50.015654Z","caller":"traceutil/trace.go:171","msg":"trace[816965447] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:359; }","duration":"209.699835ms","start":"2026-05-01T00:51:49.805938Z","end":"2026-05-01T00:51:50.015638Z","steps":["trace[816965447] 'agreement among raft nodes before linearized reading' (duration: 209.549523ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.015857Z","caller":"traceutil/trace.go:171","msg":"trace[226081623] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"209.428582ms","start":"2026-05-01T00:51:49.806419Z","end":"2026-05-01T00:51:50.015848Z","steps":["trace[226081623] 'process raft request' (duration: 207.873566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:50.015933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.274393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-01T00:51:50.015987Z","caller":"traceutil/trace.go:171","msg":"trace[1930101952] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:359; }","duration":"109.350273ms","start":"2026-05-01T00:51:49.906623Z","end":"2026-05-01T00:51:50.015974Z","steps":["trace[1930101952] 'agreement among raft nodes before linearized reading' (duration: 109.257342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:50.016173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.756106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-01T00:51:50.016221Z","caller":"traceutil/trace.go:171","msg":"trace[235684844] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:359; }","duration":"159.840608ms","start":"2026-05-01T00:51:49.856368Z","end":"2026-05-01T00:51:50.016209Z","steps":["trace[235684844] 'agreement among raft nodes before linearized reading' (duration: 159.765817ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.191435Z","caller":"traceutil/trace.go:171","msg":"trace[931123844] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"114.42922ms","start":"2026-05-01T00:51:50.076978Z","end":"2026-05-01T00:51:50.191407Z","steps":["trace[931123844] 'process raft request' (duration: 72.488448ms)","trace[931123844] 'compare' (duration: 41.69476ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:50.202627Z","caller":"traceutil/trace.go:171","msg":"trace[1487187265] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"124.241291ms","start":"2026-05-01T00:51:50.078364Z","end":"2026-05-01T00:51:50.202606Z","steps":["trace[1487187265] 'process raft request' (duration: 124.035939ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:50.202897Z","caller":"traceutil/trace.go:171","msg":"trace[1273678187] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"118.933996ms","start":"2026-05-01T00:51:50.083699Z","end":"2026-05-01T00:51:50.202633Z","steps":["trace[1273678187] 'process raft request' (duration: 118.798555ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:51:52.477169Z","caller":"traceutil/trace.go:171","msg":"trace[937362129] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"103.264073ms","start":"2026-05-01T00:51:52.373865Z","end":"2026-05-01T00:51:52.477129Z","steps":["trace[937362129] 'process raft request' (duration: 87.195309ms)","trace[937362129] 'compare' (duration: 15.911402ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:51:52.477895Z","caller":"traceutil/trace.go:171","msg":"trace[1321460750] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"101.335353ms","start":"2026-05-01T00:51:52.376534Z","end":"2026-05-01T00:51:52.477869Z","steps":["trace[1321460750] 'process raft request' (duration: 100.548594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:51:53.063731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.980499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:51:53.063861Z","caller":"traceutil/trace.go:171","msg":"trace[123467022] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"369.154169ms","start":"2026-05-01T00:51:52.694677Z","end":"2026-05-01T00:51:53.063831Z","steps":["trace[123467022] 'process raft request' (duration: 198.946907ms)","trace[123467022] 'compare' (duration: 169.848028ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:51:53.063961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:51:52.694659Z","time spent":"369.263681ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:51:57.67277Z","caller":"traceutil/trace.go:171","msg":"trace[1204585584] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"104.505507ms","start":"2026-05-01T00:51:57.568237Z","end":"2026-05-01T00:51:57.672742Z","steps":["trace[1204585584] 'process raft request' (duration: 104.315145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:02.407756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.127942ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:52:02.407845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.869319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:02.407893Z","caller":"traceutil/trace.go:171","msg":"trace[851993232] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:448; }","duration":"124.278364ms","start":"2026-05-01T00:52:02.283595Z","end":"2026-05-01T00:52:02.407873Z","steps":["trace[851993232] 'range keys from in-memory index tree' (duration: 124.110202ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:02.407961Z","caller":"traceutil/trace.go:171","msg":"trace[29457801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"457.040911ms","start":"2026-05-01T00:52:01.950893Z","end":"2026-05-01T00:52:02.407934Z","steps":["trace[29457801] 'range keys from in-memory index tree' (duration: 456.753688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:02.408018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:01.950845Z","time spent":"457.162342ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-01T00:52:02.798747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.057497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:52:02.798891Z","caller":"traceutil/trace.go:171","msg":"trace[2049937546] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"158.140502ms","start":"2026-05-01T00:52:02.640728Z","end":"2026-05-01T00:52:02.798868Z","steps":["trace[2049937546] 'process raft request' (duration: 17.838693ms)","trace[2049937546] 'compare' (duration: 139.891455ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:02.804461Z","caller":"traceutil/trace.go:171","msg":"trace[59723001] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"163.008599ms","start":"2026-05-01T00:52:02.641428Z","end":"2026-05-01T00:52:02.804437Z","steps":["trace[59723001] 'process raft request' (duration: 162.820967ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:02.804761Z","caller":"traceutil/trace.go:171","msg":"trace[124631265] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"162.746397ms","start":"2026-05-01T00:52:02.641998Z","end":"2026-05-01T00:52:02.804744Z","steps":["trace[124631265] 'process raft request' (duration: 162.391994ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:02.805217Z","caller":"traceutil/trace.go:171","msg":"trace[608155822] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"159.879729ms","start":"2026-05-01T00:52:02.645327Z","end":"2026-05-01T00:52:02.805207Z","steps":["trace[608155822] 'process raft request' (duration: 159.831758ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:02.805929Z","caller":"traceutil/trace.go:171","msg":"trace[895862963] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"162.09386ms","start":"2026-05-01T00:52:02.643815Z","end":"2026-05-01T00:52:02.805908Z","steps":["trace[895862963] 'process raft request' (duration: 161.213942ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:02.96843Z","caller":"traceutil/trace.go:171","msg":"trace[540615625] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"120.407267ms","start":"2026-05-01T00:52:02.847994Z","end":"2026-05-01T00:52:02.968401Z","steps":["trace[540615625] 'process raft request' (duration: 112.195457ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:02.994791Z","caller":"traceutil/trace.go:171","msg":"trace[696731721] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"103.530143ms","start":"2026-05-01T00:52:02.891228Z","end":"2026-05-01T00:52:02.994758Z","steps":["trace[696731721] 'process raft request' (duration: 103.470142ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:02.994906Z","caller":"traceutil/trace.go:171","msg":"trace[1350626389] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"104.063608ms","start":"2026-05-01T00:52:02.890825Z","end":"2026-05-01T00:52:02.994889Z","steps":["trace[1350626389] 'process raft request' (duration: 103.807426ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:02.995031Z","caller":"traceutil/trace.go:171","msg":"trace[1298844637] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"104.266921ms","start":"2026-05-01T00:52:02.890753Z","end":"2026-05-01T00:52:02.99502Z","steps":["trace[1298844637] 'process raft request' (duration: 103.703985ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:07.825923Z","caller":"traceutil/trace.go:171","msg":"trace[951838914] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"386.794937ms","start":"2026-05-01T00:52:07.439106Z","end":"2026-05-01T00:52:07.825901Z","steps":["trace[951838914] 'read index received' (duration: 386.631126ms)","trace[951838914] 'applied index is now lower than readState.Index' (duration: 162.741µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:07.826057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.944447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-01T00:52:07.82622Z","caller":"traceutil/trace.go:171","msg":"trace[1974860485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"387.163901ms","start":"2026-05-01T00:52:07.439043Z","end":"2026-05-01T00:52:07.826207Z","steps":["trace[1974860485] 'agreement among raft nodes before linearized reading' (duration: 386.949957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:07.826318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:07.439025Z","time spent":"387.255248ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-01T00:52:07.826643Z","caller":"traceutil/trace.go:171","msg":"trace[1745847091] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"667.126975ms","start":"2026-05-01T00:52:07.159502Z","end":"2026-05-01T00:52:07.826629Z","steps":["trace[1745847091] 'process raft request' (duration: 666.290299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:07.826843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:07.15948Z","time spent":"667.201179ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:12.506371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.029169ms","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-05-01T00:52:12.50647Z","caller":"traceutil/trace.go:171","msg":"trace[1484883345] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:501; }","duration":"223.156077ms","start":"2026-05-01T00:52:12.283297Z","end":"2026-05-01T00:52:12.506453Z","steps":["trace[1484883345] 'range keys from in-memory index tree' (duration: 223.003848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:13.446323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259565895,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T00:52:13.55216Z","caller":"traceutil/trace.go:171","msg":"trace[33212509] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"793.42387ms","start":"2026-05-01T00:52:12.758709Z","end":"2026-05-01T00:52:13.552133Z","steps":["trace[33212509] 'process raft request' (duration: 793.25914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:13.55245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:12.758679Z","time spent":"793.555529ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:14.182882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.495253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:52:14.183124Z","caller":"traceutil/trace.go:171","msg":"trace[204805955] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.094957486s","start":"2026-05-01T00:52:13.088129Z","end":"2026-05-01T00:52:14.183087Z","steps":["trace[204805955] 'process raft request' (duration: 710.163304ms)","trace[204805955] 'compare' (duration: 384.339424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:14.183281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:13.088112Z","time spent":"1.095096654s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:14.183335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237376341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:14.183358Z","caller":"traceutil/trace.go:171","msg":"trace[1243592279] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:521; }","duration":"1.237020858s","start":"2026-05-01T00:52:12.945945Z","end":"2026-05-01T00:52:14.182965Z","steps":["trace[1243592279] 'read index received' (duration: 606.034698ms)","trace[1243592279] 'applied index is now lower than readState.Index' (duration: 630.98506ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:14.183404Z","caller":"traceutil/trace.go:171","msg":"trace[1194556207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"1.237484137s","start":"2026-05-01T00:52:12.945904Z","end":"2026-05-01T00:52:14.183388Z","steps":["trace[1194556207] 'agreement among raft nodes before linearized reading' (duration: 1.237384691s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:14.183532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.946284ms","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-05-01T00:52:14.183559Z","caller":"traceutil/trace.go:171","msg":"trace[709097762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"302.014157ms","start":"2026-05-01T00:52:13.881541Z","end":"2026-05-01T00:52:14.183555Z","steps":["trace[709097762] 'agreement among raft nodes before linearized reading' (duration: 301.962074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:14.183578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:13.881521Z","time spent":"302.053111ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-01T00:52:14.183602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.980234ms","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-05-01T00:52:14.183675Z","caller":"traceutil/trace.go:171","msg":"trace[1429489549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:503; }","duration":"129.117253ms","start":"2026-05-01T00:52:14.054543Z","end":"2026-05-01T00:52:14.18366Z","steps":["trace[1429489549] 'agreement among raft nodes before linearized reading' (duration: 128.981685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:14.183859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.704324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-01T00:52:14.183872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:12.945885Z","time spent":"1.237600834s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-01T00:52:14.183921Z","caller":"traceutil/trace.go:171","msg":"trace[1179095108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"264.80603ms","start":"2026-05-01T00:52:13.9191Z","end":"2026-05-01T00:52:14.183906Z","steps":["trace[1179095108] 'agreement among raft nodes before linearized reading' (duration: 264.457899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:14.692762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259565900,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T00:52:15.198386Z","caller":"traceutil/trace.go:171","msg":"trace[46615105] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.010146499s","start":"2026-05-01T00:52:14.188226Z","end":"2026-05-01T00:52:15.198373Z","steps":["trace[46615105] 'process raft request' (duration: 1.009865012s)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:15.198485Z","caller":"traceutil/trace.go:171","msg":"trace[459475436] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.009559114s","start":"2026-05-01T00:52:14.188891Z","end":"2026-05-01T00:52:15.19845Z","steps":["trace[459475436] 'process raft request' (duration: 1.009288748s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:15.198534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:14.188205Z","time spent":"1.010245216s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:15.198653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:14.188879Z","time spent":"1.009681161s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:52:15.19833Z","caller":"traceutil/trace.go:171","msg":"trace[186775228] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:523; }","duration":"1.006448733s","start":"2026-05-01T00:52:14.191857Z","end":"2026-05-01T00:52:15.198306Z","steps":["trace[186775228] 'read index received' (duration: 954.419723ms)","trace[186775228] 'applied index is now lower than readState.Index' (duration: 52.02764ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:15.199046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.368847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-05-01T00:52:15.199096Z","caller":"traceutil/trace.go:171","msg":"trace[68378361] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:506; }","duration":"490.396858ms","start":"2026-05-01T00:52:14.708668Z","end":"2026-05-01T00:52:15.199065Z","steps":["trace[68378361] 'agreement among raft nodes before linearized reading' (duration: 490.324384ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:15.199084Z","caller":"traceutil/trace.go:171","msg":"trace[1023103498] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.011218626s","start":"2026-05-01T00:52:14.187025Z","end":"2026-05-01T00:52:15.198244Z","steps":["trace[1023103498] 'process raft request' (duration: 959.243058ms)","trace[1023103498] 'compare' (duration: 51.335109ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:15.199137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:14.708588Z","time spent":"490.538617ms","remote":"127.0.0.1:49010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12689,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-05-01T00:52:15.199242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:14.187007Z","time spent":"1.012151172s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:15.198951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.798766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-01T00:52:15.199476Z","caller":"traceutil/trace.go:171","msg":"trace[591854640] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:506; }","duration":"501.366771ms","start":"2026-05-01T00:52:14.698097Z","end":"2026-05-01T00:52:15.199464Z","steps":["trace[591854640] 'agreement among raft nodes before linearized reading' (duration: 500.810717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:15.199511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:14.698076Z","time spent":"501.426134ms","remote":"127.0.0.1:48960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-05-01T00:52:15.198835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006947733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:15.200184Z","caller":"traceutil/trace.go:171","msg":"trace[1559560326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"1.008324337s","start":"2026-05-01T00:52:14.191841Z","end":"2026-05-01T00:52:15.200165Z","steps":["trace[1559560326] 'agreement among raft nodes before linearized reading' (duration: 1.006739401s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:15.200282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:14.191834Z","time spent":"1.008432053s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-01T00:52:16.292306Z","caller":"traceutil/trace.go:171","msg":"trace[375680944] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"219.819665ms","start":"2026-05-01T00:52:16.072461Z","end":"2026-05-01T00:52:16.29228Z","steps":["trace[375680944] 'process raft request' (duration: 219.093381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:16.77185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.673965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:52:16.771944Z","caller":"traceutil/trace.go:171","msg":"trace[1999366608] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"280.725319ms","start":"2026-05-01T00:52:16.491204Z","end":"2026-05-01T00:52:16.77193Z","steps":["trace[1999366608] 'process raft request' (duration: 121.912506ms)","trace[1999366608] 'compare' (duration: 158.536787ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:17.832258Z","caller":"traceutil/trace.go:171","msg":"trace[2112870399] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"232.183962ms","start":"2026-05-01T00:52:17.600049Z","end":"2026-05-01T00:52:17.832233Z","steps":["trace[2112870399] 'process raft request' (duration: 232.023772ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:17.934165Z","caller":"traceutil/trace.go:171","msg":"trace[1614996101] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"182.44501ms","start":"2026-05-01T00:52:17.751691Z","end":"2026-05-01T00:52:17.934136Z","steps":["trace[1614996101] 'process raft request' (duration: 147.833353ms)","trace[1614996101] 'compare' (duration: 34.49445ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:18.662745Z","caller":"traceutil/trace.go:171","msg":"trace[1106045487] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"172.068476ms","start":"2026-05-01T00:52:18.490651Z","end":"2026-05-01T00:52:18.662719Z","steps":["trace[1106045487] 'process raft request' (duration: 171.856744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:18.925935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.921153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:52:18.926317Z","caller":"traceutil/trace.go:171","msg":"trace[1348720528] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"419.284074ms","start":"2026-05-01T00:52:18.506997Z","end":"2026-05-01T00:52:18.926281Z","steps":["trace[1348720528] 'process raft request' (duration: 314.943656ms)","trace[1348720528] 'compare' (duration: 103.771695ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:18.926487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:18.506976Z","time spent":"419.446073ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:52:18.926574Z","caller":"traceutil/trace.go:171","msg":"trace[1391926586] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"417.400194ms","start":"2026-05-01T00:52:18.509105Z","end":"2026-05-01T00:52:18.926505Z","steps":["trace[1391926586] 'process raft request' (duration: 417.070255ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:18.926641Z","caller":"traceutil/trace.go:171","msg":"trace[942588226] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"403.082558ms","start":"2026-05-01T00:52:18.523551Z","end":"2026-05-01T00:52:18.926633Z","steps":["trace[942588226] 'process raft request' (duration: 402.680094ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:18.926593Z","caller":"traceutil/trace.go:171","msg":"trace[66565] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"419.492397ms","start":"2026-05-01T00:52:18.50707Z","end":"2026-05-01T00:52:18.926563Z","steps":["trace[66565] 'process raft request' (duration: 418.999068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:18.92671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:18.523515Z","time spent":"403.162252ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T00:52:18.926773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:18.509098Z","time spent":"417.597235ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T00:52:18.926818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:18.507066Z","time spent":"419.630284ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:52:18.926879Z","caller":"traceutil/trace.go:171","msg":"trace[2077019083] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"395.794262ms","start":"2026-05-01T00:52:18.531076Z","end":"2026-05-01T00:52:18.92687Z","steps":["trace[2077019083] 'process raft request' (duration: 395.340815ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:18.926896Z","caller":"traceutil/trace.go:171","msg":"trace[1290868597] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"403.053775ms","start":"2026-05-01T00:52:18.523809Z","end":"2026-05-01T00:52:18.926863Z","steps":["trace[1290868597] 'process raft request' (duration: 402.559216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:18.926923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:18.531061Z","time spent":"395.842524ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:52:18.926587Z","caller":"traceutil/trace.go:171","msg":"trace[1608642911] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"365.207963ms","start":"2026-05-01T00:52:18.561364Z","end":"2026-05-01T00:52:18.926572Z","steps":["trace[1608642911] 'process raft request' (duration: 365.1532ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:18.926971Z","caller":"traceutil/trace.go:171","msg":"trace[1804873215] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"365.569905ms","start":"2026-05-01T00:52:18.561357Z","end":"2026-05-01T00:52:18.926927Z","steps":["trace[1804873215] 'process raft request' (duration: 365.111668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:18.926998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:18.523796Z","time spent":"403.159852ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:52:18.926981Z","caller":"traceutil/trace.go:171","msg":"trace[1427664192] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"419.825315ms","start":"2026-05-01T00:52:18.507109Z","end":"2026-05-01T00:52:18.926934Z","steps":["trace[1427664192] 'process raft request' (duration: 419.010208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:18.927055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:18.561331Z","time spent":"365.688411ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T00:52:18.927028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:18.561336Z","time spent":"365.672011ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:52:18.927141Z","caller":"traceutil/trace.go:171","msg":"trace[358689850] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"403.392026ms","start":"2026-05-01T00:52:18.523667Z","end":"2026-05-01T00:52:18.927059Z","steps":["trace[358689850] 'process raft request' (duration: 402.61268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:18.927164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:18.507104Z","time spent":"419.990956ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T00:52:18.927412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:18.523659Z","time spent":"403.662721ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:52:18.955267Z","caller":"traceutil/trace.go:171","msg":"trace[207414141] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"292.26106ms","start":"2026-05-01T00:52:18.662989Z","end":"2026-05-01T00:52:18.95525Z","steps":["trace[207414141] 'process raft request' (duration: 292.005735ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:18.955338Z","caller":"traceutil/trace.go:171","msg":"trace[744836788] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"287.397796ms","start":"2026-05-01T00:52:18.667914Z","end":"2026-05-01T00:52:18.955312Z","steps":["trace[744836788] 'process raft request' (duration: 287.29901ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:18.955381Z","caller":"traceutil/trace.go:171","msg":"trace[59990744] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"285.703057ms","start":"2026-05-01T00:52:18.669668Z","end":"2026-05-01T00:52:18.955371Z","steps":["trace[59990744] 'process raft request' (duration: 285.58198ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:18.95539Z","caller":"traceutil/trace.go:171","msg":"trace[53889057] transaction","detail":"{read_only:false; number_of_response:1; response_revision:560; }","duration":"285.687456ms","start":"2026-05-01T00:52:18.669688Z","end":"2026-05-01T00:52:18.955375Z","steps":["trace[53889057] 'process raft request' (duration: 285.614632ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:18.95545Z","caller":"traceutil/trace.go:171","msg":"trace[1424143187] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"264.811766ms","start":"2026-05-01T00:52:18.690627Z","end":"2026-05-01T00:52:18.955439Z","steps":["trace[1424143187] 'process raft request' (duration: 264.69929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.416016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.860203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:52:19.416288Z","caller":"traceutil/trace.go:171","msg":"trace[860152870] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"395.497736ms","start":"2026-05-01T00:52:19.020759Z","end":"2026-05-01T00:52:19.416256Z","steps":["trace[860152870] 'process raft request' (duration: 38.308753ms)","trace[860152870] 'compare' (duration: 356.687254ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:19.416361Z","caller":"traceutil/trace.go:171","msg":"trace[1569031028] transaction","detail":"{read_only:false; number_of_response:1; response_revision:573; }","duration":"392.389016ms","start":"2026-05-01T00:52:19.023934Z","end":"2026-05-01T00:52:19.416323Z","steps":["trace[1569031028] 'process raft request' (duration: 392.265149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.416442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.020739Z","time spent":"395.617853ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:52:19.416526Z","caller":"traceutil/trace.go:171","msg":"trace[1985486586] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"377.440262ms","start":"2026-05-01T00:52:19.039066Z","end":"2026-05-01T00:52:19.416506Z","steps":["trace[1985486586] 'process raft request' (duration: 377.375438ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:19.416565Z","caller":"traceutil/trace.go:171","msg":"trace[515157974] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"389.991177ms","start":"2026-05-01T00:52:19.02655Z","end":"2026-05-01T00:52:19.416541Z","steps":["trace[515157974] 'process raft request' (duration: 389.703681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.416616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.039054Z","time spent":"377.524607ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:52:19.416642Z","caller":"traceutil/trace.go:171","msg":"trace[382550791] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"386.896868ms","start":"2026-05-01T00:52:19.029733Z","end":"2026-05-01T00:52:19.41663Z","steps":["trace[382550791] 'process raft request' (duration: 386.58625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.416693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.029721Z","time spent":"386.946872ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:52:19.416728Z","caller":"traceutil/trace.go:171","msg":"trace[795976125] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"382.424751ms","start":"2026-05-01T00:52:19.034294Z","end":"2026-05-01T00:52:19.416718Z","steps":["trace[795976125] 'process raft request' (duration: 382.105032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.416679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.026536Z","time spent":"390.085713ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:19.416562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.023915Z","time spent":"392.533675ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7376,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:19.416785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.034284Z","time spent":"382.478424ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:52:19.684875Z","caller":"traceutil/trace.go:171","msg":"trace[1152144639] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:595; }","duration":"473.317271ms","start":"2026-05-01T00:52:19.211532Z","end":"2026-05-01T00:52:19.684849Z","steps":["trace[1152144639] 'read index received' (duration: 204.232367ms)","trace[1152144639] 'applied index is now lower than readState.Index' (duration: 269.084014ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:19.685532Z","caller":"traceutil/trace.go:171","msg":"trace[1257873911] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"610.59261ms","start":"2026-05-01T00:52:19.074922Z","end":"2026-05-01T00:52:19.685515Z","steps":["trace[1257873911] 'process raft request' (duration: 606.445711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.685674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.074895Z","time spent":"610.687956ms","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:19.685896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.370142ms","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-05-01T00:52:19.685939Z","caller":"traceutil/trace.go:171","msg":"trace[1780612211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"474.449826ms","start":"2026-05-01T00:52:19.211478Z","end":"2026-05-01T00:52:19.685928Z","steps":["trace[1780612211] 'agreement among raft nodes before linearized reading' (duration: 474.372042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.68601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.211457Z","time spent":"474.535451ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-01T00:52:19.706493Z","caller":"traceutil/trace.go:171","msg":"trace[485310273] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"282.382672ms","start":"2026-05-01T00:52:19.424094Z","end":"2026-05-01T00:52:19.706477Z","steps":["trace[485310273] 'process raft request' (duration: 282.1709ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:19.706812Z","caller":"traceutil/trace.go:171","msg":"trace[211438820] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"282.580853ms","start":"2026-05-01T00:52:19.42422Z","end":"2026-05-01T00:52:19.706801Z","steps":["trace[211438820] 'process raft request' (duration: 282.152818ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:19.706916Z","caller":"traceutil/trace.go:171","msg":"trace[1890995603] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"277.807058ms","start":"2026-05-01T00:52:19.429077Z","end":"2026-05-01T00:52:19.706884Z","steps":["trace[1890995603] 'process raft request' (duration: 277.588545ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:19.70706Z","caller":"traceutil/trace.go:171","msg":"trace[1610139954] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"275.020787ms","start":"2026-05-01T00:52:19.432002Z","end":"2026-05-01T00:52:19.707023Z","steps":["trace[1610139954] 'process raft request' (duration: 274.792354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.707562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.646955ms","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-05-01T00:52:19.707659Z","caller":"traceutil/trace.go:171","msg":"trace[2111991018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"493.772872ms","start":"2026-05-01T00:52:19.213865Z","end":"2026-05-01T00:52:19.707638Z","steps":["trace[2111991018] 'agreement among raft nodes before linearized reading' (duration: 493.600842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.70771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.213854Z","time spent":"493.841727ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-01T00:52:19.707919Z","caller":"traceutil/trace.go:171","msg":"trace[510470228] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"213.353785ms","start":"2026-05-01T00:52:19.49455Z","end":"2026-05-01T00:52:19.707904Z","steps":["trace[510470228] 'process raft request' (duration: 212.486254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.708059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.265245ms","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-05-01T00:52:19.708098Z","caller":"traceutil/trace.go:171","msg":"trace[901527304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"492.380612ms","start":"2026-05-01T00:52:19.215706Z","end":"2026-05-01T00:52:19.708086Z","steps":["trace[901527304] 'agreement among raft nodes before linearized reading' (duration: 492.310548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:19.708135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.215696Z","time spent":"492.428354ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-01T00:52:21.103825Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.161397471s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-01T00:52:21.104989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16209393s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:52:21.105146Z","caller":"traceutil/trace.go:171","msg":"trace[1179285436] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.392587054s","start":"2026-05-01T00:52:19.71253Z","end":"2026-05-01T00:52:21.105117Z","steps":["trace[1179285436] 'process raft request' (duration: 230.278192ms)","trace[1179285436] 'compare' (duration: 1.161985303s)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:21.105318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.712512Z","time spent":"1.392720791s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:52:21.105491Z","caller":"traceutil/trace.go:171","msg":"trace[1008338371] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"1.386919956s","start":"2026-05-01T00:52:19.718537Z","end":"2026-05-01T00:52:21.105456Z","steps":["trace[1008338371] 'process raft request' (duration: 1.386586287s)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:21.105639Z","caller":"traceutil/trace.go:171","msg":"trace[114088699] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.39201442s","start":"2026-05-01T00:52:19.71361Z","end":"2026-05-01T00:52:21.105624Z","steps":["trace[114088699] 'process raft request' (duration: 1.391465649s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.105717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.71359Z","time spent":"1.392090385s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:52:21.105794Z","caller":"traceutil/trace.go:171","msg":"trace[1780746017] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.375849726s","start":"2026-05-01T00:52:19.729887Z","end":"2026-05-01T00:52:21.105737Z","steps":["trace[1780746017] 'process raft request' (duration: 1.375294044s)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:21.105957Z","caller":"traceutil/trace.go:171","msg":"trace[328608858] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.352221132s","start":"2026-05-01T00:52:19.753723Z","end":"2026-05-01T00:52:21.105944Z","steps":["trace[328608858] 'process raft request' (duration: 1.351502331s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.10596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.729868Z","time spent":"1.376008486s","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:21.106024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.753705Z","time spent":"1.352290475s","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T00:52:21.106138Z","caller":"traceutil/trace.go:171","msg":"trace[279768927] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:608; }","duration":"1.269285511s","start":"2026-05-01T00:52:19.836818Z","end":"2026-05-01T00:52:21.106104Z","steps":["trace[279768927] 'read index received' (duration: 106.004344ms)","trace[279768927] 'applied index is now lower than readState.Index' (duration: 1.163280226s)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:21.106469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.718525Z","time spent":"1.387080785s","remote":"127.0.0.1:48976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:21.114763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254339035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:21.114814Z","caller":"traceutil/trace.go:171","msg":"trace[17106039] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:589; }","duration":"1.254393838s","start":"2026-05-01T00:52:19.860406Z","end":"2026-05-01T00:52:21.114799Z","steps":["trace[17106039] 'agreement among raft nodes before linearized reading' (duration: 1.254310964s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.114847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.860369Z","time spent":"1.254470103s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-01T00:52:21.114838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.151819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:21.114924Z","caller":"traceutil/trace.go:171","msg":"trace[1243963917] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:589; }","duration":"941.294237ms","start":"2026-05-01T00:52:20.17361Z","end":"2026-05-01T00:52:21.114904Z","steps":["trace[1243963917] 'agreement among raft nodes before linearized reading' (duration: 941.120107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.114943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244000498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:52:21.114967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:20.173322Z","time spent":"941.635927ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-05-01T00:52:21.114999Z","caller":"traceutil/trace.go:171","msg":"trace[234468255] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:589; }","duration":"1.244112375s","start":"2026-05-01T00:52:19.870874Z","end":"2026-05-01T00:52:21.114986Z","steps":["trace[234468255] 'agreement among raft nodes before linearized reading' (duration: 1.243982458s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.115028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.870865Z","time spent":"1.244155667s","remote":"127.0.0.1:33402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-01T00:52:21.115048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244183839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:52:21.115024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243336749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:21.115074Z","caller":"traceutil/trace.go:171","msg":"trace[708443026] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:589; }","duration":"1.244225231s","start":"2026-05-01T00:52:19.870841Z","end":"2026-05-01T00:52:21.115067Z","steps":["trace[708443026] 'agreement among raft nodes before linearized reading' (duration: 1.244182059s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.115095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.870834Z","time spent":"1.244255823s","remote":"127.0.0.1:33402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-05-01T00:52:21.115096Z","caller":"traceutil/trace.go:171","msg":"trace[1779617378] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:589; }","duration":"1.243457536s","start":"2026-05-01T00:52:19.871625Z","end":"2026-05-01T00:52:21.115082Z","steps":["trace[1779617378] 'agreement among raft nodes before linearized reading' (duration: 1.243288077s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.11513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.871606Z","time spent":"1.243515129s","remote":"127.0.0.1:33402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-05-01T00:52:21.115163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254547567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:21.115194Z","caller":"traceutil/trace.go:171","msg":"trace[1887064686] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:589; }","duration":"1.254587829s","start":"2026-05-01T00:52:19.8606Z","end":"2026-05-01T00:52:21.115188Z","steps":["trace[1887064686] 'agreement among raft nodes before linearized reading' (duration: 1.254547107s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.115212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.860596Z","time spent":"1.25461179s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-01T00:52:21.115227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.730515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:21.115253Z","caller":"traceutil/trace.go:171","msg":"trace[314339463] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:589; }","duration":"940.785538ms","start":"2026-05-01T00:52:20.17446Z","end":"2026-05-01T00:52:21.115245Z","steps":["trace[314339463] 'agreement among raft nodes before linearized reading' (duration: 940.743106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.115281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:20.174442Z","time spent":"940.832621ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-01T00:52:21.115306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254853655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:21.115332Z","caller":"traceutil/trace.go:171","msg":"trace[1626626233] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:589; }","duration":"1.254890347s","start":"2026-05-01T00:52:19.860435Z","end":"2026-05-01T00:52:21.115326Z","steps":["trace[1626626233] 'agreement among raft nodes before linearized reading' (duration: 1.254853595s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.115342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278524072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-01T00:52:21.115365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.860431Z","time spent":"1.254925729s","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-01T00:52:21.115365Z","caller":"traceutil/trace.go:171","msg":"trace[1740646651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"1.278595596s","start":"2026-05-01T00:52:19.836763Z","end":"2026-05-01T00:52:21.115358Z","steps":["trace[1740646651] 'agreement among raft nodes before linearized reading' (duration: 1.269433059s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.115389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.652017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:21.115415Z","caller":"traceutil/trace.go:171","msg":"trace[1709958393] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:589; }","duration":"941.702511ms","start":"2026-05-01T00:52:20.173705Z","end":"2026-05-01T00:52:21.115407Z","steps":["trace[1709958393] 'agreement among raft nodes before linearized reading' (duration: 941.662088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.115457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:20.173667Z","time spent":"941.784155ms","remote":"127.0.0.1:33418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-01T00:52:21.115415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.836739Z","time spent":"1.278663019s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T00:52:21.115757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165150942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:21.115799Z","caller":"traceutil/trace.go:171","msg":"trace[1419363127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.165231237s","start":"2026-05-01T00:52:19.950556Z","end":"2026-05-01T00:52:21.115787Z","steps":["trace[1419363127] 'agreement among raft nodes before linearized reading' (duration: 1.164310925s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:21.115819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:19.950542Z","time spent":"1.16527235s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-01T00:52:23.849793Z","caller":"traceutil/trace.go:171","msg":"trace[1788372064] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"104.618789ms","start":"2026-05-01T00:52:23.745146Z","end":"2026-05-01T00:52:23.849765Z","steps":["trace[1788372064] 'process raft request' (duration: 104.433319ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:24.092726Z","caller":"traceutil/trace.go:171","msg":"trace[1058726392] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"142.67502ms","start":"2026-05-01T00:52:23.950028Z","end":"2026-05-01T00:52:24.092703Z","steps":["trace[1058726392] 'read index received' (duration: 142.143412ms)","trace[1058726392] 'applied index is now lower than readState.Index' (duration: 530.598µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:24.092788Z","caller":"traceutil/trace.go:171","msg":"trace[1242171072] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"238.23337ms","start":"2026-05-01T00:52:23.854519Z","end":"2026-05-01T00:52:24.092752Z","steps":["trace[1242171072] 'process raft request' (duration: 237.916503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:24.092878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.852201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:24.093032Z","caller":"traceutil/trace.go:171","msg":"trace[2002698950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"142.896303ms","start":"2026-05-01T00:52:23.950004Z","end":"2026-05-01T00:52:24.0929Z","steps":["trace[2002698950] 'agreement among raft nodes before linearized reading' (duration: 142.788667ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:24.785548Z","caller":"traceutil/trace.go:171","msg":"trace[1336410336] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"103.786739ms","start":"2026-05-01T00:52:24.681682Z","end":"2026-05-01T00:52:24.785469Z","steps":["trace[1336410336] 'process raft request' (duration: 34.181264ms)","trace[1336410336] 'compare' (duration: 69.249955ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:25.239378Z","caller":"traceutil/trace.go:171","msg":"trace[1640090332] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"107.715515ms","start":"2026-05-01T00:52:25.131638Z","end":"2026-05-01T00:52:25.239353Z","steps":["trace[1640090332] 'process raft request' (duration: 107.580508ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:25.387114Z","caller":"traceutil/trace.go:171","msg":"trace[2032063933] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"250.262747ms","start":"2026-05-01T00:52:25.136817Z","end":"2026-05-01T00:52:25.38708Z","steps":["trace[2032063933] 'read index received' (duration: 102.436171ms)","trace[2032063933] 'applied index is now lower than readState.Index' (duration: 147.825176ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:25.387172Z","caller":"traceutil/trace.go:171","msg":"trace[475700362] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"254.21108ms","start":"2026-05-01T00:52:25.132934Z","end":"2026-05-01T00:52:25.387145Z","steps":["trace[475700362] 'process raft request' (duration: 168.776846ms)","trace[475700362] 'compare' (duration: 85.241833ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:25.387377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.542482ms","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-05-01T00:52:25.387433Z","caller":"traceutil/trace.go:171","msg":"trace[44365484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"250.655759ms","start":"2026-05-01T00:52:25.136762Z","end":"2026-05-01T00:52:25.387418Z","steps":["trace[44365484] 'agreement among raft nodes before linearized reading' (duration: 250.461108ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:25.390318Z","caller":"traceutil/trace.go:171","msg":"trace[1987029925] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"113.642804ms","start":"2026-05-01T00:52:25.276652Z","end":"2026-05-01T00:52:25.390295Z","steps":["trace[1987029925] 'process raft request' (duration: 112.376806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:29.759736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259566191,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T00:52:30.178148Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.117523459s","expected-duration":"1s"} {"level":"info","ts":"2026-05-01T00:52:30.178723Z","caller":"traceutil/trace.go:171","msg":"trace[329074462] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.118201394s","start":"2026-05-01T00:52:29.060496Z","end":"2026-05-01T00:52:30.178698Z","steps":["trace[329074462] 'process raft request' (duration: 1.118002634s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:30.17891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:29.060477Z","time spent":"1.11831204s","remote":"127.0.0.1:49090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:52:30.233881Z","caller":"traceutil/trace.go:171","msg":"trace[2082222806] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"974.186025ms","start":"2026-05-01T00:52:29.259662Z","end":"2026-05-01T00:52:30.233848Z","steps":["trace[2082222806] 'read index received' (duration: 918.789423ms)","trace[2082222806] 'applied index is now lower than readState.Index' (duration: 55.395481ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:30.234005Z","caller":"traceutil/trace.go:171","msg":"trace[772574738] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"949.071191ms","start":"2026-05-01T00:52:29.284888Z","end":"2026-05-01T00:52:30.233959Z","steps":["trace[772574738] 'process raft request' (duration: 913.590614ms)","trace[772574738] 'compare' (duration: 35.235945ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:30.234268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.079619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-01T00:52:30.234351Z","caller":"traceutil/trace.go:171","msg":"trace[1935922158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:669; }","duration":"834.152032ms","start":"2026-05-01T00:52:29.400163Z","end":"2026-05-01T00:52:30.234315Z","steps":["trace[1935922158] 'agreement among raft nodes before linearized reading' (duration: 833.999775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:30.234385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:29.400094Z","time spent":"834.284789ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-01T00:52:30.234322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.89954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:30.234439Z","caller":"traceutil/trace.go:171","msg":"trace[859283667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"284.034927ms","start":"2026-05-01T00:52:29.950384Z","end":"2026-05-01T00:52:30.234419Z","steps":["trace[859283667] 'agreement among raft nodes before linearized reading' (duration: 283.841617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:30.234549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.086752ms","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-05-01T00:52:30.234624Z","caller":"traceutil/trace.go:171","msg":"trace[1993435491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:669; }","duration":"823.179827ms","start":"2026-05-01T00:52:29.411435Z","end":"2026-05-01T00:52:30.234614Z","steps":["trace[1993435491] 'agreement among raft nodes before linearized reading' (duration: 823.065021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:30.234642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:29.411424Z","time spent":"823.212958ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T00:52:30.23478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.112881ms","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-05-01T00:52:30.234816Z","caller":"traceutil/trace.go:171","msg":"trace[370620440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:669; }","duration":"975.178465ms","start":"2026-05-01T00:52:29.259628Z","end":"2026-05-01T00:52:30.234807Z","steps":["trace[370620440] 'agreement among raft nodes before linearized reading' (duration: 975.038948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:30.234843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:29.259612Z","time spent":"975.223998ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-01T00:52:30.23502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.893581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-01T00:52:30.235065Z","caller":"traceutil/trace.go:171","msg":"trace[255109508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:669; }","duration":"358.019298ms","start":"2026-05-01T00:52:29.877029Z","end":"2026-05-01T00:52:30.235048Z","steps":["trace[255109508] 'agreement among raft nodes before linearized reading' (duration: 357.217097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:30.2351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:29.877002Z","time spent":"358.089811ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-01T00:52:30.234765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:29.28486Z","time spent":"949.22511ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:31.076015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.145085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T00:52:31.076238Z","caller":"traceutil/trace.go:171","msg":"trace[308623909] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"836.845562ms","start":"2026-05-01T00:52:30.239328Z","end":"2026-05-01T00:52:31.076174Z","steps":["trace[308623909] 'process raft request' (duration: 730.461875ms)","trace[308623909] 'compare' (duration: 106.05152ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:31.076411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:30.239305Z","time spent":"837.005111ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:52:31.076525Z","caller":"traceutil/trace.go:171","msg":"trace[113527822] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"836.036911ms","start":"2026-05-01T00:52:30.240431Z","end":"2026-05-01T00:52:31.076468Z","steps":["trace[113527822] 'process raft request' (duration: 835.673513ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:31.076581Z","caller":"traceutil/trace.go:171","msg":"trace[843711124] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"835.942076ms","start":"2026-05-01T00:52:30.240571Z","end":"2026-05-01T00:52:31.076513Z","steps":["trace[843711124] 'process raft request' (duration: 835.580118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:31.076676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:30.240421Z","time spent":"836.178769ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:31.076798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:30.240545Z","time spent":"836.144876ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:33.44963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259566204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T00:52:33.950592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259566204,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T00:52:34.300195Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.997127928s","expected-duration":"1s"} {"level":"info","ts":"2026-05-01T00:52:34.30074Z","caller":"traceutil/trace.go:171","msg":"trace[924098010] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"1.351928277s","start":"2026-05-01T00:52:32.948766Z","end":"2026-05-01T00:52:34.300694Z","steps":["trace[924098010] 'read index received' (duration: 1.35158889s)","trace[924098010] 'applied index is now lower than readState.Index' (duration: 337.897µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:34.300817Z","caller":"traceutil/trace.go:171","msg":"trace[1392283229] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"1.997841693s","start":"2026-05-01T00:52:32.302945Z","end":"2026-05-01T00:52:34.300786Z","steps":["trace[1392283229] 'process raft request' (duration: 1.9975757s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:34.30092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.352127016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:52:34.300991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220267561s","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-05-01T00:52:34.300992Z","caller":"traceutil/trace.go:171","msg":"trace[1628929239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"1.352247982s","start":"2026-05-01T00:52:32.948723Z","end":"2026-05-01T00:52:34.300971Z","steps":["trace[1628929239] 'agreement among raft nodes before linearized reading' (duration: 1.352114386s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:34.300994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:32.302925Z","time spent":"1.997967389s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:34.30107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.46605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-01T00:52:34.301058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:32.948705Z","time spent":"1.352337927s","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-01T00:52:34.301025Z","caller":"traceutil/trace.go:171","msg":"trace[14738605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:674; }","duration":"1.220354895s","start":"2026-05-01T00:52:33.080662Z","end":"2026-05-01T00:52:34.301016Z","steps":["trace[14738605] 'agreement among raft nodes before linearized reading' (duration: 1.220191107s)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:34.301091Z","caller":"traceutil/trace.go:171","msg":"trace[1838432549] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:674; }","duration":"373.533303ms","start":"2026-05-01T00:52:33.927552Z","end":"2026-05-01T00:52:34.301086Z","steps":["trace[1838432549] 'agreement among raft nodes before linearized reading' (duration: 373.495831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:34.301124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:33.927534Z","time spent":"373.583165ms","remote":"127.0.0.1:38208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-05-01T00:52:34.301159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:33.080643Z","time spent":"1.220473321s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T00:52:34.301403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218935765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-01T00:52:34.301427Z","caller":"traceutil/trace.go:171","msg":"trace[2004288136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:674; }","duration":"1.218961316s","start":"2026-05-01T00:52:33.082459Z","end":"2026-05-01T00:52:34.30142Z","steps":["trace[2004288136] 'agreement among raft nodes before linearized reading' (duration: 1.218890123s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:34.301446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:33.082415Z","time spent":"1.219026619s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-01T00:52:34.301486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219069242s","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-05-01T00:52:34.301528Z","caller":"traceutil/trace.go:171","msg":"trace[678026397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:674; }","duration":"1.219193598s","start":"2026-05-01T00:52:33.082323Z","end":"2026-05-01T00:52:34.301516Z","steps":["trace[678026397] 'agreement among raft nodes before linearized reading' (duration: 1.21903898s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:34.301561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:33.082299Z","time spent":"1.219255081s","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-01T00:52:34.565926Z","caller":"traceutil/trace.go:171","msg":"trace[376776001] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"260.246382ms","start":"2026-05-01T00:52:34.305655Z","end":"2026-05-01T00:52:34.565901Z","steps":["trace[376776001] 'process raft request' (duration: 257.824614ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:34.571125Z","caller":"traceutil/trace.go:171","msg":"trace[2073932075] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"264.468138ms","start":"2026-05-01T00:52:34.306631Z","end":"2026-05-01T00:52:34.571099Z","steps":["trace[2073932075] 'process raft request' (duration: 264.274729ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:34.571129Z","caller":"traceutil/trace.go:171","msg":"trace[1623964625] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"264.32043ms","start":"2026-05-01T00:52:34.306783Z","end":"2026-05-01T00:52:34.571104Z","steps":["trace[1623964625] 'process raft request' (duration: 264.259437ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:34.571716Z","caller":"traceutil/trace.go:171","msg":"trace[1108204827] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:701; }","duration":"225.577282ms","start":"2026-05-01T00:52:34.346058Z","end":"2026-05-01T00:52:34.571636Z","steps":["trace[1108204827] 'read index received' (duration: 217.432505ms)","trace[1108204827] 'applied index is now lower than readState.Index' (duration: 8.143277ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:52:34.571843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.769631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:34.571903Z","caller":"traceutil/trace.go:171","msg":"trace[530963937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"225.864336ms","start":"2026-05-01T00:52:34.346025Z","end":"2026-05-01T00:52:34.571889Z","steps":["trace[530963937] 'agreement among raft nodes before linearized reading' (duration: 225.771171ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:36.529855Z","caller":"traceutil/trace.go:171","msg":"trace[1177295229] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"218.94119ms","start":"2026-05-01T00:52:36.310866Z","end":"2026-05-01T00:52:36.529807Z","steps":["trace[1177295229] 'process raft request' (duration: 211.303006ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:36.533905Z","caller":"traceutil/trace.go:171","msg":"trace[1274444555] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"192.10203ms","start":"2026-05-01T00:52:36.341771Z","end":"2026-05-01T00:52:36.533873Z","steps":["trace[1274444555] 'process raft request' (duration: 191.942652ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:48.219642Z","caller":"traceutil/trace.go:171","msg":"trace[1669642723] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"222.532945ms","start":"2026-05-01T00:52:47.997085Z","end":"2026-05-01T00:52:48.219618Z","steps":["trace[1669642723] 'process raft request' (duration: 219.998779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.141715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095563259566528,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T00:52:51.534539Z","caller":"traceutil/trace.go:171","msg":"trace[191155084] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"945.927693ms","start":"2026-05-01T00:52:50.588591Z","end":"2026-05-01T00:52:51.534519Z","steps":["trace[191155084] 'process raft request' (duration: 945.763197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.53469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:50.588564Z","time spent":"946.046939ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:52:51.540747Z","caller":"traceutil/trace.go:171","msg":"trace[1790270258] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:862; }","duration":"899.971339ms","start":"2026-05-01T00:52:50.640765Z","end":"2026-05-01T00:52:51.540737Z","steps":["trace[1790270258] 'read index received' (duration: 893.637781ms)","trace[1790270258] 'applied index is now lower than readState.Index' (duration: 6.332858ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:51.540788Z","caller":"traceutil/trace.go:171","msg":"trace[649468978] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"729.156335ms","start":"2026-05-01T00:52:50.811608Z","end":"2026-05-01T00:52:51.540764Z","steps":["trace[649468978] 'process raft request' (duration: 729.02645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.540873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:50.811583Z","time spent":"729.250219ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:51.541097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.328553ms","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-05-01T00:52:51.541156Z","caller":"traceutil/trace.go:171","msg":"trace[1928694796] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"356.89813ms","start":"2026-05-01T00:52:51.184249Z","end":"2026-05-01T00:52:51.541147Z","steps":["trace[1928694796] 'process raft request' (duration: 356.454092ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:51.541162Z","caller":"traceutil/trace.go:171","msg":"trace[840206056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:831; }","duration":"900.397466ms","start":"2026-05-01T00:52:50.640751Z","end":"2026-05-01T00:52:51.541149Z","steps":["trace[840206056] 'agreement among raft nodes before linearized reading' (duration: 900.215368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.541199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:50.640715Z","time spent":"900.476129ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T00:52:51.541211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:51.184231Z","time spent":"356.947452ms","remote":"127.0.0.1:49148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:52:51.541574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.472751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.541614Z","caller":"traceutil/trace.go:171","msg":"trace[494506698] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:831; }","duration":"250.561295ms","start":"2026-05-01T00:52:51.291042Z","end":"2026-05-01T00:52:51.541603Z","steps":["trace[494506698] 'agreement among raft nodes before linearized reading' (duration: 250.487152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.541645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.228424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:52:51.541663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.561775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.541728Z","caller":"traceutil/trace.go:171","msg":"trace[866960149] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:831; }","duration":"250.656438ms","start":"2026-05-01T00:52:51.291055Z","end":"2026-05-01T00:52:51.541711Z","steps":["trace[866960149] 'agreement among raft nodes before linearized reading' (duration: 250.45757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.541884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.971045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.541947Z","caller":"traceutil/trace.go:171","msg":"trace[1808188410] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:831; }","duration":"177.039367ms","start":"2026-05-01T00:52:51.364892Z","end":"2026-05-01T00:52:51.541932Z","steps":["trace[1808188410] 'agreement among raft nodes before linearized reading' (duration: 176.946004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.542131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.295098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.542157Z","caller":"traceutil/trace.go:171","msg":"trace[444808905] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:831; }","duration":"177.338909ms","start":"2026-05-01T00:52:51.364813Z","end":"2026-05-01T00:52:51.542151Z","steps":["trace[444808905] 'agreement among raft nodes before linearized reading' (duration: 177.299338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.542209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.287099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:52:51.542244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.251399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.54225Z","caller":"traceutil/trace.go:171","msg":"trace[1696769727] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:831; }","duration":"101.344432ms","start":"2026-05-01T00:52:51.440895Z","end":"2026-05-01T00:52:51.54224Z","steps":["trace[1696769727] 'agreement among raft nodes before linearized reading' (duration: 101.28833ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:51.542262Z","caller":"traceutil/trace.go:171","msg":"trace[489431704] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:831; }","duration":"233.280641ms","start":"2026-05-01T00:52:51.308976Z","end":"2026-05-01T00:52:51.542256Z","steps":["trace[489431704] 'agreement among raft nodes before linearized reading' (duration: 233.25214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.542397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.994548ms","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":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.542431Z","caller":"traceutil/trace.go:171","msg":"trace[1067790789] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:831; }","duration":"102.0327ms","start":"2026-05-01T00:52:51.440392Z","end":"2026-05-01T00:52:51.542424Z","steps":["trace[1067790789] 'agreement among raft nodes before linearized reading' (duration: 101.984118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.542532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.192827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.542561Z","caller":"traceutil/trace.go:171","msg":"trace[1895430900] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:831; }","duration":"102.247269ms","start":"2026-05-01T00:52:51.440307Z","end":"2026-05-01T00:52:51.542554Z","steps":["trace[1895430900] 'agreement among raft nodes before linearized reading' (duration: 102.205027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.54266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.641458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.542688Z","caller":"traceutil/trace.go:171","msg":"trace[1188025499] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:831; }","duration":"132.70235ms","start":"2026-05-01T00:52:51.409978Z","end":"2026-05-01T00:52:51.542681Z","steps":["trace[1188025499] 'agreement among raft nodes before linearized reading' (duration: 132.661408ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:51.541679Z","caller":"traceutil/trace.go:171","msg":"trace[386017820] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:831; }","duration":"176.409492ms","start":"2026-05-01T00:52:51.365259Z","end":"2026-05-01T00:52:51.541668Z","steps":["trace[386017820] 'agreement among raft nodes before linearized reading' (duration: 176.234875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.542772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.131745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.542799Z","caller":"traceutil/trace.go:171","msg":"trace[1698621488] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:831; }","duration":"234.192028ms","start":"2026-05-01T00:52:51.3086Z","end":"2026-05-01T00:52:51.542792Z","steps":["trace[1698621488] 'agreement among raft nodes before linearized reading' (duration: 234.117074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.542831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.782054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.542867Z","caller":"traceutil/trace.go:171","msg":"trace[145288223] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:831; }","duration":"133.873078ms","start":"2026-05-01T00:52:51.408985Z","end":"2026-05-01T00:52:51.542859Z","steps":["trace[145288223] 'agreement among raft nodes before linearized reading' (duration: 133.821156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.542992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.948981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.543025Z","caller":"traceutil/trace.go:171","msg":"trace[211800255] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:831; }","duration":"134.007183ms","start":"2026-05-01T00:52:51.40901Z","end":"2026-05-01T00:52:51.543017Z","steps":["trace[211800255] 'agreement among raft nodes before linearized reading' (duration: 133.955431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.543083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.38305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-01T00:52:51.543113Z","caller":"traceutil/trace.go:171","msg":"trace[242820775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:831; }","duration":"896.456752ms","start":"2026-05-01T00:52:50.646649Z","end":"2026-05-01T00:52:51.543106Z","steps":["trace[242820775] 'agreement among raft nodes before linearized reading' (duration: 896.379909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.543124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.612138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T00:52:51.543132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:50.646625Z","time spent":"896.502424ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-01T00:52:51.543158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.701769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.543174Z","caller":"traceutil/trace.go:171","msg":"trace[1810653889] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:831; }","duration":"251.638889ms","start":"2026-05-01T00:52:51.291503Z","end":"2026-05-01T00:52:51.543142Z","steps":["trace[1810653889] 'agreement among raft nodes before linearized reading' (duration: 251.600577ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:51.543193Z","caller":"traceutil/trace.go:171","msg":"trace[746582679] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:831; }","duration":"157.762532ms","start":"2026-05-01T00:52:51.385422Z","end":"2026-05-01T00:52:51.543185Z","steps":["trace[746582679] 'agreement among raft nodes before linearized reading' (duration: 157.710119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.543244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.469906ms","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-05-01T00:52:51.543267Z","caller":"traceutil/trace.go:171","msg":"trace[2113729376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:831; }","duration":"898.513588ms","start":"2026-05-01T00:52:50.644748Z","end":"2026-05-01T00:52:51.543262Z","steps":["trace[2113729376] 'agreement among raft nodes before linearized reading' (duration: 898.454285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.543284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:50.644738Z","time spent":"898.541368ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-01T00:52:51.543327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.197879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.543359Z","caller":"traceutil/trace.go:171","msg":"trace[1621233047] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:831; }","duration":"158.259432ms","start":"2026-05-01T00:52:51.385091Z","end":"2026-05-01T00:52:51.543351Z","steps":["trace[1621233047] 'agreement among raft nodes before linearized reading' (duration: 158.21348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.543475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.396988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.543505Z","caller":"traceutil/trace.go:171","msg":"trace[1391697083] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:831; }","duration":"158.442939ms","start":"2026-05-01T00:52:51.385055Z","end":"2026-05-01T00:52:51.543498Z","steps":["trace[1391697083] 'agreement among raft nodes before linearized reading' (duration: 158.393677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.54305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.519811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.543721Z","caller":"traceutil/trace.go:171","msg":"trace[239285921] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:831; }","duration":"235.221479ms","start":"2026-05-01T00:52:51.30849Z","end":"2026-05-01T00:52:51.543711Z","steps":["trace[239285921] 'agreement among raft nodes before linearized reading' (duration: 234.465688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.542696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.946446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:51.543902Z","caller":"traceutil/trace.go:171","msg":"trace[151737531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:831; }","duration":"594.173736ms","start":"2026-05-01T00:52:50.949714Z","end":"2026-05-01T00:52:51.543888Z","steps":["trace[151737531] 'agreement among raft nodes before linearized reading' (duration: 592.959947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:51.544646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:52:50.949699Z","time spent":"594.222928ms","remote":"127.0.0.1:48916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-01T00:52:55.629209Z","caller":"traceutil/trace.go:171","msg":"trace[1702660914] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"104.429373ms","start":"2026-05-01T00:52:55.524755Z","end":"2026-05-01T00:52:55.629184Z","steps":["trace[1702660914] 'process raft request' (duration: 71.091016ms)","trace[1702660914] 'compare' (duration: 32.719292ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:55.83452Z","caller":"traceutil/trace.go:171","msg":"trace[241068367] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"197.852473ms","start":"2026-05-01T00:52:55.636643Z","end":"2026-05-01T00:52:55.834496Z","steps":["trace[241068367] 'process raft request' (duration: 197.770559ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:55.834691Z","caller":"traceutil/trace.go:171","msg":"trace[71532415] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"200.5822ms","start":"2026-05-01T00:52:55.633984Z","end":"2026-05-01T00:52:55.834567Z","steps":["trace[71532415] 'process raft request' (duration: 109.98289ms)","trace[71532415] 'compare' (duration: 90.286708ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:55.834767Z","caller":"traceutil/trace.go:171","msg":"trace[418687278] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"198.005479ms","start":"2026-05-01T00:52:55.636743Z","end":"2026-05-01T00:52:55.834749Z","steps":["trace[418687278] 'process raft request' (duration: 197.713737ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:55.834839Z","caller":"traceutil/trace.go:171","msg":"trace[510107873] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"195.361025ms","start":"2026-05-01T00:52:55.63945Z","end":"2026-05-01T00:52:55.834811Z","steps":["trace[510107873] 'process raft request' (duration: 195.285082ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:55.834824Z","caller":"traceutil/trace.go:171","msg":"trace[1062446083] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"198.060411ms","start":"2026-05-01T00:52:55.636741Z","end":"2026-05-01T00:52:55.834801Z","steps":["trace[1062446083] 'process raft request' (duration: 197.877063ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:55.875615Z","caller":"traceutil/trace.go:171","msg":"trace[1215951512] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:889; }","duration":"171.190258ms","start":"2026-05-01T00:52:55.70441Z","end":"2026-05-01T00:52:55.8756Z","steps":["trace[1215951512] 'read index received' (duration: 39.654384ms)","trace[1215951512] 'applied index is now lower than readState.Index' (duration: 131.535164ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:52:55.875638Z","caller":"traceutil/trace.go:171","msg":"trace[1088184280] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"204.750083ms","start":"2026-05-01T00:52:55.670846Z","end":"2026-05-01T00:52:55.875596Z","steps":["trace[1088184280] 'process raft request' (duration: 204.509104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:52:55.875739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.311413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:52:55.875767Z","caller":"traceutil/trace.go:171","msg":"trace[617703896] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:861; }","duration":"171.357445ms","start":"2026-05-01T00:52:55.704401Z","end":"2026-05-01T00:52:55.875759Z","steps":["trace[617703896] 'agreement among raft nodes before linearized reading' (duration: 171.275191ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:52:55.875845Z","caller":"traceutil/trace.go:171","msg":"trace[132785421] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"204.807256ms","start":"2026-05-01T00:52:55.671008Z","end":"2026-05-01T00:52:55.875815Z","steps":["trace[132785421] 'process raft request' (duration: 204.504504ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:53:12.455265Z","caller":"traceutil/trace.go:171","msg":"trace[709821866] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"105.448388ms","start":"2026-05-01T00:53:12.349797Z","end":"2026-05-01T00:53:12.455245Z","steps":["trace[709821866] 'process raft request' (duration: 105.319033ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:53:52.247153Z","caller":"traceutil/trace.go:171","msg":"trace[2131536599] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1212; }","duration":"133.738131ms","start":"2026-05-01T00:53:52.11339Z","end":"2026-05-01T00:53:52.247129Z","steps":["trace[2131536599] 'read index received' (duration: 7.955627ms)","trace[2131536599] 'applied index is now lower than readState.Index' (duration: 125.781654ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:53:52.24731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.882045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:53:52.247352Z","caller":"traceutil/trace.go:171","msg":"trace[1467449559] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1168; }","duration":"133.969476ms","start":"2026-05-01T00:53:52.11337Z","end":"2026-05-01T00:53:52.24734Z","steps":["trace[1467449559] 'agreement among raft nodes before linearized reading' (duration: 133.857124ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:53:52.247697Z","caller":"traceutil/trace.go:171","msg":"trace[437457188] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"136.823717ms","start":"2026-05-01T00:53:52.110862Z","end":"2026-05-01T00:53:52.247685Z","steps":["trace[437457188] 'process raft request' (duration: 136.116979ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:53:52.247825Z","caller":"traceutil/trace.go:171","msg":"trace[498720715] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"135.893574ms","start":"2026-05-01T00:53:52.111921Z","end":"2026-05-01T00:53:52.247814Z","steps":["trace[498720715] 'process raft request' (duration: 135.166876ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:54:03.122188Z","caller":"traceutil/trace.go:171","msg":"trace[1083257971] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"158.789105ms","start":"2026-05-01T00:54:02.96338Z","end":"2026-05-01T00:54:03.122169Z","steps":["trace[1083257971] 'process raft request' (duration: 158.649082ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:54:26.41907Z","caller":"traceutil/trace.go:171","msg":"trace[407707483] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"130.245567ms","start":"2026-05-01T00:54:26.288791Z","end":"2026-05-01T00:54:26.419036Z","steps":["trace[407707483] 'process raft request' (duration: 129.89738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:54:30.690673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.777425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T00:54:30.690825Z","caller":"traceutil/trace.go:171","msg":"trace[288517035] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:1293; }","duration":"104.958399ms","start":"2026-05-01T00:54:30.585848Z","end":"2026-05-01T00:54:30.690806Z","steps":["trace[288517035] 'range keys from in-memory index tree' (duration: 104.727095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:54:30.690673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.354209ms","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-05-01T00:54:30.690974Z","caller":"traceutil/trace.go:171","msg":"trace[929531600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"112.715787ms","start":"2026-05-01T00:54:30.57823Z","end":"2026-05-01T00:54:30.690945Z","steps":["trace[929531600] 'range keys from in-memory index tree' (duration: 112.143815ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:54:31.67124Z","caller":"traceutil/trace.go:171","msg":"trace[256027452] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1354; }","duration":"175.230249ms","start":"2026-05-01T00:54:31.495987Z","end":"2026-05-01T00:54:31.671217Z","steps":["trace[256027452] 'read index received' (duration: 29.466805ms)","trace[256027452] 'applied index is now lower than readState.Index' (duration: 145.762124ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:54:31.671267Z","caller":"traceutil/trace.go:171","msg":"trace[1623278731] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"175.779351ms","start":"2026-05-01T00:54:31.49546Z","end":"2026-05-01T00:54:31.67124Z","steps":["trace[1623278731] 'process raft request' (duration: 125.149748ms)","trace[1623278731] 'compare' (duration: 50.362707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T00:54:31.671429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.436054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-05-01T00:54:31.671468Z","caller":"traceutil/trace.go:171","msg":"trace[601804845] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1300; }","duration":"175.523635ms","start":"2026-05-01T00:54:31.495934Z","end":"2026-05-01T00:54:31.671458Z","steps":["trace[601804845] 'agreement among raft nodes before linearized reading' (duration: 175.380222ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:54:39.552322Z","caller":"traceutil/trace.go:171","msg":"trace[327279498] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"499.953571ms","start":"2026-05-01T00:54:39.052342Z","end":"2026-05-01T00:54:39.552296Z","steps":["trace[327279498] 'process raft request' (duration: 499.817978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:54:39.55253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:54:39.052331Z","time spent":"500.123584ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T00:54:47.855452Z","caller":"traceutil/trace.go:171","msg":"trace[1582598793] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"241.343696ms","start":"2026-05-01T00:54:47.614087Z","end":"2026-05-01T00:54:47.855431Z","steps":["trace[1582598793] 'process raft request' (duration: 237.84685ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:54:48.80407Z","caller":"traceutil/trace.go:171","msg":"trace[1019204677] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"268.527446ms","start":"2026-05-01T00:54:48.535519Z","end":"2026-05-01T00:54:48.804046Z","steps":["trace[1019204677] 'process raft request' (duration: 268.353812ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:54:48.862741Z","caller":"traceutil/trace.go:171","msg":"trace[1540871862] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1448; }","duration":"258.085808ms","start":"2026-05-01T00:54:48.604634Z","end":"2026-05-01T00:54:48.86272Z","steps":["trace[1540871862] 'read index received' (duration: 199.347275ms)","trace[1540871862] 'applied index is now lower than readState.Index' (duration: 58.737513ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:54:48.862835Z","caller":"traceutil/trace.go:171","msg":"trace[881085683] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"324.001375ms","start":"2026-05-01T00:54:48.538806Z","end":"2026-05-01T00:54:48.862808Z","steps":["trace[881085683] 'process raft request' (duration: 323.791701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:54:48.862956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T00:54:48.538786Z","time spent":"324.107138ms","remote":"127.0.0.1:49178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T00:54:48.863009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.366493ms","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-05-01T00:54:48.86308Z","caller":"traceutil/trace.go:171","msg":"trace[1217997217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1390; }","duration":"258.497005ms","start":"2026-05-01T00:54:48.604567Z","end":"2026-05-01T00:54:48.863064Z","steps":["trace[1217997217] 'agreement among raft nodes before linearized reading' (duration: 258.279161ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T00:55:16.20377Z","caller":"traceutil/trace.go:171","msg":"trace[1609095125] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1551; }","duration":"161.385ms","start":"2026-05-01T00:55:16.042351Z","end":"2026-05-01T00:55:16.203736Z","steps":["trace[1609095125] 'read index received' (duration: 161.146846ms)","trace[1609095125] 'applied index is now lower than readState.Index' (duration: 236.704µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T00:55:16.203847Z","caller":"traceutil/trace.go:171","msg":"trace[1157783792] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"203.827603ms","start":"2026-05-01T00:55:15.999978Z","end":"2026-05-01T00:55:16.203805Z","steps":["trace[1157783792] 'process raft request' (duration: 203.526467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:55:16.203995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.635835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-s6z8vr\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-05-01T00:55:16.204084Z","caller":"traceutil/trace.go:171","msg":"trace[1559433883] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-s6z8vr; range_end:; response_count:1; response_revision:1485; }","duration":"161.768057ms","start":"2026-05-01T00:55:16.042298Z","end":"2026-05-01T00:55:16.204066Z","steps":["trace[1559433883] 'agreement among raft nodes before linearized reading' (duration: 161.614024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:55:16.204097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.364728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/kubeadm-certs\" ","response":"range_response_count:1 size:11447"} {"level":"info","ts":"2026-05-01T00:55:16.204179Z","caller":"traceutil/trace.go:171","msg":"trace[2002797967] range","detail":"{range_begin:/registry/secrets/kube-system/kubeadm-certs; range_end:; response_count:1; response_revision:1485; }","duration":"146.491071ms","start":"2026-05-01T00:55:16.057662Z","end":"2026-05-01T00:55:16.204153Z","steps":["trace[2002797967] 'agreement among raft nodes before linearized reading' (duration: 146.300127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:55:16.409076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.475367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-05-01T00:55:16.409167Z","caller":"traceutil/trace.go:171","msg":"trace[1211895071] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:1485; }","duration":"123.606639ms","start":"2026-05-01T00:55:16.285537Z","end":"2026-05-01T00:55:16.409143Z","steps":["trace[1211895071] 'range keys from in-memory index tree' (duration: 123.380265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T00:55:16.409352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.89682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1\" ","response":"range_response_count:1 size:317290"} {"level":"info","ts":"2026-05-01T00:55:16.409447Z","caller":"traceutil/trace.go:171","msg":"trace[792126556] range","detail":"{range_begin:/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1; range_end:; response_count:1; response_revision:1485; }","duration":"126.048872ms","start":"2026-05-01T00:55:16.283379Z","end":"2026-05-01T00:55:16.409428Z","steps":["trace[792126556] 'range keys from in-memory index tree' (duration: 125.647015ms)"],"step_count":1}