{"level":"warn","ts":"2026-04-28T06:04:22.320063Z","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-28T06:04:22.320166Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-28T06:04:22.320298Z","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-28T06:04:22.320316Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-28T06:04:22.320335Z","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-28T06:04:22.32097Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-04-28T06:04:22.321066Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-28T06:04:22.325672Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.289524ms"} {"level":"info","ts":"2026-04-28T06:04:22.331914Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-04-28T06:04:22.331997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:04:22.332023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-04-28T06:04:22.332033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:04:22.332128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-04-28T06:04:22.33223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-04-28T06:04:22.34136Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:04:22.343271Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:04:22.34464Z","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-28T06:04:22.348016Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:04:22.348929Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:04:22.349226Z","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-28T06:04:22.349358Z","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-28T06:04:22.349374Z","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-28T06:04:22.354782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-04-28T06:04:22.355158Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-28T06:04:22.357915Z","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-28T06:04:22.358262Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:04:22.358317Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:04:22.35838Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-28T06:04:22.358388Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-28T06:04:22.5326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:04:22.532672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:04:22.532709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-04-28T06:04:22.532727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:04:22.532733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-28T06:04:22.532747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-04-28T06:04:22.532759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-28T06:04:22.53601Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:04:22.536425Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:04:22.536591Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:04:22.536644Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:04:22.536811Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:04:22.53686Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:04:22.537143Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:04:22.537295Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:04:22.537334Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:04:22.539055Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-04-28T06:04:22.539128Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:04:52.81342Z","caller":"traceutil/trace.go:171","msg":"trace[2009784970] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"178.997583ms","start":"2026-04-28T06:04:52.634358Z","end":"2026-04-28T06:04:52.813356Z","steps":["trace[2009784970] 'process raft request' (duration: 138.260057ms)","trace[2009784970] 'compare' (duration: 40.466217ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:04:52.814044Z","caller":"traceutil/trace.go:171","msg":"trace[1877095200] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"130.451188ms","start":"2026-04-28T06:04:52.683569Z","end":"2026-04-28T06:04:52.814021Z","steps":["trace[1877095200] 'process raft request' (duration: 130.346845ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:52.814337Z","caller":"traceutil/trace.go:171","msg":"trace[1796186533] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"170.525664ms","start":"2026-04-28T06:04:52.643794Z","end":"2026-04-28T06:04:52.81432Z","steps":["trace[1796186533] 'process raft request' (duration: 169.950206ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:52.814522Z","caller":"traceutil/trace.go:171","msg":"trace[361289599] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"173.525396ms","start":"2026-04-28T06:04:52.640986Z","end":"2026-04-28T06:04:52.814511Z","steps":["trace[361289599] 'process raft request' (duration: 172.657229ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:53.096713Z","caller":"traceutil/trace.go:171","msg":"trace[1960032622] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"148.949304ms","start":"2026-04-28T06:04:52.947436Z","end":"2026-04-28T06:04:53.096385Z","steps":["trace[1960032622] 'process raft request' (duration: 120.04298ms)","trace[1960032622] 'compare' (duration: 28.754699ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:04:55.722091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.60063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:04:55.722355Z","caller":"traceutil/trace.go:171","msg":"trace[1541453098] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"200.964904ms","start":"2026-04-28T06:04:55.521369Z","end":"2026-04-28T06:04:55.722334Z","steps":["trace[1541453098] 'process raft request' (duration: 200.889292ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:04:55.72235Z","caller":"traceutil/trace.go:171","msg":"trace[1264176642] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"200.930113ms","start":"2026-04-28T06:04:55.521341Z","end":"2026-04-28T06:04:55.722272Z","steps":["trace[1264176642] 'process raft request' (duration: 39.657262ms)","trace[1264176642] 'compare' (duration: 160.456436ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:04:56.193014Z","caller":"traceutil/trace.go:171","msg":"trace[275120012] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"265.217477ms","start":"2026-04-28T06:04:55.927774Z","end":"2026-04-28T06:04:56.192992Z","steps":["trace[275120012] 'process raft request' (duration: 263.02379ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:00.050747Z","caller":"traceutil/trace.go:171","msg":"trace[2054525785] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"156.594867ms","start":"2026-04-28T06:04:59.894128Z","end":"2026-04-28T06:05:00.050723Z","steps":["trace[2054525785] 'process raft request' (duration: 156.076862ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:05:02.726719Z","caller":"traceutil/trace.go:171","msg":"trace[1810658225] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"181.280202ms","start":"2026-04-28T06:05:02.54542Z","end":"2026-04-28T06:05:02.726701Z","steps":["trace[1810658225] 'process raft request' (duration: 84.010678ms)","trace[1810658225] 'compare' (duration: 96.949754ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:05:15.878627Z","caller":"traceutil/trace.go:171","msg":"trace[1256144743] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"334.293251ms","start":"2026-04-28T06:05:15.544311Z","end":"2026-04-28T06:05:15.878605Z","steps":["trace[1256144743] 'process raft request' (duration: 334.166537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:05:15.879109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:05:15.544288Z","time spent":"334.409734ms","remote":"127.0.0.1:36822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:08:40.70913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.055396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:08:40.709241Z","caller":"traceutil/trace.go:171","msg":"trace[1548733753] linearizableReadLoop","detail":"{readStateIndex:1583; appliedIndex:1582; }","duration":"190.465647ms","start":"2026-04-28T06:08:40.518759Z","end":"2026-04-28T06:08:40.709225Z","steps":["trace[1548733753] 'read index received' (duration: 49.210545ms)","trace[1548733753] 'applied index is now lower than readState.Index' (duration: 141.253972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:08:40.7094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.640921ms","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-28T06:08:40.709426Z","caller":"traceutil/trace.go:171","msg":"trace[267494066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1513; }","duration":"190.700253ms","start":"2026-04-28T06:08:40.518717Z","end":"2026-04-28T06:08:40.709417Z","steps":["trace[267494066] 'agreement among raft nodes before linearized reading' (duration: 190.550829ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:08:40.709503Z","caller":"traceutil/trace.go:171","msg":"trace[1657125907] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"236.527095ms","start":"2026-04-28T06:08:40.472946Z","end":"2026-04-28T06:08:40.709473Z","steps":["trace[1657125907] 'process raft request' (duration: 95.046148ms)","trace[1657125907] 'compare' (duration: 140.84571ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:08:40.864684Z","caller":"traceutil/trace.go:171","msg":"trace[1762033180] linearizableReadLoop","detail":"{readStateIndex:1584; appliedIndex:1583; }","duration":"109.11165ms","start":"2026-04-28T06:08:40.755549Z","end":"2026-04-28T06:08:40.864661Z","steps":["trace[1762033180] 'read index received' (duration: 106.697484ms)","trace[1762033180] 'applied index is now lower than readState.Index' (duration: 2.413146ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:08:40.864792Z","caller":"traceutil/trace.go:171","msg":"trace[1133894807] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"151.422438ms","start":"2026-04-28T06:08:40.713336Z","end":"2026-04-28T06:08:40.864759Z","steps":["trace[1133894807] 'process raft request' (duration: 148.897329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:08:40.864844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.270734ms","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-28T06:08:40.864923Z","caller":"traceutil/trace.go:171","msg":"trace[2009582371] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1514; }","duration":"109.361227ms","start":"2026-04-28T06:08:40.75551Z","end":"2026-04-28T06:08:40.864871Z","steps":["trace[2009582371] 'agreement among raft nodes before linearized reading' (duration: 109.241373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:01.613579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.868857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:01.613657Z","caller":"traceutil/trace.go:171","msg":"trace[993547906] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1581; }","duration":"239.98429ms","start":"2026-04-28T06:09:01.373651Z","end":"2026-04-28T06:09:01.613635Z","steps":["trace[993547906] 'count revisions from in-memory index tree' (duration: 239.709922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:01.827852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.952054ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-28T06:09:01.827924Z","caller":"traceutil/trace.go:171","msg":"trace[2075376623] linearizableReadLoop","detail":"{readStateIndex:1656; appliedIndex:1655; }","duration":"125.801092ms","start":"2026-04-28T06:09:01.70211Z","end":"2026-04-28T06:09:01.827911Z","steps":["trace[2075376623] 'read index received' (duration: 12.726855ms)","trace[2075376623] 'applied index is now lower than readState.Index' (duration: 113.071017ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:01.828033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.923485ms","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-28T06:09:01.82805Z","caller":"traceutil/trace.go:171","msg":"trace[327839602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1581; }","duration":"125.948726ms","start":"2026-04-28T06:09:01.702095Z","end":"2026-04-28T06:09:01.828044Z","steps":["trace[327839602] 'agreement among raft nodes before linearized reading' (duration: 125.845133ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:04.993502Z","caller":"traceutil/trace.go:171","msg":"trace[613763507] linearizableReadLoop","detail":"{readStateIndex:1666; appliedIndex:1665; }","duration":"161.653755ms","start":"2026-04-28T06:09:04.831826Z","end":"2026-04-28T06:09:04.99348Z","steps":["trace[613763507] 'read index received' (duration: 161.46541ms)","trace[613763507] 'applied index is now lower than readState.Index' (duration: 187.385µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:04.993682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.84444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T06:09:04.993719Z","caller":"traceutil/trace.go:171","msg":"trace[1108760733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1591; }","duration":"161.897131ms","start":"2026-04-28T06:09:04.831812Z","end":"2026-04-28T06:09:04.993709Z","steps":["trace[1108760733] 'agreement among raft nodes before linearized reading' (duration: 161.757258ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:04.993895Z","caller":"traceutil/trace.go:171","msg":"trace[1526794201] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"393.758301ms","start":"2026-04-28T06:09:04.599783Z","end":"2026-04-28T06:09:04.993542Z","steps":["trace[1526794201] 'process raft request' (duration: 393.561726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:04.994177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:04.599761Z","time spent":"394.349767ms","remote":"127.0.0.1:36888","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-28T06:09:05.232008Z","caller":"traceutil/trace.go:171","msg":"trace[1765946610] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"146.513274ms","start":"2026-04-28T06:09:05.085465Z","end":"2026-04-28T06:09:05.231978Z","steps":["trace[1765946610] 'process raft request' (duration: 123.700645ms)","trace[1765946610] 'compare' (duration: 22.411449ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:08.361852Z","caller":"traceutil/trace.go:171","msg":"trace[2133818935] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"150.98757ms","start":"2026-04-28T06:09:08.210838Z","end":"2026-04-28T06:09:08.361826Z","steps":["trace[2133818935] 'process raft request' (duration: 150.826246ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:21.281445Z","caller":"traceutil/trace.go:171","msg":"trace[349949934] linearizableReadLoop","detail":"{readStateIndex:1775; appliedIndex:1774; }","duration":"178.729478ms","start":"2026-04-28T06:09:21.102693Z","end":"2026-04-28T06:09:21.281422Z","steps":["trace[349949934] 'read index received' (duration: 178.586104ms)","trace[349949934] 'applied index is now lower than readState.Index' (duration: 142.464µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:21.281577Z","caller":"traceutil/trace.go:171","msg":"trace[662508958] transaction","detail":"{read_only:false; response_revision:1695; number_of_response:1; }","duration":"188.080853ms","start":"2026-04-28T06:09:21.093452Z","end":"2026-04-28T06:09:21.281533Z","steps":["trace[662508958] 'process raft request' (duration: 187.843386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:21.281641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.906972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:09:21.281681Z","caller":"traceutil/trace.go:171","msg":"trace[1036200530] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1695; }","duration":"178.994255ms","start":"2026-04-28T06:09:21.102676Z","end":"2026-04-28T06:09:21.28167Z","steps":["trace[1036200530] 'agreement among raft nodes before linearized reading' (duration: 178.886382ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:34.19305Z","caller":"traceutil/trace.go:171","msg":"trace[164020300] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"167.702249ms","start":"2026-04-28T06:09:34.025325Z","end":"2026-04-28T06:09:34.193027Z","steps":["trace[164020300] 'process raft request' (duration: 166.929708ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:34.600585Z","caller":"traceutil/trace.go:171","msg":"trace[685372723] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"127.102164ms","start":"2026-04-28T06:09:34.473457Z","end":"2026-04-28T06:09:34.600559Z","steps":["trace[685372723] 'process raft request' (duration: 126.95993ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:36.359173Z","caller":"traceutil/trace.go:171","msg":"trace[213743081] linearizableReadLoop","detail":"{readStateIndex:1829; appliedIndex:1828; }","duration":"145.403504ms","start":"2026-04-28T06:09:36.213746Z","end":"2026-04-28T06:09:36.35915Z","steps":["trace[213743081] 'read index received' (duration: 145.207519ms)","trace[213743081] 'applied index is now lower than readState.Index' (duration: 195.055µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:36.359296Z","caller":"traceutil/trace.go:171","msg":"trace[723985800] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"158.25249ms","start":"2026-04-28T06:09:36.201017Z","end":"2026-04-28T06:09:36.35927Z","steps":["trace[723985800] 'process raft request' (duration: 158.002323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:36.359585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.809824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T06:09:36.359632Z","caller":"traceutil/trace.go:171","msg":"trace[1378362643] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:1746; }","duration":"145.901346ms","start":"2026-04-28T06:09:36.213717Z","end":"2026-04-28T06:09:36.359618Z","steps":["trace[1378362643] 'agreement among raft nodes before linearized reading' (duration: 145.529166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:37.538772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.066005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-28T06:09:37.538835Z","caller":"traceutil/trace.go:171","msg":"trace[1019974970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1749; }","duration":"145.196368ms","start":"2026-04-28T06:09:37.393626Z","end":"2026-04-28T06:09:37.538823Z","steps":["trace[1019974970] 'range keys from in-memory index tree' (duration: 144.937721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:37.538861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.365339ms","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-28T06:09:37.538926Z","caller":"traceutil/trace.go:171","msg":"trace[656241414] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1749; }","duration":"448.442581ms","start":"2026-04-28T06:09:37.090467Z","end":"2026-04-28T06:09:37.53891Z","steps":["trace[656241414] 'count revisions from in-memory index tree' (duration: 448.278297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:37.538971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:37.090387Z","time spent":"448.575925ms","remote":"127.0.0.1:36704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":101,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:37.538993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.670676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:09:37.539022Z","caller":"traceutil/trace.go:171","msg":"trace[779807596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1749; }","duration":"125.721967ms","start":"2026-04-28T06:09:37.413293Z","end":"2026-04-28T06:09:37.539015Z","steps":["trace[779807596] 'range keys from in-memory index tree' (duration: 125.613084ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:42.498182Z","caller":"traceutil/trace.go:171","msg":"trace[1606756857] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"145.762063ms","start":"2026-04-28T06:09:42.352386Z","end":"2026-04-28T06:09:42.498148Z","steps":["trace[1606756857] 'process raft request' (duration: 145.563798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:43.938282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.853486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:43.938431Z","caller":"traceutil/trace.go:171","msg":"trace[1457837438] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"314.578071ms","start":"2026-04-28T06:09:43.62383Z","end":"2026-04-28T06:09:43.938408Z","steps":["trace[1457837438] 'read index received' (duration: 163.525079ms)","trace[1457837438] 'applied index is now lower than readState.Index' (duration: 151.051231ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:43.938518Z","caller":"traceutil/trace.go:171","msg":"trace[1272597381] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"318.894324ms","start":"2026-04-28T06:09:43.619609Z","end":"2026-04-28T06:09:43.938503Z","steps":["trace[1272597381] 'process raft request' (duration: 167.697899ms)","trace[1272597381] 'compare' (duration: 150.62946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:43.938592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:43.619595Z","time spent":"318.959095ms","remote":"127.0.0.1:36888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:43.938818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.96841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-28T06:09:43.938915Z","caller":"traceutil/trace.go:171","msg":"trace[1536098514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1804; }","duration":"315.098854ms","start":"2026-04-28T06:09:43.623792Z","end":"2026-04-28T06:09:43.938891Z","steps":["trace[1536098514] 'agreement among raft nodes before linearized reading' (duration: 314.828517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:43.93898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:43.623778Z","time spent":"315.186946ms","remote":"127.0.0.1:36888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:09:44.154737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.28844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:44.155537Z","caller":"traceutil/trace.go:171","msg":"trace[1181604979] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"216.958081ms","start":"2026-04-28T06:09:43.938473Z","end":"2026-04-28T06:09:44.155431Z","steps":["trace[1181604979] 'read index received' (duration: 104.871391ms)","trace[1181604979] 'applied index is now lower than readState.Index' (duration: 112.07975ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:09:44.15572Z","caller":"traceutil/trace.go:171","msg":"trace[82285583] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"212.888464ms","start":"2026-04-28T06:09:43.942808Z","end":"2026-04-28T06:09:44.155696Z","steps":["trace[82285583] 'process raft request' (duration: 212.092213ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:44.155793Z","caller":"traceutil/trace.go:171","msg":"trace[1333909986] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"328.391723ms","start":"2026-04-28T06:09:43.827308Z","end":"2026-04-28T06:09:44.1557Z","steps":["trace[1333909986] 'process raft request' (duration: 216.022036ms)","trace[1333909986] 'compare' (duration: 110.97021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:44.155959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:43.827282Z","time spent":"328.594089ms","remote":"127.0.0.1:36888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:09:44.15599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.781022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T06:09:44.156056Z","caller":"traceutil/trace.go:171","msg":"trace[1566226308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1806; }","duration":"522.896405ms","start":"2026-04-28T06:09:43.633141Z","end":"2026-04-28T06:09:44.156038Z","steps":["trace[1566226308] 'agreement among raft nodes before linearized reading' (duration: 522.402502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:44.156202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:43.63312Z","time spent":"523.063449ms","remote":"127.0.0.1:36888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:09:45.261625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.839449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:09:45.261496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.32241ms","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-28T06:09:45.262074Z","caller":"traceutil/trace.go:171","msg":"trace[1853205390] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1808; }","duration":"492.354073ms","start":"2026-04-28T06:09:44.769691Z","end":"2026-04-28T06:09:45.262045Z","steps":["trace[1853205390] 'count revisions from in-memory index tree' (duration: 491.720926ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:09:45.262122Z","caller":"traceutil/trace.go:171","msg":"trace[1348020052] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1808; }","duration":"625.954417ms","start":"2026-04-28T06:09:44.636133Z","end":"2026-04-28T06:09:45.262087Z","steps":["trace[1348020052] 'count revisions from in-memory index tree' (duration: 625.206018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:45.261986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.959559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-28T06:09:45.262172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:44.63606Z","time spent":"626.097471ms","remote":"127.0.0.1:37080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:09:45.262173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:44.769668Z","time spent":"492.457796ms","remote":"127.0.0.1:36918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:09:45.262198Z","caller":"traceutil/trace.go:171","msg":"trace[2142610634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1808; }","duration":"562.187505ms","start":"2026-04-28T06:09:44.699979Z","end":"2026-04-28T06:09:45.262166Z","steps":["trace[2142610634] 'range keys from in-memory index tree' (duration: 561.772024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:45.262319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:44.699959Z","time spent":"562.340229ms","remote":"127.0.0.1:36888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T06:09:45.791103Z","caller":"traceutil/trace.go:171","msg":"trace[1703990113] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"330.415977ms","start":"2026-04-28T06:09:45.460659Z","end":"2026-04-28T06:09:45.791074Z","steps":["trace[1703990113] 'process raft request' (duration: 330.102138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:09:45.791249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:45.460634Z","time spent":"330.54176ms","remote":"127.0.0.1:36704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:09:46.013119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.24089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:09:46.013712Z","caller":"traceutil/trace.go:171","msg":"trace[19096152] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"429.177626ms","start":"2026-04-28T06:09:45.584511Z","end":"2026-04-28T06:09:46.013689Z","steps":["trace[19096152] 'process raft request' (duration: 275.27698ms)","trace[19096152] 'compare' (duration: 153.127296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:09:46.013798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:09:45.584486Z","time spent":"429.273699ms","remote":"127.0.0.1:36888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}