{"level":"warn","ts":"2026-04-27T16:33:43.373751Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T16:33:43.373837Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-27T16:33:43.373905Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T16:33:43.373917Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-27T16:33:43.373942Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-27T16:33:43.374598Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-04-27T16:33:43.374675Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-27T16:33:43.382361Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.483075ms"} {"level":"info","ts":"2026-04-27T16:33:43.394045Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-04-27T16:33:43.394147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T16:33:43.394179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-04-27T16:33:43.394198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T16:33:43.394211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-04-27T16:33:43.394257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-04-27T16:33:43.402784Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T16:33:43.406867Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T16:33:43.407954Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-27T16:33:43.411853Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T16:33:43.412781Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T16:33:43.413044Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-27T16:33:43.413659Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-27T16:33:43.41369Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-27T16:33:43.415341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-04-27T16:33:43.415518Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-27T16:33:43.422515Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-27T16:33:43.422792Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-27T16:33:43.422818Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-27T16:33:43.422906Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T16:33:43.422928Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T16:33:44.195154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T16:33:44.195296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T16:33:44.195352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-04-27T16:33:44.195378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-04-27T16:33:44.195389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-27T16:33:44.195407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-04-27T16:33:44.195423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-27T16:33:44.196827Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:33:44.197637Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T16:33:44.197864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T16:33:44.198193Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T16:33:44.198211Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:33:44.198417Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T16:33:44.198498Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T16:33:44.198514Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:33:44.198572Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:33:44.200937Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T16:33:44.211938Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-04-27T16:33:57.009735Z","caller":"traceutil/trace.go:171","msg":"trace[1414526938] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"121.361066ms","start":"2026-04-27T16:33:56.888333Z","end":"2026-04-27T16:33:57.009694Z","steps":["trace[1414526938] 'process raft request' (duration: 121.029623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:33:57.318143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.966087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:33:57.318314Z","caller":"traceutil/trace.go:171","msg":"trace[595603448] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"301.601594ms","start":"2026-04-27T16:33:57.016686Z","end":"2026-04-27T16:33:57.318288Z","steps":["trace[595603448] 'process raft request' (duration: 102.711002ms)","trace[595603448] 'compare' (duration: 197.634524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:33:57.318385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:33:57.016667Z","time spent":"301.686997ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:33:57.325493Z","caller":"traceutil/trace.go:171","msg":"trace[896834012] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"303.641184ms","start":"2026-04-27T16:33:57.021822Z","end":"2026-04-27T16:33:57.325463Z","steps":["trace[896834012] 'process raft request' (duration: 303.223308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:33:57.32568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:33:57.021809Z","time spent":"303.769478ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:33:57.482957Z","caller":"traceutil/trace.go:171","msg":"trace[1401836738] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"109.694051ms","start":"2026-04-27T16:33:57.373208Z","end":"2026-04-27T16:33:57.482902Z","steps":["trace[1401836738] 'process raft request' (duration: 66.722044ms)","trace[1401836738] 'compare' (duration: 42.738438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:02.551802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113968448136558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:34:03.052766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113968448136558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:34:03.553902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113968448136558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:34:03.89827Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.202475925s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T16:34:03.898899Z","caller":"traceutil/trace.go:171","msg":"trace[907458340] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"2.203180082s","start":"2026-04-27T16:34:01.695675Z","end":"2026-04-27T16:34:03.898855Z","steps":["trace[907458340] 'process raft request' (duration: 2.202973984s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:03.899159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:01.695659Z","time spent":"2.203334258s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:03.914573Z","caller":"traceutil/trace.go:171","msg":"trace[2075033216] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"2.217798211s","start":"2026-04-27T16:34:01.696722Z","end":"2026-04-27T16:34:03.91452Z","steps":["trace[2075033216] 'process raft request' (duration: 2.217548511s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:03.914835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:01.696711Z","time spent":"2.217976378s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:03.960847Z","caller":"traceutil/trace.go:171","msg":"trace[371418393] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:390; }","duration":"1.909313049s","start":"2026-04-27T16:34:02.051481Z","end":"2026-04-27T16:34:03.960794Z","steps":["trace[371418393] 'read index received' (duration: 1.847075093s)","trace[371418393] 'applied index is now lower than readState.Index' (duration: 62.235696ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:03.96138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.909614653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:34:03.961406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.40032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:34:03.961444Z","caller":"traceutil/trace.go:171","msg":"trace[1347895774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:379; }","duration":"1.909994747s","start":"2026-04-27T16:34:02.051433Z","end":"2026-04-27T16:34:03.961427Z","steps":["trace[1347895774] 'agreement among raft nodes before linearized reading' (duration: 1.909600141s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:03.96145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.218229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:34:03.961481Z","caller":"traceutil/trace.go:171","msg":"trace[1303627918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:379; }","duration":"425.632829ms","start":"2026-04-27T16:34:03.535826Z","end":"2026-04-27T16:34:03.961459Z","steps":["trace[1303627918] 'agreement among raft nodes before linearized reading' (duration: 425.318797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:03.961497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:02.051411Z","time spent":"1.91007423s","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:34:03.961509Z","caller":"traceutil/trace.go:171","msg":"trace[1090154726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:379; }","duration":"616.554482ms","start":"2026-04-27T16:34:03.344937Z","end":"2026-04-27T16:34:03.961492Z","steps":["trace[1090154726] 'agreement among raft nodes before linearized reading' (duration: 616.154107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:03.961524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:03.53576Z","time spent":"425.756083ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:34:03.96156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:03.344911Z","time spent":"616.634835ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T16:34:04.782388Z","caller":"traceutil/trace.go:171","msg":"trace[1083515575] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"526.560748ms","start":"2026-04-27T16:34:04.255789Z","end":"2026-04-27T16:34:04.78235Z","steps":["trace[1083515575] 'process raft request' (duration: 526.371701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:04.782595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:04.255765Z","time spent":"526.726605ms","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4361,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:06.57618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.07859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:06.576324Z","caller":"traceutil/trace.go:171","msg":"trace[1903610193] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:396; }","duration":"657.122275ms","start":"2026-04-27T16:34:05.919177Z","end":"2026-04-27T16:34:06.576299Z","steps":["trace[1903610193] 'read index received' (duration: 48.839161ms)","trace[1903610193] 'applied index is now lower than readState.Index' (duration: 608.281374ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:06.576439Z","caller":"traceutil/trace.go:171","msg":"trace[1375184610] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"657.857222ms","start":"2026-04-27T16:34:05.918568Z","end":"2026-04-27T16:34:06.576425Z","steps":["trace[1375184610] 'process raft request' (duration: 406.425258ms)","trace[1375184610] 'compare' (duration: 250.577281ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:06.576593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:05.918551Z","time spent":"657.916565ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:06.576964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.694752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:34:06.577077Z","caller":"traceutil/trace.go:171","msg":"trace[386843259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:384; }","duration":"579.861168ms","start":"2026-04-27T16:34:05.997186Z","end":"2026-04-27T16:34:06.577047Z","steps":["trace[386843259] 'agreement among raft nodes before linearized reading' (duration: 579.615778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:06.57716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.419418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-27T16:34:06.577295Z","caller":"traceutil/trace.go:171","msg":"trace[879449296] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:384; }","duration":"255.599496ms","start":"2026-04-27T16:34:06.321654Z","end":"2026-04-27T16:34:06.577254Z","steps":["trace[879449296] 'agreement among raft nodes before linearized reading' (duration: 255.380357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:06.577318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.018572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:34:06.577422Z","caller":"traceutil/trace.go:171","msg":"trace[815838883] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"544.169687ms","start":"2026-04-27T16:34:06.033231Z","end":"2026-04-27T16:34:06.577401Z","steps":["trace[815838883] 'agreement among raft nodes before linearized reading' (duration: 543.99039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:06.577474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:06.03321Z","time spent":"544.254161ms","remote":"127.0.0.1:55426","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":"warn","ts":"2026-04-27T16:34:06.57731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:05.997162Z","time spent":"580.103057ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:34:06.577563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.28449ms","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-04-27T16:34:06.577724Z","caller":"traceutil/trace.go:171","msg":"trace[252380197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:384; }","duration":"658.570972ms","start":"2026-04-27T16:34:05.919127Z","end":"2026-04-27T16:34:06.577698Z","steps":["trace[252380197] 'agreement among raft nodes before linearized reading' (duration: 658.056931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:06.577803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:05.919108Z","time spent":"658.682496ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:34:06.82671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.951055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:06.827363Z","caller":"traceutil/trace.go:171","msg":"trace[1568640354] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"243.850719ms","start":"2026-04-27T16:34:06.583482Z","end":"2026-04-27T16:34:06.827333Z","steps":["trace[1568640354] 'process raft request' (duration: 126.166625ms)","trace[1568640354] 'compare' (duration: 116.81349ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:06.827417Z","caller":"traceutil/trace.go:171","msg":"trace[1703655959] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"235.475263ms","start":"2026-04-27T16:34:06.59192Z","end":"2026-04-27T16:34:06.827395Z","steps":["trace[1703655959] 'process raft request' (duration: 235.40887ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:06.827525Z","caller":"traceutil/trace.go:171","msg":"trace[791674007] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"242.361451ms","start":"2026-04-27T16:34:06.585156Z","end":"2026-04-27T16:34:06.827518Z","steps":["trace[791674007] 'process raft request' (duration: 242.090871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:07.577441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.865162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:07.577612Z","caller":"traceutil/trace.go:171","msg":"trace[1958789830] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"523.40791ms","start":"2026-04-27T16:34:07.054177Z","end":"2026-04-27T16:34:07.577585Z","steps":["trace[1958789830] 'read index received' (duration: 182.232396ms)","trace[1958789830] 'applied index is now lower than readState.Index' (duration: 341.173574ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:07.577622Z","caller":"traceutil/trace.go:171","msg":"trace[450057024] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"742.328011ms","start":"2026-04-27T16:34:06.835266Z","end":"2026-04-27T16:34:07.577594Z","steps":["trace[450057024] 'process raft request' (duration: 401.215609ms)","trace[450057024] 'compare' (duration: 340.655244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:07.577756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:06.835245Z","time spent":"742.455538ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:07.577812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.62137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:07.577857Z","caller":"traceutil/trace.go:171","msg":"trace[78984417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"523.701973ms","start":"2026-04-27T16:34:07.05414Z","end":"2026-04-27T16:34:07.577842Z","steps":["trace[78984417] 'agreement among raft nodes before linearized reading' (duration: 523.578239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:07.577872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.601277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-27T16:34:07.577953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:07.054124Z","time spent":"523.818927ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:34:07.577981Z","caller":"traceutil/trace.go:171","msg":"trace[1193721901] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:389; }","duration":"296.751153ms","start":"2026-04-27T16:34:07.281204Z","end":"2026-04-27T16:34:07.577955Z","steps":["trace[1193721901] 'agreement among raft nodes before linearized reading' (duration: 296.546055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:08.056208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.255102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:08.056539Z","caller":"traceutil/trace.go:171","msg":"trace[179468508] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"471.78117ms","start":"2026-04-27T16:34:07.584676Z","end":"2026-04-27T16:34:08.056457Z","steps":["trace[179468508] 'process raft request' (duration: 471.614953ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:08.056656Z","caller":"traceutil/trace.go:171","msg":"trace[1066880147] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"390.49511ms","start":"2026-04-27T16:34:07.666143Z","end":"2026-04-27T16:34:08.056638Z","steps":["trace[1066880147] 'process raft request' (duration: 390.231079ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:08.056591Z","caller":"traceutil/trace.go:171","msg":"trace[1020991216] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"472.344661ms","start":"2026-04-27T16:34:07.584212Z","end":"2026-04-27T16:34:08.056556Z","steps":["trace[1020991216] 'process raft request' (duration: 331.663713ms)","trace[1020991216] 'compare' (duration: 140.098196ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:08.056844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:07.584184Z","time spent":"472.636445ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:08.056844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:07.666112Z","time spent":"390.626295ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:08.056951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:07.584655Z","time spent":"472.02594ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:34:08.056563Z","caller":"traceutil/trace.go:171","msg":"trace[1584772103] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"469.865115ms","start":"2026-04-27T16:34:07.586656Z","end":"2026-04-27T16:34:08.056521Z","steps":["trace[1584772103] 'process raft request' (duration: 469.681098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:08.057376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:07.586639Z","time spent":"470.699718ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:09.133713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.760978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:09.134208Z","caller":"traceutil/trace.go:171","msg":"trace[1586703145] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:409; }","duration":"1.069459786s","start":"2026-04-27T16:34:08.064724Z","end":"2026-04-27T16:34:09.134184Z","steps":["trace[1586703145] 'read index received' (duration: 91.194621ms)","trace[1586703145] 'applied index is now lower than readState.Index' (duration: 978.263975ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:09.134329Z","caller":"traceutil/trace.go:171","msg":"trace[697744569] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"1.069743127s","start":"2026-04-27T16:34:08.064572Z","end":"2026-04-27T16:34:09.134315Z","steps":["trace[697744569] 'process raft request' (duration: 915.273681ms)","trace[697744569] 'compare' (duration: 153.54718ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:09.134402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:08.064559Z","time spent":"1.06980906s","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:34:09.134927Z","caller":"traceutil/trace.go:171","msg":"trace[178352595] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"1.070286658s","start":"2026-04-27T16:34:08.064628Z","end":"2026-04-27T16:34:09.134915Z","steps":["trace[178352595] 'process raft request' (duration: 1.069222197s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:09.135347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070616122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-27T16:34:09.135395Z","caller":"traceutil/trace.go:171","msg":"trace[19245517] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:399; }","duration":"1.070692715s","start":"2026-04-27T16:34:08.064691Z","end":"2026-04-27T16:34:09.135383Z","steps":["trace[19245517] 'agreement among raft nodes before linearized reading' (duration: 1.070592961s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:09.135435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:08.064681Z","time spent":"1.070746487s","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-04-27T16:34:09.135762Z","caller":"traceutil/trace.go:171","msg":"trace[1238437162] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"1.070213275s","start":"2026-04-27T16:34:08.065532Z","end":"2026-04-27T16:34:09.135746Z","steps":["trace[1238437162] 'process raft request' (duration: 1.068406604s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:09.135878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:08.065501Z","time spent":"1.070340371s","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:34:09.136358Z","caller":"traceutil/trace.go:171","msg":"trace[561349845] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"1.070278378s","start":"2026-04-27T16:34:08.066066Z","end":"2026-04-27T16:34:09.136345Z","steps":["trace[561349845] 'process raft request' (duration: 1.067983468s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:09.136522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:08.06606Z","time spent":"1.070375952s","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:09.136833Z","caller":"traceutil/trace.go:171","msg":"trace[657239526] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"1.070554568s","start":"2026-04-27T16:34:08.066263Z","end":"2026-04-27T16:34:09.136817Z","steps":["trace[657239526] 'process raft request' (duration: 1.067869253s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:09.136944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:08.066248Z","time spent":"1.070621151s","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:09.138793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.020854ms","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-04-27T16:34:09.13884Z","caller":"traceutil/trace.go:171","msg":"trace[1528639342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:399; }","duration":"532.144799ms","start":"2026-04-27T16:34:08.606683Z","end":"2026-04-27T16:34:09.138828Z","steps":["trace[1528639342] 'agreement among raft nodes before linearized reading' (duration: 532.066206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:09.138868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:08.606653Z","time spent":"532.209032ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:34:09.139221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.564414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:34:09.13932Z","caller":"traceutil/trace.go:171","msg":"trace[834025314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:399; }","duration":"306.70613ms","start":"2026-04-27T16:34:08.832576Z","end":"2026-04-27T16:34:09.139282Z","steps":["trace[834025314] 'agreement among raft nodes before linearized reading' (duration: 306.492131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:09.139376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:08.832555Z","time spent":"306.814433ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:34:09.139469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.535161ms","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-04-27T16:34:09.139508Z","caller":"traceutil/trace.go:171","msg":"trace[907756111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:399; }","duration":"306.582533ms","start":"2026-04-27T16:34:08.832915Z","end":"2026-04-27T16:34:09.139497Z","steps":["trace[907756111] 'agreement among raft nodes before linearized reading' (duration: 306.51262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:09.139537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:08.832908Z","time spent":"306.622275ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:34:09.135006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:08.064604Z","time spent":"1.070358001s","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:09.999961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.017277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:10.000395Z","caller":"traceutil/trace.go:171","msg":"trace[503288668] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"851.595271ms","start":"2026-04-27T16:34:09.14877Z","end":"2026-04-27T16:34:10.000365Z","steps":["trace[503288668] 'process raft request' (duration: 346.46187ms)","trace[503288668] 'compare' (duration: 503.728466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:10.00049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:09.148748Z","time spent":"851.697485ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:10.000575Z","caller":"traceutil/trace.go:171","msg":"trace[118693885] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"850.910053ms","start":"2026-04-27T16:34:09.149644Z","end":"2026-04-27T16:34:10.000554Z","steps":["trace[118693885] 'process raft request' (duration: 850.545559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:10.000837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:09.149632Z","time spent":"851.053828ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:10.001027Z","caller":"traceutil/trace.go:171","msg":"trace[507890031] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:415; }","duration":"789.479893ms","start":"2026-04-27T16:34:09.21152Z","end":"2026-04-27T16:34:10.001Z","steps":["trace[507890031] 'read index received' (duration: 283.604163ms)","trace[507890031] 'applied index is now lower than readState.Index' (duration: 505.8749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:10.001204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.575151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-27T16:34:10.001242Z","caller":"traceutil/trace.go:171","msg":"trace[1678425344] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:405; }","duration":"850.672055ms","start":"2026-04-27T16:34:09.150557Z","end":"2026-04-27T16:34:10.001229Z","steps":["trace[1678425344] 'agreement among raft nodes before linearized reading' (duration: 850.554881ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:10.001258Z","caller":"traceutil/trace.go:171","msg":"trace[1595588504] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"845.127328ms","start":"2026-04-27T16:34:09.156114Z","end":"2026-04-27T16:34:10.001241Z","steps":["trace[1595588504] 'process raft request' (duration: 844.749963ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:10.001214Z","caller":"traceutil/trace.go:171","msg":"trace[903314587] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"851.186275ms","start":"2026-04-27T16:34:09.149982Z","end":"2026-04-27T16:34:10.001168Z","steps":["trace[903314587] 'process raft request' (duration: 850.473876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:10.001277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:09.150548Z","time spent":"850.720547ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4218,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-27T16:34:10.001321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:09.156108Z","time spent":"845.17981ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:10.001347Z","caller":"traceutil/trace.go:171","msg":"trace[242636573] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"827.622864ms","start":"2026-04-27T16:34:09.173709Z","end":"2026-04-27T16:34:10.001332Z","steps":["trace[242636573] 'process raft request' (duration: 827.24763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:10.001412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:09.14994Z","time spent":"851.376422ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:10.001486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:09.173678Z","time spent":"827.732968ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:10.563388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.118111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:10.564142Z","caller":"traceutil/trace.go:171","msg":"trace[898872700] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"1.349990581s","start":"2026-04-27T16:34:09.214125Z","end":"2026-04-27T16:34:10.564116Z","steps":["trace[898872700] 'process raft request' (duration: 936.001895ms)","trace[898872700] 'compare' (duration: 412.700855ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:10.564319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:09.214078Z","time spent":"1.35020434s","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4013,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:10.616746Z","caller":"traceutil/trace.go:171","msg":"trace[1401726586] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"604.246008ms","start":"2026-04-27T16:34:10.012472Z","end":"2026-04-27T16:34:10.616718Z","steps":["trace[1401726586] 'process raft request' (duration: 603.986578ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:10.616761Z","caller":"traceutil/trace.go:171","msg":"trace[1978377657] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:420; }","duration":"563.11789ms","start":"2026-04-27T16:34:10.053599Z","end":"2026-04-27T16:34:10.616716Z","steps":["trace[1978377657] 'read index received' (duration: 96.620401ms)","trace[1978377657] 'applied index is now lower than readState.Index' (duration: 466.494639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:10.61698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:10.012439Z","time spent":"604.385733ms","remote":"127.0.0.1:55674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:10.617057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.423291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:10.618343Z","caller":"traceutil/trace.go:171","msg":"trace[1931180643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"563.693414ms","start":"2026-04-27T16:34:10.053526Z","end":"2026-04-27T16:34:10.61722Z","steps":["trace[1931180643] 'agreement among raft nodes before linearized reading' (duration: 563.349249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:10.618424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:10.053317Z","time spent":"565.090758ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:34:11.172648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.596915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-27T16:34:11.172822Z","caller":"traceutil/trace.go:171","msg":"trace[403407412] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:422; }","duration":"555.950937ms","start":"2026-04-27T16:34:10.616854Z","end":"2026-04-27T16:34:11.172805Z","steps":["trace[403407412] 'read index received' (duration: 58.085632ms)","trace[403407412] 'applied index is now lower than readState.Index' (duration: 497.864425ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:11.172977Z","caller":"traceutil/trace.go:171","msg":"trace[1339598067] transaction","detail":"{read_only:false; number_of_response:1; response_revision:408; }","duration":"605.595018ms","start":"2026-04-27T16:34:10.567375Z","end":"2026-04-27T16:34:11.17297Z","steps":["trace[1339598067] 'process raft request' (duration: 107.545817ms)","trace[1339598067] 'compare' (duration: 497.318083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:11.173022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:10.56735Z","time spent":"605.652961ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-04-27T16:34:11.173144Z","caller":"traceutil/trace.go:171","msg":"trace[1964224763] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"604.970405ms","start":"2026-04-27T16:34:10.568167Z","end":"2026-04-27T16:34:11.173138Z","steps":["trace[1964224763] 'process raft request' (duration: 604.57626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:11.173179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:10.568158Z","time spent":"604.997966ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":721,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:11.173481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013470866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:34:11.173496Z","caller":"traceutil/trace.go:171","msg":"trace[2114237488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"1.013534889s","start":"2026-04-27T16:34:10.159956Z","end":"2026-04-27T16:34:11.173491Z","steps":["trace[2114237488] 'agreement among raft nodes before linearized reading' (duration: 1.013293869s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:11.17351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:10.159928Z","time spent":"1.0135798s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T16:34:11.174223Z","caller":"traceutil/trace.go:171","msg":"trace[1348979360] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"550.266925ms","start":"2026-04-27T16:34:10.623944Z","end":"2026-04-27T16:34:11.174211Z","steps":["trace[1348979360] 'process raft request' (duration: 550.050356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:11.174264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:10.623917Z","time spent":"550.326087ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:11.174371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.72569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:11.17445Z","caller":"traceutil/trace.go:171","msg":"trace[750014018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"554.858594ms","start":"2026-04-27T16:34:10.619571Z","end":"2026-04-27T16:34:11.17443Z","steps":["trace[750014018] 'agreement among raft nodes before linearized reading' (duration: 554.698608ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:11.17448Z","caller":"traceutil/trace.go:171","msg":"trace[354716687] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"549.703173ms","start":"2026-04-27T16:34:10.624769Z","end":"2026-04-27T16:34:11.174472Z","steps":["trace[354716687] 'process raft request' (duration: 549.358429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:11.174514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:10.624747Z","time spent":"549.750605ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:11.174507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:10.619547Z","time spent":"554.947488ms","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:34:11.174651Z","caller":"traceutil/trace.go:171","msg":"trace[1729815098] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"548.224734ms","start":"2026-04-27T16:34:10.626419Z","end":"2026-04-27T16:34:11.174644Z","steps":["trace[1729815098] 'process raft request' (duration: 547.770027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:11.174686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:10.626408Z","time spent":"548.259096ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:21.519714Z","caller":"traceutil/trace.go:171","msg":"trace[1494635260] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"221.097757ms","start":"2026-04-27T16:34:21.298588Z","end":"2026-04-27T16:34:21.519686Z","steps":["trace[1494635260] 'read index received' (duration: 220.982723ms)","trace[1494635260] 'applied index is now lower than readState.Index' (duration: 114.334µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:21.519835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.240563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:34:21.519862Z","caller":"traceutil/trace.go:171","msg":"trace[1508237018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:481; }","duration":"221.324916ms","start":"2026-04-27T16:34:21.298528Z","end":"2026-04-27T16:34:21.519853Z","steps":["trace[1508237018] 'agreement among raft nodes before linearized reading' (duration: 221.257683ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:21.519829Z","caller":"traceutil/trace.go:171","msg":"trace[776416824] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"274.975469ms","start":"2026-04-27T16:34:21.244814Z","end":"2026-04-27T16:34:21.519789Z","steps":["trace[776416824] 'process raft request' (duration: 274.75586ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:27.778198Z","caller":"traceutil/trace.go:171","msg":"trace[2080751778] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"228.278404ms","start":"2026-04-27T16:34:27.549895Z","end":"2026-04-27T16:34:27.778174Z","steps":["trace[2080751778] 'process raft request' (duration: 201.524149ms)","trace[2080751778] 'compare' (duration: 26.424422ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:27.77817Z","caller":"traceutil/trace.go:171","msg":"trace[1535697218] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"213.730735ms","start":"2026-04-27T16:34:27.564376Z","end":"2026-04-27T16:34:27.778106Z","steps":["trace[1535697218] 'read index received' (duration: 187.126557ms)","trace[1535697218] 'applied index is now lower than readState.Index' (duration: 26.602908ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:27.778587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.176652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:34:27.778737Z","caller":"traceutil/trace.go:171","msg":"trace[1505514720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"214.302658ms","start":"2026-04-27T16:34:27.56437Z","end":"2026-04-27T16:34:27.778673Z","steps":["trace[1505514720] 'agreement among raft nodes before linearized reading' (duration: 213.893621ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:27.924977Z","caller":"traceutil/trace.go:171","msg":"trace[37922475] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"276.711922ms","start":"2026-04-27T16:34:27.648224Z","end":"2026-04-27T16:34:27.924936Z","steps":["trace[37922475] 'process raft request' (duration: 267.602308ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:27.933624Z","caller":"traceutil/trace.go:171","msg":"trace[519309617] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"150.901606ms","start":"2026-04-27T16:34:27.782692Z","end":"2026-04-27T16:34:27.933594Z","steps":["trace[519309617] 'process raft request' (duration: 150.75149ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:28.347439Z","caller":"traceutil/trace.go:171","msg":"trace[561319615] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"367.908245ms","start":"2026-04-27T16:34:27.979492Z","end":"2026-04-27T16:34:28.3474Z","steps":["trace[561319615] 'process raft request' (duration: 270.65199ms)","trace[561319615] 'compare' (duration: 97.064526ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:28.348504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:27.979468Z","time spent":"368.068511ms","remote":"127.0.0.1:55524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:34:28.552128Z","caller":"traceutil/trace.go:171","msg":"trace[259640526] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"499.926333ms","start":"2026-04-27T16:34:28.051614Z","end":"2026-04-27T16:34:28.55154Z","steps":["trace[259640526] 'read index received' (duration: 198.622672ms)","trace[259640526] 'applied index is now lower than readState.Index' (duration: 301.302361ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:28.552151Z","caller":"traceutil/trace.go:171","msg":"trace[1342170214] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"561.745176ms","start":"2026-04-27T16:34:27.990353Z","end":"2026-04-27T16:34:28.552098Z","steps":["trace[1342170214] 'process raft request' (duration: 465.115387ms)","trace[1342170214] 'compare' (duration: 95.775424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:28.552334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:27.990314Z","time spent":"561.927033ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:28.552361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.717175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:28.5524Z","caller":"traceutil/trace.go:171","msg":"trace[1566404265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"500.82634ms","start":"2026-04-27T16:34:28.051561Z","end":"2026-04-27T16:34:28.552387Z","steps":["trace[1566404265] 'agreement among raft nodes before linearized reading' (duration: 500.717805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:28.552449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:28.051542Z","time spent":"500.897823ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:34:28.552769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.104331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T16:34:28.552821Z","caller":"traceutil/trace.go:171","msg":"trace[2107876240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"441.185984ms","start":"2026-04-27T16:34:28.111618Z","end":"2026-04-27T16:34:28.552804Z","steps":["trace[2107876240] 'agreement among raft nodes before linearized reading' (duration: 441.019447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:28.552851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:28.111591Z","time spent":"441.253787ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:34:28.553003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.827972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:34:28.553012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.952516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T16:34:28.553104Z","caller":"traceutil/trace.go:171","msg":"trace[1757645085] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:590; }","duration":"191.04823ms","start":"2026-04-27T16:34:28.362027Z","end":"2026-04-27T16:34:28.553075Z","steps":["trace[1757645085] 'agreement among raft nodes before linearized reading' (duration: 190.80614ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:28.553101Z","caller":"traceutil/trace.go:171","msg":"trace[1072262926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"434.0723ms","start":"2026-04-27T16:34:28.119009Z","end":"2026-04-27T16:34:28.553081Z","steps":["trace[1072262926] 'agreement among raft nodes before linearized reading' (duration: 433.8159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:28.553151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:28.11899Z","time spent":"434.149963ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:34:29.344511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.449998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:29.344679Z","caller":"traceutil/trace.go:171","msg":"trace[1385921048] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"782.498211ms","start":"2026-04-27T16:34:28.562138Z","end":"2026-04-27T16:34:29.344636Z","steps":["trace[1385921048] 'process raft request' (duration: 639.778932ms)","trace[1385921048] 'compare' (duration: 142.106485ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:29.344767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:28.562094Z","time spent":"782.621637ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:29.451644Z","caller":"traceutil/trace.go:171","msg":"trace[135530935] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"396.367554ms","start":"2026-04-27T16:34:29.055235Z","end":"2026-04-27T16:34:29.451602Z","steps":["trace[135530935] 'read index received' (duration: 146.70813ms)","trace[135530935] 'applied index is now lower than readState.Index' (duration: 249.657914ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:29.451826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.565301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:29.451862Z","caller":"traceutil/trace.go:171","msg":"trace[165212198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"396.669086ms","start":"2026-04-27T16:34:29.05518Z","end":"2026-04-27T16:34:29.451849Z","steps":["trace[165212198] 'agreement among raft nodes before linearized reading' (duration: 396.571492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:29.451899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:29.055155Z","time spent":"396.734898ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:34:29.451927Z","caller":"traceutil/trace.go:171","msg":"trace[891503346] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"496.963783ms","start":"2026-04-27T16:34:28.95487Z","end":"2026-04-27T16:34:29.451834Z","steps":["trace[891503346] 'process raft request' (duration: 495.695422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:29.452347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:28.95483Z","time spent":"497.376339ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:29.657801Z","caller":"traceutil/trace.go:171","msg":"trace[474001583] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"138.498422ms","start":"2026-04-27T16:34:29.519261Z","end":"2026-04-27T16:34:29.65776Z","steps":["trace[474001583] 'process raft request' (duration: 119.794997ms)","trace[474001583] 'compare' (duration: 18.501987ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:30.230969Z","caller":"traceutil/trace.go:171","msg":"trace[1135723593] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"158.450534ms","start":"2026-04-27T16:34:30.072464Z","end":"2026-04-27T16:34:30.230915Z","steps":["trace[1135723593] 'process raft request' (duration: 84.635998ms)","trace[1135723593] 'compare' (duration: 73.595247ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:30.274794Z","caller":"traceutil/trace.go:171","msg":"trace[1454438491] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"208.617005ms","start":"2026-04-27T16:34:30.066139Z","end":"2026-04-27T16:34:30.274756Z","steps":["trace[1454438491] 'process raft request' (duration: 207.730329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:30.581438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.81038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:30.58183Z","caller":"traceutil/trace.go:171","msg":"trace[927941224] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"240.465987ms","start":"2026-04-27T16:34:30.341288Z","end":"2026-04-27T16:34:30.581754Z","steps":["trace[927941224] 'process raft request' (duration: 240.246098ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:30.582384Z","caller":"traceutil/trace.go:171","msg":"trace[1372286550] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"249.893993ms","start":"2026-04-27T16:34:30.332464Z","end":"2026-04-27T16:34:30.582358Z","steps":["trace[1372286550] 'process raft request' (duration: 115.072312ms)","trace[1372286550] 'compare' (duration: 133.681336ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:39.32464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00052436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:39.324788Z","caller":"traceutil/trace.go:171","msg":"trace[1668417067] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000739259s","start":"2026-04-27T16:34:37.324014Z","end":"2026-04-27T16:34:39.324753Z","steps":["trace[1668417067] 'agreement among raft nodes before linearized reading' (duration: 2.000560732s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:39.324859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:37.323993Z","time spent":"2.000853864s","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/27 16:34:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:41.050469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000159222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-27T16:34:41.05057Z","caller":"traceutil/trace.go:171","msg":"trace[1028082289] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000318998s","start":"2026-04-27T16:34:39.050228Z","end":"2026-04-27T16:34:41.050547Z","steps":["trace[1028082289] 'agreement among raft nodes before linearized reading' (duration: 2.000189043s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:41.050624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:39.050205Z","time spent":"2.000408142s","remote":"127.0.0.1:55162","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-04-27T16:34:42.574664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999400383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-27T16:34:42.574804Z","caller":"traceutil/trace.go:171","msg":"trace[1450928328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999631943s","start":"2026-04-27T16:34:37.575138Z","end":"2026-04-27T16:34:42.57477Z","steps":["trace[1450928328] 'agreement among raft nodes before linearized reading' (duration: 4.999454026s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:42.574891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:37.575109Z","time spent":"4.999760488s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/04/27 16:34:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:43.004288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998963814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:43.004451Z","caller":"traceutil/trace.go:171","msg":"trace[1459056953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999172623s","start":"2026-04-27T16:34:38.005245Z","end":"2026-04-27T16:34:43.004418Z","steps":["trace[1459056953] 'agreement among raft nodes before linearized reading' (duration: 4.998958523s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:43.004518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:38.005175Z","time spent":"4.999328449s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/27 16:34:43 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:43.051366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000113546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:43.051435Z","caller":"traceutil/trace.go:171","msg":"trace[218457746] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000216289s","start":"2026-04-27T16:34:41.051201Z","end":"2026-04-27T16:34:43.051417Z","steps":["trace[218457746] 'agreement among raft nodes before linearized reading' (duration: 2.000144237s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:43.051496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:41.051178Z","time spent":"2.000303223s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/27 16:34:43 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:43.638653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999519932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:43.638889Z","caller":"traceutil/trace.go:171","msg":"trace[1372336021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999817684s","start":"2026-04-27T16:34:38.639026Z","end":"2026-04-27T16:34:43.638844Z","steps":["trace[1372336021] 'agreement among raft nodes before linearized reading' (duration: 4.999562024s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:43.639014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:38.639001Z","time spent":"4.999978931s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/27 16:34:43 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:44.261154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:37.260009Z","time spent":"7.001133695s","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-27T16:34:44.508022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:37.507072Z","time spent":"7.00094226s","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-27T16:34:44.523411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:37.522291Z","time spent":"7.001115271s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-27T16:34:45.06193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000541288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-27T16:34:45.062026Z","caller":"traceutil/trace.go:171","msg":"trace[1351303834] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000714405s","start":"2026-04-27T16:34:43.061291Z","end":"2026-04-27T16:34:45.062006Z","steps":["trace[1351303834] 'agreement among raft nodes before linearized reading' (duration: 2.00059117s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:45.0621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:43.061263Z","time spent":"2.00082636s","remote":"127.0.0.1:55162","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-04-27T16:34:46.27366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.925866876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:46.27384Z","caller":"traceutil/trace.go:171","msg":"trace[2088093223] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; }","duration":"8.926115767s","start":"2026-04-27T16:34:37.347697Z","end":"2026-04-27T16:34:46.273812Z","steps":["trace[2088093223] 'agreement among raft nodes before linearized reading' (duration: 8.925881016s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:46.273978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:37.347684Z","time spent":"8.926278764s","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} 2026/04/27 16:34:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:47.06242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999719347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:47.06254Z","caller":"traceutil/trace.go:171","msg":"trace[1792174662] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999910205s","start":"2026-04-27T16:34:45.062601Z","end":"2026-04-27T16:34:47.062511Z","steps":["trace[1792174662] 'agreement among raft nodes before linearized reading' (duration: 1.999754829s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:47.062624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:45.062572Z","time spent":"2.00004016s","remote":"127.0.0.1:55154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/27 16:34:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:47.397432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999309946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:47.39756Z","caller":"traceutil/trace.go:171","msg":"trace[1749287027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"9.999504464s","start":"2026-04-27T16:34:37.398025Z","end":"2026-04-27T16:34:47.397529Z","steps":["trace[1749287027] 'agreement among raft nodes before linearized reading' (duration: 9.999354868s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:47.397618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:37.398Z","time spent":"9.999606108s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/04/27 16:34:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:47.57444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.996919654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:47.574518Z","caller":"traceutil/trace.go:171","msg":"trace[1508878714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.997016438s","start":"2026-04-27T16:34:44.577478Z","end":"2026-04-27T16:34:47.574495Z","steps":["trace[1508878714] 'agreement among raft nodes before linearized reading' (duration: 2.996920674s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:47.574561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:44.577402Z","time spent":"2.997146303s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/04/27 16:34:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:47.83054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999470927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:47.830651Z","caller":"traceutil/trace.go:171","msg":"trace[1907986630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"9.999648265s","start":"2026-04-27T16:34:37.830979Z","end":"2026-04-27T16:34:47.830627Z","steps":["trace[1907986630] 'agreement among raft nodes before linearized reading' (duration: 9.999505259s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:47.830708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:37.830958Z","time spent":"9.999736849s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/04/27 16:34:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:47.877217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:40.876749Z","time spent":"7.00045843s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-27T16:34:48.004224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997500943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:48.004503Z","caller":"traceutil/trace.go:171","msg":"trace[481270726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.997853908s","start":"2026-04-27T16:34:45.006607Z","end":"2026-04-27T16:34:48.004461Z","steps":["trace[481270726] 'agreement among raft nodes before linearized reading' (duration: 2.997532135s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.00477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:45.006581Z","time spent":"2.99817023s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/27 16:34:48 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:48.294901Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"10.950971788s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T16:34:48.583415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.131608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-04-27T16:34:48.583519Z","caller":"traceutil/trace.go:171","msg":"trace[739170210] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"11.299821135s","start":"2026-04-27T16:34:37.28368Z","end":"2026-04-27T16:34:48.583501Z","steps":["trace[739170210] 'read index received' (duration: 60.266961ms)","trace[739170210] 'applied index is now lower than readState.Index' (duration: 11.239552934s)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:48.583928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.300248312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-27T16:34:48.583961Z","caller":"traceutil/trace.go:171","msg":"trace[1300355229] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:699; }","duration":"11.300333826s","start":"2026-04-27T16:34:37.283615Z","end":"2026-04-27T16:34:48.583949Z","steps":["trace[1300355229] 'agreement among raft nodes before linearized reading' (duration: 11.300250862s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.583992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:37.283589Z","time spent":"11.300396698s","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"warn","ts":"2026-04-27T16:34:48.584382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.850505939s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:48.584561Z","caller":"traceutil/trace.go:171","msg":"trace[1824931933] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:699; }","duration":"5.850703077s","start":"2026-04-27T16:34:42.733829Z","end":"2026-04-27T16:34:48.584532Z","steps":["trace[1824931933] 'range keys from in-memory index tree' (duration: 5.850475428s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.638584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.996952049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-27T16:34:48.638656Z","caller":"traceutil/trace.go:171","msg":"trace[990232846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.997075843s","start":"2026-04-27T16:34:45.641563Z","end":"2026-04-27T16:34:48.638639Z","steps":["trace[990232846] 'agreement among raft nodes before linearized reading' (duration: 2.99699343s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.638699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:45.641535Z","time spent":"2.997154628s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/27 16:34:48 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-27T16:34:48.850025Z","caller":"traceutil/trace.go:171","msg":"trace[1666556485] transaction","detail":"{read_only:false; number_of_response:1; response_revision:703; }","duration":"4.321925822s","start":"2026-04-27T16:34:44.528035Z","end":"2026-04-27T16:34:48.849961Z","steps":["trace[1666556485] 'process raft request' (duration: 4.321730424s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:48.850075Z","caller":"traceutil/trace.go:171","msg":"trace[1107563982] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"1.449591941s","start":"2026-04-27T16:34:47.400455Z","end":"2026-04-27T16:34:48.850047Z","steps":["trace[1107563982] 'process raft request' (duration: 1.449433985s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:48.850056Z","caller":"traceutil/trace.go:171","msg":"trace[535252568] transaction","detail":"{read_only:false; number_of_response:1; response_revision:703; }","duration":"3.333487138s","start":"2026-04-27T16:34:45.516528Z","end":"2026-04-27T16:34:48.850015Z","steps":["trace[535252568] 'process raft request' (duration: 3.333327302s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.850251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:45.516486Z","time spent":"3.333662394s","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":44,"response count":0,"response size":1746,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:48.850278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:47.400423Z","time spent":"1.449718747s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:48.850069Z","caller":"traceutil/trace.go:171","msg":"trace[1158824247] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"1.271336677s","start":"2026-04-27T16:34:47.578714Z","end":"2026-04-27T16:34:48.850051Z","steps":["trace[1158824247] 'process raft request' (duration: 1.271258304s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:48.850504Z","caller":"traceutil/trace.go:171","msg":"trace[1710879360] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"1.017287681s","start":"2026-04-27T16:34:47.833201Z","end":"2026-04-27T16:34:48.850489Z","steps":["trace[1710879360] 'process raft request' (duration: 1.016811852s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.850551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:47.578692Z","time spent":"1.271810517s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:48.850572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:47.833173Z","time spent":"1.017359024s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:48.850692Z","caller":"traceutil/trace.go:171","msg":"trace[712251451] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"209.365413ms","start":"2026-04-27T16:34:48.641291Z","end":"2026-04-27T16:34:48.850656Z","steps":["trace[712251451] 'process raft request' (duration: 209.230287ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:48.850844Z","caller":"traceutil/trace.go:171","msg":"trace[1083330583] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:722; }","duration":"267.281373ms","start":"2026-04-27T16:34:48.583546Z","end":"2026-04-27T16:34:48.850827Z","steps":["trace[1083330583] 'read index received' (duration: 183.784556ms)","trace[1083330583] 'applied index is now lower than readState.Index' (duration: 83.492016ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:48.850865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780453422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:48.850898Z","caller":"traceutil/trace.go:171","msg":"trace[581329644] transaction","detail":"{read_only:false; number_of_response:1; response_revision:706; }","duration":"967.844767ms","start":"2026-04-27T16:34:47.883039Z","end":"2026-04-27T16:34:48.850883Z","steps":["trace[581329644] 'process raft request' (duration: 967.256203ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:48.85093Z","caller":"traceutil/trace.go:171","msg":"trace[1694324166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"1.780590467s","start":"2026-04-27T16:34:47.070325Z","end":"2026-04-27T16:34:48.850916Z","steps":["trace[1694324166] 'agreement among raft nodes before linearized reading' (duration: 1.780500714s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.850962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:47.070294Z","time spent":"1.780662081s","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:34:48.850966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:44.528006Z","time spent":"4.32213427s","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":721,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:48.851005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.749337895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-q7r2k.18aa43ea326bb7d4\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-04-27T16:34:48.851036Z","caller":"traceutil/trace.go:171","msg":"trace[876884372] range","detail":"{range_begin:/registry/events/kube-system/cilium-q7r2k.18aa43ea326bb7d4; range_end:; response_count:1; response_revision:707; }","duration":"10.749411898s","start":"2026-04-27T16:34:38.101614Z","end":"2026-04-27T16:34:48.851026Z","steps":["trace[876884372] 'agreement among raft nodes before linearized reading' (duration: 10.749266401s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.851049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:47.883013Z","time spent":"967.92163ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:48.851065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:38.101589Z","time spent":"10.74946853s","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":818,"request content":"key:\"/registry/events/kube-system/cilium-q7r2k.18aa43ea326bb7d4\" "} {"level":"warn","ts":"2026-04-27T16:34:48.851137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.581472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T16:34:48.851128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.505428105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:34:48.851225Z","caller":"traceutil/trace.go:171","msg":"trace[1573758843] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:707; }","duration":"3.505614253s","start":"2026-04-27T16:34:45.345589Z","end":"2026-04-27T16:34:48.851204Z","steps":["trace[1573758843] 'agreement among raft nodes before linearized reading' (duration: 3.505414055s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.851287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:45.345563Z","time spent":"3.505710977s","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:34:48.851177Z","caller":"traceutil/trace.go:171","msg":"trace[337864604] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:707; }","duration":"883.669296ms","start":"2026-04-27T16:34:47.967498Z","end":"2026-04-27T16:34:48.851167Z","steps":["trace[337864604] 'agreement among raft nodes before linearized reading' (duration: 883.50918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.851297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.778882638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:34:48.85142Z","caller":"traceutil/trace.go:171","msg":"trace[527043673] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:707; }","duration":"3.779081045s","start":"2026-04-27T16:34:45.072312Z","end":"2026-04-27T16:34:48.851393Z","steps":["trace[527043673] 'agreement among raft nodes before linearized reading' (duration: 3.778759482s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:48.851354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:47.967472Z","time spent":"883.871494ms","remote":"127.0.0.1:55502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:34:48.851475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:45.072265Z","time spent":"3.77919902s","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":65,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} 2026/04/27 16:34:48 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-27T16:34:49.25818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.665665ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-27T16:34:49.258375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:48.91917Z","time spent":"339.200349ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T16:34:49.258672Z","caller":"traceutil/trace.go:171","msg":"trace[127243144] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"340.613587ms","start":"2026-04-27T16:34:48.918026Z","end":"2026-04-27T16:34:49.258639Z","steps":["trace[127243144] 'read index received' (duration: 158.494613ms)","trace[127243144] 'applied index is now lower than readState.Index' (duration: 182.115974ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:49.258732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:48.857855Z","time spent":"400.86514ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T16:34:49.25889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.878541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:49.258937Z","caller":"traceutil/trace.go:171","msg":"trace[1740573477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"331.973314ms","start":"2026-04-27T16:34:48.926953Z","end":"2026-04-27T16:34:49.258926Z","steps":["trace[1740573477] 'agreement among raft nodes before linearized reading' (duration: 331.86814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:49.258952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.928832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q7r2k\" ","response":"range_response_count:1 size:18713"} {"level":"warn","ts":"2026-04-27T16:34:49.258943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.175962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-04-27T16:34:49.258979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:48.926937Z","time spent":"332.032787ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:34:49.259003Z","caller":"traceutil/trace.go:171","msg":"trace[1572191098] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q7r2k; range_end:; response_count:1; response_revision:707; }","duration":"158.004125ms","start":"2026-04-27T16:34:49.100978Z","end":"2026-04-27T16:34:49.258982Z","steps":["trace[1572191098] 'agreement among raft nodes before linearized reading' (duration: 157.874919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:49.258982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.254345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:687"} {"level":"info","ts":"2026-04-27T16:34:49.25901Z","caller":"traceutil/trace.go:171","msg":"trace[659855082] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:707; }","duration":"401.276937ms","start":"2026-04-27T16:34:48.857716Z","end":"2026-04-27T16:34:49.258993Z","steps":["trace[659855082] 'agreement among raft nodes before linearized reading' (duration: 401.002726ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:49.259066Z","caller":"traceutil/trace.go:171","msg":"trace[230930029] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:707; }","duration":"401.36288ms","start":"2026-04-27T16:34:48.857683Z","end":"2026-04-27T16:34:49.259046Z","steps":["trace[230930029] 'agreement among raft nodes before linearized reading' (duration: 401.088399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:49.259112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:48.857711Z","time spent":"401.35694ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-27T16:34:49.259147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:48.857675Z","time spent":"401.462514ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":710,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-27T16:34:49.913612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.532185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:49.913724Z","caller":"traceutil/trace.go:171","msg":"trace[1144031651] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"653.210801ms","start":"2026-04-27T16:34:49.260493Z","end":"2026-04-27T16:34:49.913704Z","steps":["trace[1144031651] 'process raft request' (duration: 149.50177ms)","trace[1144031651] 'compare' (duration: 503.393328ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:49.913787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:49.260466Z","time spent":"653.294644ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:50.007272Z","caller":"traceutil/trace.go:171","msg":"trace[1003383135] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"746.640304ms","start":"2026-04-27T16:34:49.260606Z","end":"2026-04-27T16:34:50.007246Z","steps":["trace[1003383135] 'process raft request' (duration: 746.426115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:50.007426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:49.260567Z","time spent":"746.764149ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:50.007613Z","caller":"traceutil/trace.go:171","msg":"trace[1577556076] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:737; }","duration":"647.299961ms","start":"2026-04-27T16:34:49.360297Z","end":"2026-04-27T16:34:50.007597Z","steps":["trace[1577556076] 'read index received' (duration: 49.724679ms)","trace[1577556076] 'applied index is now lower than readState.Index' (duration: 597.574072ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:50.007854Z","caller":"traceutil/trace.go:171","msg":"trace[1475220914] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"744.33578ms","start":"2026-04-27T16:34:49.263508Z","end":"2026-04-27T16:34:50.007844Z","steps":["trace[1475220914] 'process raft request' (duration: 743.686934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:50.00792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:49.26349Z","time spent":"744.388602ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:50.008006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.718359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:34:50.008037Z","caller":"traceutil/trace.go:171","msg":"trace[701550138] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:712; }","duration":"647.817663ms","start":"2026-04-27T16:34:49.36021Z","end":"2026-04-27T16:34:50.008028Z","steps":["trace[701550138] 'agreement among raft nodes before linearized reading' (duration: 647.724028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:50.008061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:49.360179Z","time spent":"647.875455ms","remote":"127.0.0.1:55512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:34:50.008172Z","caller":"traceutil/trace.go:171","msg":"trace[105335539] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"744.094702ms","start":"2026-04-27T16:34:49.26407Z","end":"2026-04-27T16:34:50.008165Z","steps":["trace[105335539] 'process raft request' (duration: 743.33307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:50.008221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:49.264059Z","time spent":"744.130413ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:50.008274Z","caller":"traceutil/trace.go:171","msg":"trace[908479686] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"734.902858ms","start":"2026-04-27T16:34:49.273325Z","end":"2026-04-27T16:34:50.008228Z","steps":["trace[908479686] 'process raft request' (duration: 734.154367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:50.008477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:49.273316Z","time spent":"735.042875ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20558,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:50.240372Z","caller":"traceutil/trace.go:171","msg":"trace[2055814954] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:742; }","duration":"227.185031ms","start":"2026-04-27T16:34:50.013158Z","end":"2026-04-27T16:34:50.240343Z","steps":["trace[2055814954] 'read index received' (duration: 131.817486ms)","trace[2055814954] 'applied index is now lower than readState.Index' (duration: 95.357345ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:50.240378Z","caller":"traceutil/trace.go:171","msg":"trace[1915795449] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"227.640819ms","start":"2026-04-27T16:34:50.012703Z","end":"2026-04-27T16:34:50.240344Z","steps":["trace[1915795449] 'process raft request' (duration: 132.256044ms)","trace[1915795449] 'compare' (duration: 94.956978ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:50.240811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.287572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:50.240873Z","caller":"traceutil/trace.go:171","msg":"trace[1337595540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"190.408037ms","start":"2026-04-27T16:34:50.050451Z","end":"2026-04-27T16:34:50.240859Z","steps":["trace[1337595540] 'agreement among raft nodes before linearized reading' (duration: 190.258191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:50.240673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.492305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6755"} {"level":"info","ts":"2026-04-27T16:34:50.241032Z","caller":"traceutil/trace.go:171","msg":"trace[597558051] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:713; }","duration":"227.882161ms","start":"2026-04-27T16:34:50.013098Z","end":"2026-04-27T16:34:50.24098Z","steps":["trace[597558051] 'agreement among raft nodes before linearized reading' (duration: 227.398852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:50.241375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.029076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-27T16:34:50.241498Z","caller":"traceutil/trace.go:171","msg":"trace[888541731] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:713; }","duration":"228.300487ms","start":"2026-04-27T16:34:50.013175Z","end":"2026-04-27T16:34:50.241475Z","steps":["trace[888541731] 'agreement among raft nodes before linearized reading' (duration: 227.408001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:50.771804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.983715ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-27T16:34:50.771968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:50.24643Z","time spent":"525.535115ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T16:34:51.173478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.601158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:51.17381Z","caller":"traceutil/trace.go:171","msg":"trace[261664216] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"925.778587ms","start":"2026-04-27T16:34:50.247998Z","end":"2026-04-27T16:34:51.173777Z","steps":["trace[261664216] 'process raft request' (duration: 661.731319ms)","trace[261664216] 'compare' (duration: 263.158391ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:51.173928Z","caller":"traceutil/trace.go:171","msg":"trace[502005168] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"922.919231ms","start":"2026-04-27T16:34:50.250986Z","end":"2026-04-27T16:34:51.173906Z","steps":["trace[502005168] 'process raft request' (duration: 922.66395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:51.173993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:50.247983Z","time spent":"925.902232ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:51.174004Z","caller":"traceutil/trace.go:171","msg":"trace[2034749242] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"878.195863ms","start":"2026-04-27T16:34:50.295779Z","end":"2026-04-27T16:34:51.173974Z","steps":["trace[2034749242] 'process raft request' (duration: 878.034266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:51.17403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:50.250976Z","time spent":"923.002404ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:51.174101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:50.295759Z","time spent":"878.301677ms","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:51.17421Z","caller":"traceutil/trace.go:171","msg":"trace[1499343746] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:744; }","duration":"122.623337ms","start":"2026-04-27T16:34:51.051546Z","end":"2026-04-27T16:34:51.174169Z","steps":["trace[1499343746] 'read index received' (duration: 90.461469ms)","trace[1499343746] 'applied index is now lower than readState.Index' (duration: 32.159418ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:51.174241Z","caller":"traceutil/trace.go:171","msg":"trace[2038566978] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"401.20967ms","start":"2026-04-27T16:34:50.772974Z","end":"2026-04-27T16:34:51.174184Z","steps":["trace[2038566978] 'process raft request' (duration: 400.941829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:51.174465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.896239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:51.174517Z","caller":"traceutil/trace.go:171","msg":"trace[2052669870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"123.004763ms","start":"2026-04-27T16:34:51.0515Z","end":"2026-04-27T16:34:51.174505Z","steps":["trace[2052669870] 'agreement among raft nodes before linearized reading' (duration: 122.811435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:51.17476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:50.772947Z","time spent":"401.405668ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:51.41704Z","caller":"traceutil/trace.go:171","msg":"trace[714717713] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"204.284897ms","start":"2026-04-27T16:34:51.212726Z","end":"2026-04-27T16:34:51.417011Z","steps":["trace[714717713] 'read index received' (duration: 159.160012ms)","trace[714717713] 'applied index is now lower than readState.Index' (duration: 45.123185ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:51.417217Z","caller":"traceutil/trace.go:171","msg":"trace[1213713937] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"231.206384ms","start":"2026-04-27T16:34:51.185972Z","end":"2026-04-27T16:34:51.417178Z","steps":["trace[1213713937] 'process raft request' (duration: 186.085388ms)","trace[1213713937] 'compare' (duration: 44.620365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:51.417518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.09581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18aa43ebd0eb7539\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-27T16:34:51.417573Z","caller":"traceutil/trace.go:171","msg":"trace[1569462049] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18aa43ebd0eb7539; range_end:; response_count:1; response_revision:719; }","duration":"204.190474ms","start":"2026-04-27T16:34:51.213367Z","end":"2026-04-27T16:34:51.417557Z","steps":["trace[1569462049] 'agreement among raft nodes before linearized reading' (duration: 203.959845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:51.417785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.308891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:34:51.418135Z","caller":"traceutil/trace.go:171","msg":"trace[1205250644] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:719; }","duration":"132.73548ms","start":"2026-04-27T16:34:51.285378Z","end":"2026-04-27T16:34:51.418114Z","steps":["trace[1205250644] 'agreement among raft nodes before linearized reading' (duration: 132.046862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:51.417846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.092421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-27T16:34:51.418528Z","caller":"traceutil/trace.go:171","msg":"trace[1739580954] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:719; }","duration":"205.81792ms","start":"2026-04-27T16:34:51.212679Z","end":"2026-04-27T16:34:51.418497Z","steps":["trace[1739580954] 'agreement among raft nodes before linearized reading' (duration: 204.477906ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:51.715668Z","caller":"traceutil/trace.go:171","msg":"trace[424701800] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"292.479755ms","start":"2026-04-27T16:34:51.423124Z","end":"2026-04-27T16:34:51.715604Z","steps":["trace[424701800] 'read index received' (duration: 207.636014ms)","trace[424701800] 'applied index is now lower than readState.Index' (duration: 84.841831ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:51.715962Z","caller":"traceutil/trace.go:171","msg":"trace[740942969] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"294.987847ms","start":"2026-04-27T16:34:51.420907Z","end":"2026-04-27T16:34:51.715895Z","steps":["trace[740942969] 'process raft request' (duration: 209.895667ms)","trace[740942969] 'compare' (duration: 84.626842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:51.716247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.128191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4134"} {"level":"info","ts":"2026-04-27T16:34:51.716309Z","caller":"traceutil/trace.go:171","msg":"trace[836366392] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:720; }","duration":"293.240405ms","start":"2026-04-27T16:34:51.42305Z","end":"2026-04-27T16:34:51.716291Z","steps":["trace[836366392] 'agreement among raft nodes before linearized reading' (duration: 292.729185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:51.96249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.071122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:51.962678Z","caller":"traceutil/trace.go:171","msg":"trace[1248568537] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"238.334963ms","start":"2026-04-27T16:34:51.724314Z","end":"2026-04-27T16:34:51.962649Z","steps":["trace[1248568537] 'process raft request' (duration: 119.968759ms)","trace[1248568537] 'compare' (duration: 117.906006ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:52.289178Z","caller":"traceutil/trace.go:171","msg":"trace[1599674398] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"564.281941ms","start":"2026-04-27T16:34:51.724857Z","end":"2026-04-27T16:34:52.289139Z","steps":["trace[1599674398] 'process raft request' (duration: 524.895727ms)","trace[1599674398] 'compare' (duration: 39.234338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:52.289375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:51.724823Z","time spent":"564.448197ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4413,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:52.331259Z","caller":"traceutil/trace.go:171","msg":"trace[607932074] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:752; }","duration":"278.81236ms","start":"2026-04-27T16:34:52.052415Z","end":"2026-04-27T16:34:52.331228Z","steps":["trace[607932074] 'read index received' (duration: 197.561842ms)","trace[607932074] 'applied index is now lower than readState.Index' (duration: 81.248848ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:52.331372Z","caller":"traceutil/trace.go:171","msg":"trace[2097526125] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"362.855839ms","start":"2026-04-27T16:34:51.968456Z","end":"2026-04-27T16:34:52.331312Z","steps":["trace[2097526125] 'process raft request' (duration: 362.537366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:52.331597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:51.968423Z","time spent":"363.057427ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:52.331658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.193495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:52.331732Z","caller":"traceutil/trace.go:171","msg":"trace[327182987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"279.375523ms","start":"2026-04-27T16:34:52.052343Z","end":"2026-04-27T16:34:52.331719Z","steps":["trace[327182987] 'agreement among raft nodes before linearized reading' (duration: 279.152054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:52.866988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.040035ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:52.867193Z","caller":"traceutil/trace.go:171","msg":"trace[1627893667] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:723; }","duration":"133.283035ms","start":"2026-04-27T16:34:52.73389Z","end":"2026-04-27T16:34:52.867173Z","steps":["trace[1627893667] 'range keys from in-memory index tree' (duration: 133.015824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:52.867188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.92893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:52.86733Z","caller":"traceutil/trace.go:171","msg":"trace[1308080965] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"530.558806ms","start":"2026-04-27T16:34:52.336742Z","end":"2026-04-27T16:34:52.8673Z","steps":["trace[1308080965] 'process raft request' (duration: 342.406707ms)","trace[1308080965] 'compare' (duration: 187.693771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:52.867379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:52.336725Z","time spent":"530.63866ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:53.135606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.31083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:53.135931Z","caller":"traceutil/trace.go:171","msg":"trace[18640300] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"794.651822ms","start":"2026-04-27T16:34:52.341248Z","end":"2026-04-27T16:34:53.1359Z","steps":["trace[18640300] 'process raft request' (duration: 648.887044ms)","trace[18640300] 'compare' (duration: 145.018268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:53.136091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:52.341226Z","time spent":"794.783018ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7026,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:53.13625Z","caller":"traceutil/trace.go:171","msg":"trace[1397675679] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"264.70576ms","start":"2026-04-27T16:34:52.871504Z","end":"2026-04-27T16:34:53.136209Z","steps":["trace[1397675679] 'process raft request' (duration: 264.297853ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:53.597985Z","caller":"traceutil/trace.go:171","msg":"trace[69938893] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"456.468317ms","start":"2026-04-27T16:34:53.141481Z","end":"2026-04-27T16:34:53.597949Z","steps":["trace[69938893] 'process raft request' (duration: 456.253998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:53.598184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:53.141451Z","time spent":"456.642314ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":768,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:54.042546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.910566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:54.042646Z","caller":"traceutil/trace.go:171","msg":"trace[680350938] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"440.035258ms","start":"2026-04-27T16:34:53.602594Z","end":"2026-04-27T16:34:54.042629Z","steps":["trace[680350938] 'process raft request' (duration: 212.974826ms)","trace[680350938] 'compare' (duration: 226.710878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:54.042688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:53.602561Z","time spent":"440.111062ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:34:56.511732Z","caller":"traceutil/trace.go:171","msg":"trace[941974173] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"102.846001ms","start":"2026-04-27T16:34:56.408842Z","end":"2026-04-27T16:34:56.511688Z","steps":["trace[941974173] 'process raft request' (duration: 102.635342ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:56.988381Z","caller":"traceutil/trace.go:171","msg":"trace[1961069832] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"425.29248ms","start":"2026-04-27T16:34:56.563044Z","end":"2026-04-27T16:34:56.988336Z","steps":["trace[1961069832] 'read index received' (duration: 356.743741ms)","trace[1961069832] 'applied index is now lower than readState.Index' (duration: 68.546929ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:56.988414Z","caller":"traceutil/trace.go:171","msg":"trace[1865248864] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"568.108794ms","start":"2026-04-27T16:34:56.420258Z","end":"2026-04-27T16:34:56.988367Z","steps":["trace[1865248864] 'process raft request' (duration: 499.645998ms)","trace[1865248864] 'compare' (duration: 68.195935ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:56.988576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.5037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:34:56.98866Z","caller":"traceutil/trace.go:171","msg":"trace[127192929] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:731; }","duration":"425.602744ms","start":"2026-04-27T16:34:56.563036Z","end":"2026-04-27T16:34:56.988638Z","steps":["trace[127192929] 'agreement among raft nodes before linearized reading' (duration: 425.421676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:56.988684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.843789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:34:56.988726Z","caller":"traceutil/trace.go:171","msg":"trace[942146372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:731; }","duration":"172.942963ms","start":"2026-04-27T16:34:56.815768Z","end":"2026-04-27T16:34:56.988711Z","steps":["trace[942146372] 'agreement among raft nodes before linearized reading' (duration: 172.745915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:56.988767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:56.562972Z","time spent":"425.725948ms","remote":"127.0.0.1:55734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":74,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:34:56.988894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:56.420209Z","time spent":"568.405636ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20570,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:57.65262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113968448137292,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T16:34:57.728442Z","caller":"traceutil/trace.go:171","msg":"trace[2018972853] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"576.434413ms","start":"2026-04-27T16:34:57.151981Z","end":"2026-04-27T16:34:57.728415Z","steps":["trace[2018972853] 'read index received' (duration: 576.152762ms)","trace[2018972853] 'applied index is now lower than readState.Index' (duration: 280.541µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:57.728507Z","caller":"traceutil/trace.go:171","msg":"trace[2076981968] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"665.752794ms","start":"2026-04-27T16:34:57.062704Z","end":"2026-04-27T16:34:57.728456Z","steps":["trace[2076981968] 'process raft request' (duration: 665.518244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:57.728912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.894443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:425"} {"level":"info","ts":"2026-04-27T16:34:57.728978Z","caller":"traceutil/trace.go:171","msg":"trace[440075480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"577.037449ms","start":"2026-04-27T16:34:57.151928Z","end":"2026-04-27T16:34:57.728965Z","steps":["trace[440075480] 'agreement among raft nodes before linearized reading' (duration: 576.682333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:57.729023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.151902Z","time spent":"577.111292ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":448,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:34:57.729023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.494807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:429"} {"level":"info","ts":"2026-04-27T16:34:57.729091Z","caller":"traceutil/trace.go:171","msg":"trace[1056579538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:732; }","duration":"576.640023ms","start":"2026-04-27T16:34:57.152428Z","end":"2026-04-27T16:34:57.729068Z","steps":["trace[1056579538] 'agreement among raft nodes before linearized reading' (duration: 576.362831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:57.72915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.152397Z","time spent":"576.744177ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":452,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:34:57.729252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.062684Z","time spent":"666.347329ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:57.729357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.528564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1712"} {"level":"info","ts":"2026-04-27T16:34:57.72948Z","caller":"traceutil/trace.go:171","msg":"trace[1721216717] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:732; }","duration":"480.770344ms","start":"2026-04-27T16:34:57.24869Z","end":"2026-04-27T16:34:57.72946Z","steps":["trace[1721216717] 'agreement among raft nodes before linearized reading' (duration: 480.257023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:57.72956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.248667Z","time spent":"480.881508ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1735,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-04-27T16:34:57.729397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.732092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1712"} {"level":"info","ts":"2026-04-27T16:34:57.729717Z","caller":"traceutil/trace.go:171","msg":"trace[746112358] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:732; }","duration":"481.074865ms","start":"2026-04-27T16:34:57.248627Z","end":"2026-04-27T16:34:57.729702Z","steps":["trace[746112358] 'agreement among raft nodes before linearized reading' (duration: 480.68902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:57.729774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.248612Z","time spent":"481.145279ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1735,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"info","ts":"2026-04-27T16:34:58.26694Z","caller":"traceutil/trace.go:171","msg":"trace[423720854] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"527.453951ms","start":"2026-04-27T16:34:57.739466Z","end":"2026-04-27T16:34:58.26692Z","steps":["trace[423720854] 'process raft request' (duration: 527.243222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.267112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.739449Z","time spent":"527.555805ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:58.267187Z","caller":"traceutil/trace.go:171","msg":"trace[185748147] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:763; }","duration":"518.845898ms","start":"2026-04-27T16:34:57.748321Z","end":"2026-04-27T16:34:58.267167Z","steps":["trace[185748147] 'read index received' (duration: 18.195573ms)","trace[185748147] 'applied index is now lower than readState.Index' (duration: 500.649645ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:58.267277Z","caller":"traceutil/trace.go:171","msg":"trace[2058843730] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"514.356655ms","start":"2026-04-27T16:34:57.752914Z","end":"2026-04-27T16:34:58.26727Z","steps":["trace[2058843730] 'process raft request' (duration: 514.182928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.267359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.752895Z","time spent":"514.402037ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7064,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:58.2674Z","caller":"traceutil/trace.go:171","msg":"trace[725994325] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"525.448269ms","start":"2026-04-27T16:34:57.741939Z","end":"2026-04-27T16:34:58.267387Z","steps":["trace[725994325] 'process raft request' (duration: 524.938908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.267449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.741928Z","time spent":"525.496911ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:58.26756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.240945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1712"} {"level":"info","ts":"2026-04-27T16:34:58.267587Z","caller":"traceutil/trace.go:171","msg":"trace[2090149543] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:736; }","duration":"519.304328ms","start":"2026-04-27T16:34:57.748275Z","end":"2026-04-27T16:34:58.267579Z","steps":["trace[2090149543] 'agreement among raft nodes before linearized reading' (duration: 519.217914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.267608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.748258Z","time spent":"519.34712ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1735,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-04-27T16:34:58.26772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.593715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1712"} {"level":"info","ts":"2026-04-27T16:34:58.267751Z","caller":"traceutil/trace.go:171","msg":"trace[450377561] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:736; }","duration":"514.646477ms","start":"2026-04-27T16:34:57.753096Z","end":"2026-04-27T16:34:58.267743Z","steps":["trace[450377561] 'agreement among raft nodes before linearized reading' (duration: 514.545433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.267774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.753091Z","time spent":"514.678638ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1735,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-04-27T16:34:58.267853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.191773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:34:58.267867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.318289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-27T16:34:58.267894Z","caller":"traceutil/trace.go:171","msg":"trace[1735307900] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:736; }","duration":"216.381532ms","start":"2026-04-27T16:34:58.051506Z","end":"2026-04-27T16:34:58.267888Z","steps":["trace[1735307900] 'agreement among raft nodes before linearized reading' (duration: 216.3343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.267928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.820595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-27T16:34:58.267956Z","caller":"traceutil/trace.go:171","msg":"trace[1132518244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:736; }","duration":"493.886508ms","start":"2026-04-27T16:34:57.774062Z","end":"2026-04-27T16:34:58.267949Z","steps":["trace[1132518244] 'agreement among raft nodes before linearized reading' (duration: 493.823065ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:58.267874Z","caller":"traceutil/trace.go:171","msg":"trace[189110802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"215.222335ms","start":"2026-04-27T16:34:58.052646Z","end":"2026-04-27T16:34:58.267868Z","steps":["trace[189110802] 'agreement among raft nodes before linearized reading' (duration: 215.187244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.267979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:57.774039Z","time spent":"493.93387ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:34:58.928477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.530525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:34:58.9288Z","caller":"traceutil/trace.go:171","msg":"trace[1941725183] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"653.761726ms","start":"2026-04-27T16:34:58.274977Z","end":"2026-04-27T16:34:58.928739Z","steps":["trace[1941725183] 'process raft request' (duration: 653.664662ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:58.928881Z","caller":"traceutil/trace.go:171","msg":"trace[176817954] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"654.554169ms","start":"2026-04-27T16:34:58.274288Z","end":"2026-04-27T16:34:58.928842Z","steps":["trace[176817954] 'read index received' (duration: 419.570435ms)","trace[176817954] 'applied index is now lower than readState.Index' (duration: 234.981044ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:58.929024Z","caller":"traceutil/trace.go:171","msg":"trace[1197199578] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"657.061592ms","start":"2026-04-27T16:34:58.271949Z","end":"2026-04-27T16:34:58.92901Z","steps":["trace[1197199578] 'process raft request' (duration: 421.87753ms)","trace[1197199578] 'compare' (duration: 234.354128ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:58.9291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.271932Z","time spent":"657.127325ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:34:58.929092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.274963Z","time spent":"654.062558ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:58.929226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.963682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"warn","ts":"2026-04-27T16:34:58.929245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.597881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:58.929298Z","caller":"traceutil/trace.go:171","msg":"trace[2044887705] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:738; }","duration":"410.080777ms","start":"2026-04-27T16:34:58.519197Z","end":"2026-04-27T16:34:58.929278Z","steps":["trace[2044887705] 'agreement among raft nodes before linearized reading' (duration: 409.9166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.929336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.519175Z","time spent":"410.15414ms","remote":"127.0.0.1:55236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3040,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-04-27T16:34:58.929447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.673064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-04-27T16:34:58.929496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.172136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:34:58.929542Z","caller":"traceutil/trace.go:171","msg":"trace[686061877] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:738; }","duration":"208.25403ms","start":"2026-04-27T16:34:58.721276Z","end":"2026-04-27T16:34:58.92953Z","steps":["trace[686061877] 'agreement among raft nodes before linearized reading' (duration: 208.173787ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:58.929499Z","caller":"traceutil/trace.go:171","msg":"trace[1125163289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"644.743357ms","start":"2026-04-27T16:34:58.284742Z","end":"2026-04-27T16:34:58.929486Z","steps":["trace[1125163289] 'agreement among raft nodes before linearized reading' (duration: 644.56083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.929652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.28473Z","time spent":"644.913214ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:34:58.929756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.451025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1712"} {"level":"info","ts":"2026-04-27T16:34:58.929831Z","caller":"traceutil/trace.go:171","msg":"trace[425260779] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:738; }","duration":"655.547979ms","start":"2026-04-27T16:34:58.274264Z","end":"2026-04-27T16:34:58.929812Z","steps":["trace[425260779] 'agreement among raft nodes before linearized reading' (duration: 655.413454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.929871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.274247Z","time spent":"655.616532ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1735,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"info","ts":"2026-04-27T16:34:58.930339Z","caller":"traceutil/trace.go:171","msg":"trace[1841144674] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:738; }","duration":"117.718116ms","start":"2026-04-27T16:34:58.811572Z","end":"2026-04-27T16:34:58.92929Z","steps":["trace[1841144674] 'agreement among raft nodes before linearized reading' (duration: 117.535139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.930348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.387152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-27T16:34:58.930409Z","caller":"traceutil/trace.go:171","msg":"trace[77308585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:738; }","duration":"655.449965ms","start":"2026-04-27T16:34:58.274947Z","end":"2026-04-27T16:34:58.930396Z","steps":["trace[77308585] 'agreement among raft nodes before linearized reading' (duration: 654.127751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:58.930447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.274907Z","time spent":"655.532489ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T16:34:59.29377Z","caller":"traceutil/trace.go:171","msg":"trace[1794449264] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:769; }","duration":"343.67755ms","start":"2026-04-27T16:34:58.950053Z","end":"2026-04-27T16:34:59.29373Z","steps":["trace[1794449264] 'read index received' (duration: 17.355291ms)","trace[1794449264] 'applied index is now lower than readState.Index' (duration: 326.321019ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:59.293923Z","caller":"traceutil/trace.go:171","msg":"trace[615729469] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"356.444932ms","start":"2026-04-27T16:34:58.937445Z","end":"2026-04-27T16:34:59.29389Z","steps":["trace[615729469] 'process raft request' (duration: 356.176011ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:59.294101Z","caller":"traceutil/trace.go:171","msg":"trace[79439973] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"357.091509ms","start":"2026-04-27T16:34:58.936989Z","end":"2026-04-27T16:34:59.294081Z","steps":["trace[79439973] 'process raft request' (duration: 279.953001ms)","trace[79439973] 'compare' (duration: 76.473981ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:59.294156Z","caller":"traceutil/trace.go:171","msg":"trace[1905101486] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"356.857229ms","start":"2026-04-27T16:34:58.937284Z","end":"2026-04-27T16:34:59.294141Z","steps":["trace[1905101486] 'process raft request' (duration: 356.276805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.29419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.936971Z","time spent":"357.179122ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:59.294224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.937272Z","time spent":"356.913041ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4763,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:34:59.294097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.937437Z","time spent":"356.550117ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:59.294387Z","caller":"traceutil/trace.go:171","msg":"trace[827943399] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"347.060148ms","start":"2026-04-27T16:34:58.947281Z","end":"2026-04-27T16:34:59.294341Z","steps":["trace[827943399] 'process raft request' (duration: 346.393411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.294502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.68746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:34:59.29449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.368778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:59.294548Z","caller":"traceutil/trace.go:171","msg":"trace[955537370] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:743; }","duration":"267.750292ms","start":"2026-04-27T16:34:59.026785Z","end":"2026-04-27T16:34:59.294535Z","steps":["trace[955537370] 'agreement among raft nodes before linearized reading' (duration: 267.670029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.294588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.947252Z","time spent":"347.240435ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1779,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:59.294584Z","caller":"traceutil/trace.go:171","msg":"trace[1215423242] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:743; }","duration":"344.535435ms","start":"2026-04-27T16:34:58.950032Z","end":"2026-04-27T16:34:59.294567Z","steps":["trace[1215423242] 'agreement among raft nodes before linearized reading' (duration: 344.297976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.294628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.19492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:34:59.294653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.950024Z","time spent":"344.616208ms","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T16:34:59.294668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.64965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-operator-869df985b8-cpscb.18aa43e91c98997d\" ","response":"range_response_count:1 size:778"} {"level":"warn","ts":"2026-04-27T16:34:59.2947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.096207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:1 size:3017"} {"level":"info","ts":"2026-04-27T16:34:59.294736Z","caller":"traceutil/trace.go:171","msg":"trace[1373885278] range","detail":"{range_begin:/registry/events/kube-system/cilium-operator-869df985b8-cpscb.18aa43e91c98997d; range_end:; response_count:1; response_revision:743; }","duration":"323.750475ms","start":"2026-04-27T16:34:58.970968Z","end":"2026-04-27T16:34:59.294719Z","steps":["trace[1373885278] 'agreement among raft nodes before linearized reading' (duration: 323.592828ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:59.294751Z","caller":"traceutil/trace.go:171","msg":"trace[1637916754] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:1; response_revision:743; }","duration":"343.150979ms","start":"2026-04-27T16:34:58.951582Z","end":"2026-04-27T16:34:59.294733Z","steps":["trace[1637916754] 'agreement among raft nodes before linearized reading' (duration: 342.985951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.294772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.970951Z","time spent":"323.813177ms","remote":"127.0.0.1:55220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":801,"request content":"key:\"/registry/events/kube-system/cilium-operator-869df985b8-cpscb.18aa43e91c98997d\" "} {"level":"warn","ts":"2026-04-27T16:34:59.294795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.951576Z","time spent":"343.209141ms","remote":"127.0.0.1:55236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3040,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"warn","ts":"2026-04-27T16:34:59.294525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.397958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:34:59.294851Z","caller":"traceutil/trace.go:171","msg":"trace[906258008] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:743; }","duration":"344.754255ms","start":"2026-04-27T16:34:58.950082Z","end":"2026-04-27T16:34:59.294836Z","steps":["trace[906258008] 'agreement among raft nodes before linearized reading' (duration: 344.002514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.294892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.950077Z","time spent":"344.802207ms","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:34:59.294673Z","caller":"traceutil/trace.go:171","msg":"trace[1059666160] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:743; }","duration":"344.238702ms","start":"2026-04-27T16:34:58.950418Z","end":"2026-04-27T16:34:59.294657Z","steps":["trace[1059666160] 'agreement among raft nodes before linearized reading' (duration: 344.166689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:58.950405Z","time spent":"344.581227ms","remote":"127.0.0.1:33302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"info","ts":"2026-04-27T16:34:59.506266Z","caller":"traceutil/trace.go:171","msg":"trace[51073000] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"164.180863ms","start":"2026-04-27T16:34:59.342059Z","end":"2026-04-27T16:34:59.50624Z","steps":["trace[51073000] 'process raft request' (duration: 164.095119ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:59.506732Z","caller":"traceutil/trace.go:171","msg":"trace[1383202627] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"199.368643ms","start":"2026-04-27T16:34:59.307341Z","end":"2026-04-27T16:34:59.50671Z","steps":["trace[1383202627] 'process raft request' (duration: 107.294672ms)","trace[1383202627] 'compare' (duration: 91.07724ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:59.506899Z","caller":"traceutil/trace.go:171","msg":"trace[1634381917] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"168.476768ms","start":"2026-04-27T16:34:59.338409Z","end":"2026-04-27T16:34:59.506886Z","steps":["trace[1634381917] 'read index received' (duration: 76.239601ms)","trace[1634381917] 'applied index is now lower than readState.Index' (duration: 92.235997ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:59.507256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.22448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-27T16:34:59.50732Z","caller":"traceutil/trace.go:171","msg":"trace[468765868] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"191.315243ms","start":"2026-04-27T16:34:59.315989Z","end":"2026-04-27T16:34:59.507304Z","steps":["trace[468765868] 'agreement among raft nodes before linearized reading' (duration: 191.084264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.507582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.122066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-27T16:34:59.507636Z","caller":"traceutil/trace.go:171","msg":"trace[1711643307] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"192.220031ms","start":"2026-04-27T16:34:59.315402Z","end":"2026-04-27T16:34:59.507622Z","steps":["trace[1711643307] 'agreement among raft nodes before linearized reading' (duration: 192.094595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.507862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.395256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-27T16:34:59.507927Z","caller":"traceutil/trace.go:171","msg":"trace[647525336] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"200.458199ms","start":"2026-04-27T16:34:59.307454Z","end":"2026-04-27T16:34:59.507912Z","steps":["trace[647525336] 'agreement among raft nodes before linearized reading' (duration: 200.329263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.508164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.762391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-27T16:34:59.508218Z","caller":"traceutil/trace.go:171","msg":"trace[1772515207] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"200.830203ms","start":"2026-04-27T16:34:59.307376Z","end":"2026-04-27T16:34:59.508206Z","steps":["trace[1772515207] 'agreement among raft nodes before linearized reading' (duration: 200.699338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.508445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.051413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-27T16:34:59.50852Z","caller":"traceutil/trace.go:171","msg":"trace[985078133] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"201.136046ms","start":"2026-04-27T16:34:59.307372Z","end":"2026-04-27T16:34:59.508508Z","steps":["trace[985078133] 'agreement among raft nodes before linearized reading' (duration: 200.99563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.508765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.418478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-27T16:34:59.508817Z","caller":"traceutil/trace.go:171","msg":"trace[718235740] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"201.48603ms","start":"2026-04-27T16:34:59.307319Z","end":"2026-04-27T16:34:59.508805Z","steps":["trace[718235740] 'agreement among raft nodes before linearized reading' (duration: 201.355084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.509046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.768432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-27T16:34:59.509097Z","caller":"traceutil/trace.go:171","msg":"trace[2013784367] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"201.837954ms","start":"2026-04-27T16:34:59.307247Z","end":"2026-04-27T16:34:59.509085Z","steps":["trace[2013784367] 'agreement among raft nodes before linearized reading' (duration: 201.718029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.509325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.873747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-27T16:34:59.509374Z","caller":"traceutil/trace.go:171","msg":"trace[2115340559] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"203.958641ms","start":"2026-04-27T16:34:59.305403Z","end":"2026-04-27T16:34:59.509362Z","steps":["trace[2115340559] 'agreement among raft nodes before linearized reading' (duration: 203.840477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.509607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.176558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-04-27T16:34:59.509646Z","caller":"traceutil/trace.go:171","msg":"trace[309342128] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:746; }","duration":"160.2455ms","start":"2026-04-27T16:34:59.349392Z","end":"2026-04-27T16:34:59.509638Z","steps":["trace[309342128] 'agreement among raft nodes before linearized reading' (duration: 160.132486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.510379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.731241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T16:34:59.510422Z","caller":"traceutil/trace.go:171","msg":"trace[1339141782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"108.812326ms","start":"2026-04-27T16:34:59.401599Z","end":"2026-04-27T16:34:59.510411Z","steps":["trace[1339141782] 'agreement among raft nodes before linearized reading' (duration: 108.723841ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:59.788381Z","caller":"traceutil/trace.go:171","msg":"trace[1300226087] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"222.790873ms","start":"2026-04-27T16:34:59.565534Z","end":"2026-04-27T16:34:59.788325Z","steps":["trace[1300226087] 'process raft request' (duration: 199.747599ms)","trace[1300226087] 'compare' (duration: 22.928369ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:34:59.979504Z","caller":"traceutil/trace.go:171","msg":"trace[154294941] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:779; }","duration":"111.59764ms","start":"2026-04-27T16:34:59.867885Z","end":"2026-04-27T16:34:59.979482Z","steps":["trace[154294941] 'read index received' (duration: 27.768147ms)","trace[154294941] 'applied index is now lower than readState.Index' (duration: 83.828693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:59.979779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:59.567967Z","time spent":"411.804181ms","remote":"127.0.0.1:55734","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T16:34:59.979918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.801687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4428"} {"level":"info","ts":"2026-04-27T16:34:59.979969Z","caller":"traceutil/trace.go:171","msg":"trace[281720561] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:750; }","duration":"112.123061ms","start":"2026-04-27T16:34:59.867834Z","end":"2026-04-27T16:34:59.979957Z","steps":["trace[281720561] 'agreement among raft nodes before linearized reading' (duration: 111.737655ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:34:59.979978Z","caller":"traceutil/trace.go:171","msg":"trace[912920511] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"412.184237ms","start":"2026-04-27T16:34:59.56777Z","end":"2026-04-27T16:34:59.979955Z","steps":["trace[912920511] 'process raft request' (duration: 327.951298ms)","trace[912920511] 'compare' (duration: 83.348493ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:34:59.980098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:59.567743Z","time spent":"412.291411ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:34:59.980096Z","caller":"traceutil/trace.go:171","msg":"trace[549829405] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"408.285448ms","start":"2026-04-27T16:34:59.57172Z","end":"2026-04-27T16:34:59.980006Z","steps":["trace[549829405] 'process raft request' (duration: 407.668153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:34:59.980316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:59.571705Z","time spent":"408.507736ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4039,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:35:00.617625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.865547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:35:00.618002Z","caller":"traceutil/trace.go:171","msg":"trace[70598518] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"320.449122ms","start":"2026-04-27T16:35:00.297533Z","end":"2026-04-27T16:35:00.617982Z","steps":["trace[70598518] 'process raft request' (duration: 320.411071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:00.618095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.297503Z","time spent":"320.545076ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:35:00.618081Z","caller":"traceutil/trace.go:171","msg":"trace[497422930] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"629.260447ms","start":"2026-04-27T16:34:59.988781Z","end":"2026-04-27T16:35:00.618041Z","steps":["trace[497422930] 'process raft request' (duration: 629.065529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:00.618253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:59.988771Z","time spent":"629.388161ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4039,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:35:00.618307Z","caller":"traceutil/trace.go:171","msg":"trace[1967034036] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"339.408779ms","start":"2026-04-27T16:35:00.278888Z","end":"2026-04-27T16:35:00.618296Z","steps":["trace[1967034036] 'process raft request' (duration: 339.011233ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:00.618336Z","caller":"traceutil/trace.go:171","msg":"trace[1119208085] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:783; }","duration":"568.174274ms","start":"2026-04-27T16:35:00.05014Z","end":"2026-04-27T16:35:00.618315Z","steps":["trace[1119208085] 'read index received' (duration: 320.570087ms)","trace[1119208085] 'applied index is now lower than readState.Index' (duration: 247.602107ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:00.618369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.278858Z","time spent":"339.482032ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:35:00.618472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.3247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:00.618499Z","caller":"traceutil/trace.go:171","msg":"trace[2054264749] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"631.458666ms","start":"2026-04-27T16:34:59.987031Z","end":"2026-04-27T16:35:00.618489Z","steps":["trace[2054264749] 'process raft request' (duration: 630.712495ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:00.618479Z","caller":"traceutil/trace.go:171","msg":"trace[1498868039] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"631.523898ms","start":"2026-04-27T16:34:59.986926Z","end":"2026-04-27T16:35:00.61845Z","steps":["trace[1498868039] 'process raft request' (duration: 383.762965ms)","trace[1498868039] 'compare' (duration: 246.680249ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:35:00.618528Z","caller":"traceutil/trace.go:171","msg":"trace[386941418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"568.422484ms","start":"2026-04-27T16:35:00.050094Z","end":"2026-04-27T16:35:00.618517Z","steps":["trace[386941418] 'agreement among raft nodes before linearized reading' (duration: 568.3222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:00.618547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:59.986994Z","time spent":"631.529158ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4425,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:35:00.618564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.050069Z","time spent":"568.484796ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:35:00.618602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:34:59.986906Z","time spent":"631.634143ms","remote":"127.0.0.1:55734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":824,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:35:00.686869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.492852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-27T16:35:00.68689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.378595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:00.686929Z","caller":"traceutil/trace.go:171","msg":"trace[205029951] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:757; }","duration":"441.616256ms","start":"2026-04-27T16:35:00.245299Z","end":"2026-04-27T16:35:00.686915Z","steps":["trace[205029951] 'agreement among raft nodes before linearized reading' (duration: 441.396067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:00.686932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.519082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:00.686946Z","caller":"traceutil/trace.go:171","msg":"trace[153339566] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:757; }","duration":"542.451329ms","start":"2026-04-27T16:35:00.14448Z","end":"2026-04-27T16:35:00.686932Z","steps":["trace[153339566] 'agreement among raft nodes before linearized reading' (duration: 542.340384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:00.686966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.245267Z","time spent":"441.69236ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"warn","ts":"2026-04-27T16:35:00.686987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.144474Z","time spent":"542.502452ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-04-27T16:35:00.686996Z","caller":"traceutil/trace.go:171","msg":"trace[1614965497] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:757; }","duration":"542.579023ms","start":"2026-04-27T16:35:00.144388Z","end":"2026-04-27T16:35:00.686967Z","steps":["trace[1614965497] 'agreement among raft nodes before linearized reading' (duration: 542.518571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:00.687033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.14438Z","time spent":"542.644038ms","remote":"127.0.0.1:56930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-04-27T16:35:00.686875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.348821ms","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-04-27T16:35:00.687186Z","caller":"traceutil/trace.go:171","msg":"trace[608065631] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:757; }","duration":"559.669785ms","start":"2026-04-27T16:35:00.127504Z","end":"2026-04-27T16:35:00.687174Z","steps":["trace[608065631] 'agreement among raft nodes before linearized reading' (duration: 559.302419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:00.687222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.127458Z","time spent":"559.753028ms","remote":"127.0.0.1:55484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:35:00.687678Z","caller":"traceutil/trace.go:171","msg":"trace[813108096] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"241.057698ms","start":"2026-04-27T16:35:00.446595Z","end":"2026-04-27T16:35:00.687653Z","steps":["trace[813108096] 'process raft request' (duration: 239.935412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:00.687733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.381976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1794"} {"level":"info","ts":"2026-04-27T16:35:00.687773Z","caller":"traceutil/trace.go:171","msg":"trace[1323857290] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:757; }","duration":"440.46062ms","start":"2026-04-27T16:35:00.2473Z","end":"2026-04-27T16:35:00.68776Z","steps":["trace[1323857290] 'agreement among raft nodes before linearized reading' (duration: 440.356675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:00.687802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.247287Z","time spent":"440.509291ms","remote":"127.0.0.1:56900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":1817,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-04-27T16:35:00.947965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.36658ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-27T16:35:00.948286Z","caller":"traceutil/trace.go:171","msg":"trace[440952359] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:789; }","duration":"247.954091ms","start":"2026-04-27T16:35:00.700315Z","end":"2026-04-27T16:35:00.948269Z","steps":["trace[440952359] 'read index received' (duration: 58.286028ms)","trace[440952359] 'applied index is now lower than readState.Index' (duration: 189.667253ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:35:00.948472Z","caller":"traceutil/trace.go:171","msg":"trace[140029516] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"249.819507ms","start":"2026-04-27T16:35:00.698642Z","end":"2026-04-27T16:35:00.948461Z","steps":["trace[140029516] 'process raft request' (duration: 249.41922ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:00.948545Z","caller":"traceutil/trace.go:171","msg":"trace[66004771] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"241.193973ms","start":"2026-04-27T16:35:00.707327Z","end":"2026-04-27T16:35:00.948521Z","steps":["trace[66004771] 'process raft request' (duration: 240.898381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:00.948716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.386639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:00.948767Z","caller":"traceutil/trace.go:171","msg":"trace[192108392] range","detail":"{range_begin:/registry/resourcequotas/envoy-gateway-system/; range_end:/registry/resourcequotas/envoy-gateway-system0; response_count:0; response_revision:760; }","duration":"248.465472ms","start":"2026-04-27T16:35:00.700286Z","end":"2026-04-27T16:35:00.948752Z","steps":["trace[192108392] 'agreement among raft nodes before linearized reading' (duration: 248.361688ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:00.948824Z","caller":"traceutil/trace.go:171","msg":"trace[887762180] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"249.249444ms","start":"2026-04-27T16:35:00.699563Z","end":"2026-04-27T16:35:00.948812Z","steps":["trace[887762180] 'process raft request' (duration: 248.609548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:01.351498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.753457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:35:01.351969Z","caller":"traceutil/trace.go:171","msg":"trace[1332575128] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"399.150729ms","start":"2026-04-27T16:35:00.952797Z","end":"2026-04-27T16:35:01.351948Z","steps":["trace[1332575128] 'process raft request' (duration: 145.876341ms)","trace[1332575128] 'compare' (duration: 252.217084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:01.352033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.952774Z","time spent":"399.229283ms","remote":"127.0.0.1:56918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2861,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:35:01.425239Z","caller":"traceutil/trace.go:171","msg":"trace[376175578] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:793; }","duration":"470.909981ms","start":"2026-04-27T16:35:00.954313Z","end":"2026-04-27T16:35:01.425223Z","steps":["trace[376175578] 'read index received' (duration: 144.459672ms)","trace[376175578] 'applied index is now lower than readState.Index' (duration: 326.448869ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:35:01.425308Z","caller":"traceutil/trace.go:171","msg":"trace[113657429] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"472.397803ms","start":"2026-04-27T16:35:00.952871Z","end":"2026-04-27T16:35:01.425269Z","steps":["trace[113657429] 'process raft request' (duration: 472.155393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:01.425476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.952838Z","time spent":"472.543779ms","remote":"127.0.0.1:55190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:35:01.425495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.161392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-cpscb\" ","response":"range_response_count:1 size:7041"} {"level":"info","ts":"2026-04-27T16:35:01.425553Z","caller":"traceutil/trace.go:171","msg":"trace[1129441566] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-cpscb; range_end:; response_count:1; response_revision:764; }","duration":"471.246205ms","start":"2026-04-27T16:35:00.954297Z","end":"2026-04-27T16:35:01.425543Z","steps":["trace[1129441566] 'agreement among raft nodes before linearized reading' (duration: 471.033917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:01.425589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.954286Z","time spent":"471.293408ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":7064,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-cpscb\" "} {"level":"warn","ts":"2026-04-27T16:35:01.425676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.557049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:01.425745Z","caller":"traceutil/trace.go:171","msg":"trace[2032193153] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:764; }","duration":"461.657933ms","start":"2026-04-27T16:35:00.964073Z","end":"2026-04-27T16:35:01.425731Z","steps":["trace[2032193153] 'agreement among raft nodes before linearized reading' (duration: 461.293078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:01.425781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.964063Z","time spent":"461.710406ms","remote":"127.0.0.1:55224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-04-27T16:35:01.425841Z","caller":"traceutil/trace.go:171","msg":"trace[1787938174] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"464.318941ms","start":"2026-04-27T16:35:00.961514Z","end":"2026-04-27T16:35:01.425833Z","steps":["trace[1787938174] 'process raft request' (duration: 463.618423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:01.42588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.961501Z","time spent":"464.359044ms","remote":"127.0.0.1:55318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4553,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:35:01.426021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.750463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T16:35:01.426065Z","caller":"traceutil/trace.go:171","msg":"trace[1647563427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"121.835446ms","start":"2026-04-27T16:35:01.30422Z","end":"2026-04-27T16:35:01.426056Z","steps":["trace[1647563427] 'agreement among raft nodes before linearized reading' (duration: 121.731862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:01.426159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.140624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:01.426119Z","caller":"traceutil/trace.go:171","msg":"trace[2004025908] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"462.636933ms","start":"2026-04-27T16:35:00.963429Z","end":"2026-04-27T16:35:01.426066Z","steps":["trace[2004025908] 'process raft request' (duration: 461.744616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:01.426465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:00.963416Z","time spent":"462.896404ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:35:01.42619Z","caller":"traceutil/trace.go:171","msg":"trace[2147202634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"374.209116ms","start":"2026-04-27T16:35:01.051973Z","end":"2026-04-27T16:35:01.426182Z","steps":["trace[2147202634] 'agreement among raft nodes before linearized reading' (duration: 374.162584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:01.426723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:01.051949Z","time spent":"374.76064ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:35:01.656673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.735477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:35:01.656762Z","caller":"traceutil/trace.go:171","msg":"trace[1624390797] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"225.78263ms","start":"2026-04-27T16:35:01.430964Z","end":"2026-04-27T16:35:01.656747Z","steps":["trace[1624390797] 'read index received' (duration: 29.916487ms)","trace[1624390797] 'applied index is now lower than readState.Index' (duration: 195.865193ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:35:01.656902Z","caller":"traceutil/trace.go:171","msg":"trace[162039984] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"227.381445ms","start":"2026-04-27T16:35:01.429511Z","end":"2026-04-27T16:35:01.656892Z","steps":["trace[162039984] 'process raft request' (duration: 31.358776ms)","trace[162039984] 'compare' (duration: 195.631203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:01.657072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.096713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-27T16:35:01.65713Z","caller":"traceutil/trace.go:171","msg":"trace[908532127] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:765; }","duration":"226.204187ms","start":"2026-04-27T16:35:01.430913Z","end":"2026-04-27T16:35:01.657117Z","steps":["trace[908532127] 'agreement among raft nodes before linearized reading' (duration: 226.049201ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:01.693402Z","caller":"traceutil/trace.go:171","msg":"trace[1633290743] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"260.099218ms","start":"2026-04-27T16:35:01.433282Z","end":"2026-04-27T16:35:01.693381Z","steps":["trace[1633290743] 'process raft request' (duration: 259.754443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:01.693769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.3362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:01.693816Z","caller":"traceutil/trace.go:171","msg":"trace[1831398244] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:768; }","duration":"253.419554ms","start":"2026-04-27T16:35:01.440385Z","end":"2026-04-27T16:35:01.693805Z","steps":["trace[1831398244] 'agreement among raft nodes before linearized reading' (duration: 253.33899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:01.693799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.44185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:35:01.693861Z","caller":"traceutil/trace.go:171","msg":"trace[1657244332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"179.545593ms","start":"2026-04-27T16:35:01.514303Z","end":"2026-04-27T16:35:01.693848Z","steps":["trace[1657244332] 'agreement among raft nodes before linearized reading' (duration: 179.383607ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:01.694016Z","caller":"traceutil/trace.go:171","msg":"trace[1494428539] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"260.364778ms","start":"2026-04-27T16:35:01.433643Z","end":"2026-04-27T16:35:01.694008Z","steps":["trace[1494428539] 'process raft request' (duration: 259.598627ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:01.694203Z","caller":"traceutil/trace.go:171","msg":"trace[830396367] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"260.375599ms","start":"2026-04-27T16:35:01.43382Z","end":"2026-04-27T16:35:01.694196Z","steps":["trace[830396367] 'process raft request' (duration: 259.479212ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:01.97277Z","caller":"traceutil/trace.go:171","msg":"trace[1687622557] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"267.401627ms","start":"2026-04-27T16:35:01.705337Z","end":"2026-04-27T16:35:01.972738Z","steps":["trace[1687622557] 'process raft request' (duration: 236.065142ms)","trace[1687622557] 'compare' (duration: 31.094655ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:35:03.602099Z","caller":"traceutil/trace.go:171","msg":"trace[1225268007] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"102.107524ms","start":"2026-04-27T16:35:03.499968Z","end":"2026-04-27T16:35:03.602076Z","steps":["trace[1225268007] 'read index received' (duration: 102.027191ms)","trace[1225268007] 'applied index is now lower than readState.Index' (duration: 79.233µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:03.602299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.286931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:03.602387Z","caller":"traceutil/trace.go:171","msg":"trace[333868021] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:772; }","duration":"102.430347ms","start":"2026-04-27T16:35:03.499936Z","end":"2026-04-27T16:35:03.602367Z","steps":["trace[333868021] 'agreement among raft nodes before linearized reading' (duration: 102.237599ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:03.603269Z","caller":"traceutil/trace.go:171","msg":"trace[1736346232] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"164.836762ms","start":"2026-04-27T16:35:03.437925Z","end":"2026-04-27T16:35:03.602762Z","steps":["trace[1736346232] 'process raft request' (duration: 164.007558ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:03.649952Z","caller":"traceutil/trace.go:171","msg":"trace[388701745] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"106.478804ms","start":"2026-04-27T16:35:03.543445Z","end":"2026-04-27T16:35:03.649924Z","steps":["trace[388701745] 'process raft request' (duration: 106.342249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:03.845919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.822331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T16:35:03.84602Z","caller":"traceutil/trace.go:171","msg":"trace[2078904154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:773; }","duration":"137.942006ms","start":"2026-04-27T16:35:03.70805Z","end":"2026-04-27T16:35:03.845992Z","steps":["trace[2078904154] 'range keys from in-memory index tree' (duration: 137.635113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:03.846017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.368944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:35:03.846111Z","caller":"traceutil/trace.go:171","msg":"trace[1097243286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"129.493189ms","start":"2026-04-27T16:35:03.716594Z","end":"2026-04-27T16:35:03.846087Z","steps":["trace[1097243286] 'range keys from in-memory index tree' (duration: 129.209897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:04.151723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.304663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:35:04.152032Z","caller":"traceutil/trace.go:171","msg":"trace[205386471] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"300.123323ms","start":"2026-04-27T16:35:03.851881Z","end":"2026-04-27T16:35:04.152005Z","steps":["trace[205386471] 'process raft request' (duration: 182.447205ms)","trace[205386471] 'compare' (duration: 117.134426ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:04.152311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:03.851854Z","time spent":"300.246668ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:35:04.229697Z","caller":"traceutil/trace.go:171","msg":"trace[1778425641] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:807; }","duration":"193.463654ms","start":"2026-04-27T16:35:04.036206Z","end":"2026-04-27T16:35:04.22967Z","steps":["trace[1778425641] 'read index received' (duration: 99.881367ms)","trace[1778425641] 'applied index is now lower than readState.Index' (duration: 93.579356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:04.230004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.784016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T16:35:04.229898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.978806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:04.230078Z","caller":"traceutil/trace.go:171","msg":"trace[65768468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"193.887011ms","start":"2026-04-27T16:35:04.036173Z","end":"2026-04-27T16:35:04.23006Z","steps":["trace[65768468] 'agreement among raft nodes before linearized reading' (duration: 193.61831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:04.230021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.364874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:35:04.230248Z","caller":"traceutil/trace.go:171","msg":"trace[1458762529] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:776; }","duration":"144.591474ms","start":"2026-04-27T16:35:04.085599Z","end":"2026-04-27T16:35:04.230191Z","steps":["trace[1458762529] 'agreement among raft nodes before linearized reading' (duration: 144.302342ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:04.230293Z","caller":"traceutil/trace.go:171","msg":"trace[1723022303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"177.252807ms","start":"2026-04-27T16:35:04.052835Z","end":"2026-04-27T16:35:04.230088Z","steps":["trace[1723022303] 'agreement among raft nodes before linearized reading' (duration: 176.976105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:05.405611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.846347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:05.406132Z","caller":"traceutil/trace.go:171","msg":"trace[238352636] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:779; }","duration":"291.424221ms","start":"2026-04-27T16:35:05.114682Z","end":"2026-04-27T16:35:05.406106Z","steps":["trace[238352636] 'count revisions from in-memory index tree' (duration: 290.745503ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:06.208875Z","caller":"traceutil/trace.go:171","msg":"trace[679401122] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"156.37741ms","start":"2026-04-27T16:35:06.052472Z","end":"2026-04-27T16:35:06.208849Z","steps":["trace[679401122] 'read index received' (duration: 156.296207ms)","trace[679401122] 'applied index is now lower than readState.Index' (duration: 80.123µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:06.209048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.560247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:06.2091Z","caller":"traceutil/trace.go:171","msg":"trace[567824116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"156.667532ms","start":"2026-04-27T16:35:06.052417Z","end":"2026-04-27T16:35:06.209084Z","steps":["trace[567824116] 'agreement among raft nodes before linearized reading' (duration: 156.579528ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:06.208931Z","caller":"traceutil/trace.go:171","msg":"trace[1873193938] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"200.702065ms","start":"2026-04-27T16:35:06.008199Z","end":"2026-04-27T16:35:06.208901Z","steps":["trace[1873193938] 'process raft request' (duration: 200.519038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:06.647762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.527948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:35:06.647853Z","caller":"traceutil/trace.go:171","msg":"trace[522791475] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"432.839403ms","start":"2026-04-27T16:35:06.214996Z","end":"2026-04-27T16:35:06.647836Z","steps":["trace[522791475] 'process raft request' (duration: 282.146639ms)","trace[522791475] 'compare' (duration: 150.266237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:06.647899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:06.214983Z","time spent":"432.896376ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:35:07.10679Z","caller":"traceutil/trace.go:171","msg":"trace[555456956] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:818; }","duration":"773.296516ms","start":"2026-04-27T16:35:06.333466Z","end":"2026-04-27T16:35:07.106763Z","steps":["trace[555456956] 'read index received' (duration: 163.685971ms)","trace[555456956] 'applied index is now lower than readState.Index' (duration: 609.609385ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:07.10701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.533235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T16:35:07.107048Z","caller":"traceutil/trace.go:171","msg":"trace[656746306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"773.624869ms","start":"2026-04-27T16:35:06.333413Z","end":"2026-04-27T16:35:07.107038Z","steps":["trace[656746306] 'agreement among raft nodes before linearized reading' (duration: 773.440062ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:07.106972Z","caller":"traceutil/trace.go:171","msg":"trace[2053771655] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"885.947555ms","start":"2026-04-27T16:35:06.220996Z","end":"2026-04-27T16:35:07.106944Z","steps":["trace[2053771655] 'process raft request' (duration: 811.380152ms)","trace[2053771655] 'compare' (duration: 74.211878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:07.107112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:06.333386Z","time spent":"773.689462ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:35:07.107414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:06.220989Z","time spent":"886.177254ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:35:08.30906Z","caller":"traceutil/trace.go:171","msg":"trace[1970860552] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"256.032639ms","start":"2026-04-27T16:35:08.053003Z","end":"2026-04-27T16:35:08.309035Z","steps":["trace[1970860552] 'read index received' (duration: 255.831081ms)","trace[1970860552] 'applied index is now lower than readState.Index' (duration: 200.788µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:35:08.309122Z","caller":"traceutil/trace.go:171","msg":"trace[1114061682] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"304.638134ms","start":"2026-04-27T16:35:08.00445Z","end":"2026-04-27T16:35:08.309088Z","steps":["trace[1114061682] 'process raft request' (duration: 304.436805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:08.309214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.192666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:08.309276Z","caller":"traceutil/trace.go:171","msg":"trace[144768778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"256.319051ms","start":"2026-04-27T16:35:08.052944Z","end":"2026-04-27T16:35:08.309263Z","steps":["trace[144768778] 'agreement among raft nodes before linearized reading' (duration: 256.196016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:08.309398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:08.004428Z","time spent":"304.862423ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:35:09.702807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.57641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:35:09.702914Z","caller":"traceutil/trace.go:171","msg":"trace[821125644] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:789; }","duration":"350.738887ms","start":"2026-04-27T16:35:09.352149Z","end":"2026-04-27T16:35:09.702888Z","steps":["trace[821125644] 'count revisions from in-memory index tree' (duration: 350.469786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:09.702971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:09.352133Z","time spent":"350.82171ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:35:10.893199Z","caller":"traceutil/trace.go:171","msg":"trace[1179604210] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"143.047579ms","start":"2026-04-27T16:35:10.750126Z","end":"2026-04-27T16:35:10.893173Z","steps":["trace[1179604210] 'process raft request' (duration: 102.143729ms)","trace[1179604210] 'compare' (duration: 40.801566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:35:13.710801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.526982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-796wt\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-27T16:35:13.710827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.372916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-27T16:35:13.710949Z","caller":"traceutil/trace.go:171","msg":"trace[811825178] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:813; }","duration":"764.622128ms","start":"2026-04-27T16:35:12.946299Z","end":"2026-04-27T16:35:13.710921Z","steps":["trace[811825178] 'range keys from in-memory index tree' (duration: 764.167908ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:35:13.710956Z","caller":"traceutil/trace.go:171","msg":"trace[582987353] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-796wt; range_end:; response_count:1; response_revision:813; }","duration":"684.727852ms","start":"2026-04-27T16:35:13.02617Z","end":"2026-04-27T16:35:13.710898Z","steps":["trace[582987353] 'range keys from in-memory index tree' (duration: 684.269233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:13.711006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:12.946262Z","time spent":"764.729732ms","remote":"127.0.0.1:55360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-04-27T16:35:13.711015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:13.026147Z","time spent":"684.854867ms","remote":"127.0.0.1:55334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-796wt\" "} {"level":"warn","ts":"2026-04-27T16:35:13.711076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.717422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:35:13.711207Z","caller":"traceutil/trace.go:171","msg":"trace[2096359561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"656.950141ms","start":"2026-04-27T16:35:13.054229Z","end":"2026-04-27T16:35:13.71118Z","steps":["trace[2096359561] 'range keys from in-memory index tree' (duration: 656.633369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:13.711258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:13.054213Z","time spent":"657.034955ms","remote":"127.0.0.1:55162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:35:13.711543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.26356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T16:35:13.711649Z","caller":"traceutil/trace.go:171","msg":"trace[1104230132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"447.428006ms","start":"2026-04-27T16:35:13.264195Z","end":"2026-04-27T16:35:13.711623Z","steps":["trace[1104230132] 'range keys from in-memory index tree' (duration: 446.972167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:35:13.711704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:35:13.264172Z","time spent":"447.5202ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T16:36:06.776064Z","caller":"traceutil/trace.go:171","msg":"trace[697057632] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"112.186727ms","start":"2026-04-27T16:36:06.663848Z","end":"2026-04-27T16:36:06.776035Z","steps":["trace[697057632] 'process raft request' (duration: 112.03066ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:36:06.804474Z","caller":"traceutil/trace.go:171","msg":"trace[319762144] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"111.760838ms","start":"2026-04-27T16:36:06.692688Z","end":"2026-04-27T16:36:06.804449Z","steps":["trace[319762144] 'process raft request' (duration: 111.650274ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:37:05.760478Z","caller":"traceutil/trace.go:171","msg":"trace[1961565897] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"158.690381ms","start":"2026-04-27T16:37:05.601759Z","end":"2026-04-27T16:37:05.760449Z","steps":["trace[1961565897] 'read index received' (duration: 156.528965ms)","trace[1961565897] 'applied index is now lower than readState.Index' (duration: 2.159576ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:37:05.760742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.966134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T16:37:05.760816Z","caller":"traceutil/trace.go:171","msg":"trace[763461441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1354; }","duration":"159.10859ms","start":"2026-04-27T16:37:05.601696Z","end":"2026-04-27T16:37:05.760805Z","steps":["trace[763461441] 'agreement among raft nodes before linearized reading' (duration: 158.88677ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:37:13.997387Z","caller":"traceutil/trace.go:171","msg":"trace[459242719] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"187.918176ms","start":"2026-04-27T16:37:13.809439Z","end":"2026-04-27T16:37:13.997357Z","steps":["trace[459242719] 'process raft request' (duration: 187.743729ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:37:20.717781Z","caller":"traceutil/trace.go:171","msg":"trace[174089351] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"157.050975ms","start":"2026-04-27T16:37:20.560685Z","end":"2026-04-27T16:37:20.717736Z","steps":["trace[174089351] 'process raft request' (duration: 156.743582ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:37:20.82887Z","caller":"traceutil/trace.go:171","msg":"trace[1789484768] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"106.92784ms","start":"2026-04-27T16:37:20.721909Z","end":"2026-04-27T16:37:20.828837Z","steps":["trace[1789484768] 'process raft request' (duration: 34.73542ms)","trace[1789484768] 'compare' (duration: 71.982601ms)"],"step_count":2}