{"level":"warn","ts":"2026-07-20T19:01:35.959458Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-20T19:01:35.959574Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-07-20T19:01:35.959667Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-20T19:01:35.959683Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-20T19:01:35.95971Z","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-07-20T19:01:35.960582Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-07-20T19:01:35.960715Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-07-20T19:01:35.964923Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.888138ms"} {"level":"info","ts":"2026-07-20T19:01:35.970799Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-07-20T19:01:35.970901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-20T19:01:35.970947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-07-20T19:01:35.970967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-20T19:01:35.97098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-07-20T19:01:35.97103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-07-20T19:01:35.978819Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-20T19:01:35.980765Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-20T19:01:35.982148Z","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-07-20T19:01:35.984567Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-20T19:01:35.985299Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-20T19:01:35.985589Z","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-07-20T19:01:35.985735Z","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-07-20T19:01:35.985749Z","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-07-20T19:01:35.986976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-07-20T19:01:35.987414Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-07-20T19:01:35.991616Z","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-07-20T19:01:35.991764Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-20T19:01:35.99179Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-07-20T19:01:35.99186Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-20T19:01:35.991895Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-20T19:01:36.672233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-20T19:01:36.672314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-20T19:01:36.672361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-07-20T19:01:36.672386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-07-20T19:01:36.672401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-20T19:01:36.67242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-07-20T19:01:36.672438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-07-20T19:01:36.673496Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T19:01:36.674308Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-07-20T19:01:36.674317Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-20T19:01:36.674584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-20T19:01:36.67486Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-20T19:01:36.674897Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-20T19:01:36.675008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T19:01:36.675305Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T19:01:36.67543Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-20T19:01:36.678569Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-07-20T19:01:36.679719Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-20T19:01:39.998729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.118291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-20T19:01:39.999152Z","caller":"traceutil/trace.go:171","msg":"trace[1109992839] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"123.965338ms","start":"2026-07-20T19:01:39.875137Z","end":"2026-07-20T19:01:39.999102Z","steps":["trace[1109992839] 'process raft request' (duration: 11.765286ms)","trace[1109992839] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; req_size:838; } (duration: 110.836435ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:02:01.259586Z","caller":"traceutil/trace.go:171","msg":"trace[207732133] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"473.161826ms","start":"2026-07-20T19:02:00.786388Z","end":"2026-07-20T19:02:01.25955Z","steps":["trace[207732133] 'process raft request' (duration: 473.016512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:01.259969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:00.786368Z","time spent":"473.311278ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:02:01.627354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.942393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:02:01.627449Z","caller":"traceutil/trace.go:171","msg":"trace[763691971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"319.085496ms","start":"2026-07-20T19:02:01.308346Z","end":"2026-07-20T19:02:01.627432Z","steps":["trace[763691971] 'range keys from in-memory index tree' (duration: 318.859372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:01.627482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:01.308326Z","time spent":"319.148277ms","remote":"127.0.0.1:45822","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-07-20T19:02:10.342493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.138631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-20T19:02:10.342609Z","caller":"traceutil/trace.go:171","msg":"trace[516085079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"109.300434ms","start":"2026-07-20T19:02:10.233287Z","end":"2026-07-20T19:02:10.342587Z","steps":["trace[516085079] 'range keys from in-memory index tree' (duration: 108.981958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:10.342917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.973416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-20T19:02:10.343027Z","caller":"traceutil/trace.go:171","msg":"trace[1968342095] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:481; }","duration":"174.1342ms","start":"2026-07-20T19:02:10.168877Z","end":"2026-07-20T19:02:10.343011Z","steps":["trace[1968342095] 'range keys from in-memory index tree' (duration: 173.684821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:13.54214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.291074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:02:13.542245Z","caller":"traceutil/trace.go:171","msg":"trace[1185637894] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:524; }","duration":"135.466777ms","start":"2026-07-20T19:02:13.406752Z","end":"2026-07-20T19:02:13.542219Z","steps":["trace[1185637894] 'range keys from in-memory index tree' (duration: 135.215702ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.149081Z","caller":"traceutil/trace.go:171","msg":"trace[397194691] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"164.556627ms","start":"2026-07-20T19:02:13.984506Z","end":"2026-07-20T19:02:14.149063Z","steps":["trace[397194691] 'process raft request' (duration: 164.430845ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.549603Z","caller":"traceutil/trace.go:171","msg":"trace[1557121881] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"110.687392ms","start":"2026-07-20T19:02:14.438887Z","end":"2026-07-20T19:02:14.549575Z","steps":["trace[1557121881] 'process raft request' (duration: 110.231993ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.551125Z","caller":"traceutil/trace.go:171","msg":"trace[2026555546] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"113.754494ms","start":"2026-07-20T19:02:14.437354Z","end":"2026-07-20T19:02:14.551108Z","steps":["trace[2026555546] 'process raft request' (duration: 106.909307ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.551343Z","caller":"traceutil/trace.go:171","msg":"trace[353901986] transaction","detail":"{read_only:false; number_of_response:0; response_revision:541; }","duration":"113.654781ms","start":"2026-07-20T19:02:14.437678Z","end":"2026-07-20T19:02:14.551333Z","steps":["trace[353901986] 'process raft request' (duration: 111.402066ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.570518Z","caller":"traceutil/trace.go:171","msg":"trace[591843422] transaction","detail":"{read_only:false; number_of_response:0; response_revision:542; }","duration":"125.891895ms","start":"2026-07-20T19:02:14.444605Z","end":"2026-07-20T19:02:14.570497Z","steps":["trace[591843422] 'process raft request' (duration: 125.687711ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.570767Z","caller":"traceutil/trace.go:171","msg":"trace[1886131919] transaction","detail":"{read_only:false; number_of_response:0; response_revision:542; }","duration":"125.773453ms","start":"2026-07-20T19:02:14.444982Z","end":"2026-07-20T19:02:14.570755Z","steps":["trace[1886131919] 'process raft request' (duration: 125.438856ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.570721Z","caller":"traceutil/trace.go:171","msg":"trace[1968546101] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"121.986109ms","start":"2026-07-20T19:02:14.448705Z","end":"2026-07-20T19:02:14.570691Z","steps":["trace[1968546101] 'process raft request' (duration: 121.738863ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.570886Z","caller":"traceutil/trace.go:171","msg":"trace[1422444187] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"114.220813ms","start":"2026-07-20T19:02:14.456627Z","end":"2026-07-20T19:02:14.570848Z","steps":["trace[1422444187] 'process raft request' (duration: 113.915806ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.571894Z","caller":"traceutil/trace.go:171","msg":"trace[70525182] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"114.343244ms","start":"2026-07-20T19:02:14.457539Z","end":"2026-07-20T19:02:14.571882Z","steps":["trace[70525182] 'process raft request' (duration: 113.803104ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.571923Z","caller":"traceutil/trace.go:171","msg":"trace[739346338] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"114.916697ms","start":"2026-07-20T19:02:14.456992Z","end":"2026-07-20T19:02:14.571909Z","steps":["trace[739346338] 'process raft request' (duration: 113.629192ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.572032Z","caller":"traceutil/trace.go:171","msg":"trace[1276066793] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"114.191272ms","start":"2026-07-20T19:02:14.457813Z","end":"2026-07-20T19:02:14.572004Z","steps":["trace[1276066793] 'process raft request' (duration: 113.932026ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.572253Z","caller":"traceutil/trace.go:171","msg":"trace[1161618390] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"113.800355ms","start":"2026-07-20T19:02:14.458442Z","end":"2026-07-20T19:02:14.572242Z","steps":["trace[1161618390] 'process raft request' (duration: 113.394596ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.572848Z","caller":"traceutil/trace.go:171","msg":"trace[2100432205] transaction","detail":"{read_only:false; number_of_response:1; response_revision:548; }","duration":"114.248892ms","start":"2026-07-20T19:02:14.458585Z","end":"2026-07-20T19:02:14.572834Z","steps":["trace[2100432205] 'process raft request' (duration: 113.283003ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.573069Z","caller":"traceutil/trace.go:171","msg":"trace[245190408] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"112.055799ms","start":"2026-07-20T19:02:14.46099Z","end":"2026-07-20T19:02:14.573046Z","steps":["trace[245190408] 'process raft request' (duration: 111.623791ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.574725Z","caller":"traceutil/trace.go:171","msg":"trace[522127818] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"112.622921ms","start":"2026-07-20T19:02:14.462091Z","end":"2026-07-20T19:02:14.574714Z","steps":["trace[522127818] 'process raft request' (duration: 112.535029ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.754745Z","caller":"traceutil/trace.go:171","msg":"trace[869027520] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"151.14257ms","start":"2026-07-20T19:02:14.603582Z","end":"2026-07-20T19:02:14.754725Z","steps":["trace[869027520] 'process raft request' (duration: 151.006237ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.754801Z","caller":"traceutil/trace.go:171","msg":"trace[860523449] transaction","detail":"{read_only:false; number_of_response:0; response_revision:551; }","duration":"178.64336ms","start":"2026-07-20T19:02:14.576133Z","end":"2026-07-20T19:02:14.754777Z","steps":["trace[860523449] 'process raft request' (duration: 137.006908ms)","trace[860523449] 'compare' (duration: 41.266675ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:02:14.754802Z","caller":"traceutil/trace.go:171","msg":"trace[1437033042] transaction","detail":"{read_only:false; number_of_response:0; response_revision:551; }","duration":"177.987507ms","start":"2026-07-20T19:02:14.576788Z","end":"2026-07-20T19:02:14.754775Z","steps":["trace[1437033042] 'process raft request' (duration: 177.668611ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.755005Z","caller":"traceutil/trace.go:171","msg":"trace[1750247571] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"169.402155ms","start":"2026-07-20T19:02:14.585588Z","end":"2026-07-20T19:02:14.75499Z","steps":["trace[1750247571] 'process raft request' (duration: 168.897345ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.757825Z","caller":"traceutil/trace.go:171","msg":"trace[900299452] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"153.334493ms","start":"2026-07-20T19:02:14.604475Z","end":"2026-07-20T19:02:14.757809Z","steps":["trace[900299452] 'process raft request' (duration: 153.128509ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.757958Z","caller":"traceutil/trace.go:171","msg":"trace[1211101057] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"140.10898ms","start":"2026-07-20T19:02:14.617835Z","end":"2026-07-20T19:02:14.757944Z","steps":["trace[1211101057] 'process raft request' (duration: 139.859025ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:14.758239Z","caller":"traceutil/trace.go:171","msg":"trace[1895852866] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"102.304006ms","start":"2026-07-20T19:02:14.655925Z","end":"2026-07-20T19:02:14.758229Z","steps":["trace[1895852866] 'process raft request' (duration: 101.814695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:20.670682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679078513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:02:21.106525Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.023227008s","expected-duration":"1s"} {"level":"info","ts":"2026-07-20T19:02:21.10736Z","caller":"traceutil/trace.go:171","msg":"trace[1012296012] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"937.456494ms","start":"2026-07-20T19:02:20.169878Z","end":"2026-07-20T19:02:21.107334Z","steps":["trace[1012296012] 'read index received' (duration: 936.784851ms)","trace[1012296012] 'applied index is now lower than readState.Index' (duration: 670.733µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:02:21.107436Z","caller":"traceutil/trace.go:171","msg":"trace[126709285] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.025259588s","start":"2026-07-20T19:02:20.082143Z","end":"2026-07-20T19:02:21.107403Z","steps":["trace[126709285] 'process raft request' (duration: 1.02480891s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:21.107571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.671608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-20T19:02:21.107614Z","caller":"traceutil/trace.go:171","msg":"trace[69739261] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:619; }","duration":"937.760069ms","start":"2026-07-20T19:02:20.169842Z","end":"2026-07-20T19:02:21.107602Z","steps":["trace[69739261] 'agreement among raft nodes before linearized reading' (duration: 937.615066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:21.107647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:20.169825Z","time spent":"937.813801ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-07-20T19:02:21.107677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:20.08212Z","time spent":"1.025474662s","remote":"127.0.0.1:38292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-20T19:02:21.10774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.745381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T19:02:21.107756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.901979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-20T19:02:21.107802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.456267ms","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-07-20T19:02:21.107841Z","caller":"traceutil/trace.go:171","msg":"trace[925733811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"798.889265ms","start":"2026-07-20T19:02:20.308925Z","end":"2026-07-20T19:02:21.107814Z","steps":["trace[925733811] 'agreement among raft nodes before linearized reading' (duration: 798.743511ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:21.107853Z","caller":"traceutil/trace.go:171","msg":"trace[1562058500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"530.045472ms","start":"2026-07-20T19:02:20.577783Z","end":"2026-07-20T19:02:21.107828Z","steps":["trace[1562058500] 'agreement among raft nodes before linearized reading' (duration: 529.783866ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:21.107862Z","caller":"traceutil/trace.go:171","msg":"trace[536905807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"719.548159ms","start":"2026-07-20T19:02:20.388296Z","end":"2026-07-20T19:02:21.107844Z","steps":["trace[536905807] 'agreement among raft nodes before linearized reading' (duration: 719.361394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:21.107843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.243003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mfnxw\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-07-20T19:02:21.107883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:20.308903Z","time spent":"798.971986ms","remote":"127.0.0.1:45832","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-07-20T19:02:21.107897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:20.388278Z","time spent":"719.611731ms","remote":"127.0.0.1:38490","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":"warn","ts":"2026-07-20T19:02:21.107902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:20.577755Z","time spent":"530.136725ms","remote":"127.0.0.1:38490","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-07-20T19:02:21.107934Z","caller":"traceutil/trace.go:171","msg":"trace[1373158654] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mfnxw; range_end:; response_count:1; response_revision:619; }","duration":"123.374206ms","start":"2026-07-20T19:02:20.984536Z","end":"2026-07-20T19:02:21.10791Z","steps":["trace[1373158654] 'agreement among raft nodes before linearized reading' (duration: 123.079399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:21.108284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.107697ms","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-07-20T19:02:21.108347Z","caller":"traceutil/trace.go:171","msg":"trace[32007072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"917.23077ms","start":"2026-07-20T19:02:20.191103Z","end":"2026-07-20T19:02:21.108334Z","steps":["trace[32007072] 'agreement among raft nodes before linearized reading' (duration: 916.435774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:21.108383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:20.191095Z","time spent":"917.280751ms","remote":"127.0.0.1:38490","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-07-20T19:02:22.113118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.948038ms","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-07-20T19:02:22.113613Z","caller":"traceutil/trace.go:171","msg":"trace[25276847] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:619; }","duration":"963.461789ms","start":"2026-07-20T19:02:21.150134Z","end":"2026-07-20T19:02:22.113596Z","steps":["trace[25276847] 'range keys from in-memory index tree' (duration: 962.932028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:22.114486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.693015ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-20T19:02:22.114656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:21.109049Z","time spent":"1.005603112s","remote":"127.0.0.1:45848","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-20T19:02:22.114889Z","caller":"traceutil/trace.go:171","msg":"trace[1828148690] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.002286176s","start":"2026-07-20T19:02:21.112542Z","end":"2026-07-20T19:02:22.114829Z","steps":["trace[1828148690] 'process raft request' (duration: 1.002059411s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:22.115077Z","caller":"traceutil/trace.go:171","msg":"trace[667462010] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.000334406s","start":"2026-07-20T19:02:21.114732Z","end":"2026-07-20T19:02:22.115066Z","steps":["trace[667462010] 'process raft request' (duration: 1.000239674s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:22.115096Z","caller":"traceutil/trace.go:171","msg":"trace[1709486742] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.001774555s","start":"2026-07-20T19:02:21.113288Z","end":"2026-07-20T19:02:22.115063Z","steps":["trace[1709486742] 'process raft request' (duration: 1.001471539s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:22.115158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:21.114682Z","time spent":"1.000430688s","remote":"127.0.0.1:38490","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-07-20T19:02:22.115157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.716793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:02:22.115075Z","caller":"traceutil/trace.go:171","msg":"trace[1394194003] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:644; }","duration":"952.627842ms","start":"2026-07-20T19:02:21.162436Z","end":"2026-07-20T19:02:22.115064Z","steps":["trace[1394194003] 'read index received' (duration: 85.269043ms)","trace[1394194003] 'applied index is now lower than readState.Index' (duration: 867.358019ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:02:22.115164Z","caller":"traceutil/trace.go:171","msg":"trace[379843806] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"991.757854ms","start":"2026-07-20T19:02:21.123378Z","end":"2026-07-20T19:02:22.115135Z","steps":["trace[379843806] 'process raft request' (duration: 991.641802ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:22.115197Z","caller":"traceutil/trace.go:171","msg":"trace[1512141023] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:623; }","duration":"952.788926ms","start":"2026-07-20T19:02:21.162393Z","end":"2026-07-20T19:02:22.115182Z","steps":["trace[1512141023] 'agreement among raft nodes before linearized reading' (duration: 952.703795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:22.115237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:21.162376Z","time spent":"952.853157ms","remote":"127.0.0.1:38416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-20T19:02:22.115239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:21.113269Z","time spent":"1.001893428s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:02:22.115305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:21.123353Z","time spent":"991.874396ms","remote":"127.0.0.1:38398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:02:22.115366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.192007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-20T19:02:22.115343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.88803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:02:22.115385Z","caller":"traceutil/trace.go:171","msg":"trace[1155523687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"751.250828ms","start":"2026-07-20T19:02:21.364128Z","end":"2026-07-20T19:02:22.115379Z","steps":["trace[1155523687] 'agreement among raft nodes before linearized reading' (duration: 751.177527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:22.1154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:21.364103Z","time spent":"751.293528ms","remote":"127.0.0.1:38490","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":"info","ts":"2026-07-20T19:02:22.115403Z","caller":"traceutil/trace.go:171","msg":"trace[2106729888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"806.990292ms","start":"2026-07-20T19:02:21.308396Z","end":"2026-07-20T19:02:22.115386Z","steps":["trace[2106729888] 'agreement among raft nodes before linearized reading' (duration: 806.88347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:22.115441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:21.308377Z","time spent":"807.056493ms","remote":"127.0.0.1:45822","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-07-20T19:02:22.115534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:21.112528Z","time spent":"1.002952638s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:02:23.818154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509264915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:02:23.818238Z","caller":"traceutil/trace.go:171","msg":"trace[1501549958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"1.509394908s","start":"2026-07-20T19:02:22.308825Z","end":"2026-07-20T19:02:23.81822Z","steps":["trace[1501549958] 'range keys from in-memory index tree' (duration: 1.509197524s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:23.818281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:22.308809Z","time spent":"1.509462849s","remote":"127.0.0.1:45822","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-07-20T19:02:23.818639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.605348853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-20T19:02:23.818735Z","caller":"traceutil/trace.go:171","msg":"trace[835189309] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:625; }","duration":"1.605495965s","start":"2026-07-20T19:02:22.213204Z","end":"2026-07-20T19:02:23.8187Z","steps":["trace[835189309] 'range keys from in-memory index tree' (duration: 1.605045796s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:23.818788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:22.213192Z","time spent":"1.605582417s","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-07-20T19:02:23.827407Z","caller":"traceutil/trace.go:171","msg":"trace[1978276731] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.092697147s","start":"2026-07-20T19:02:22.734385Z","end":"2026-07-20T19:02:23.827082Z","steps":["trace[1978276731] 'process raft request' (duration: 1.092484232s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:23.827488Z","caller":"traceutil/trace.go:171","msg":"trace[2077701923] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.301489392s","start":"2026-07-20T19:02:22.525611Z","end":"2026-07-20T19:02:23.827101Z","steps":["trace[2077701923] 'process raft request' (duration: 1.294563084s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:23.827622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:22.525586Z","time spent":"1.30195379s","remote":"127.0.0.1:38292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:02:23.828337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:22.73436Z","time spent":"1.093941503s","remote":"127.0.0.1:38490","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-07-20T19:02:24.6562Z","caller":"traceutil/trace.go:171","msg":"trace[1982052774] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"314.38727ms","start":"2026-07-20T19:02:24.341786Z","end":"2026-07-20T19:02:24.656174Z","steps":["trace[1982052774] 'process raft request' (duration: 313.180716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:24.656359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:24.341755Z","time spent":"314.519443ms","remote":"127.0.0.1:38292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T19:02:26.561912Z","caller":"traceutil/trace.go:171","msg":"trace[423877958] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"204.01781ms","start":"2026-07-20T19:02:26.357869Z","end":"2026-07-20T19:02:26.561886Z","steps":["trace[423877958] 'read index received' (duration: 153.565729ms)","trace[423877958] 'applied index is now lower than readState.Index' (duration: 50.451101ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:02:26.562049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.165022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:02:26.562084Z","caller":"traceutil/trace.go:171","msg":"trace[1379661797] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:677; }","duration":"204.253794ms","start":"2026-07-20T19:02:26.357818Z","end":"2026-07-20T19:02:26.562072Z","steps":["trace[1379661797] 'agreement among raft nodes before linearized reading' (duration: 204.158103ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:26.562577Z","caller":"traceutil/trace.go:171","msg":"trace[222169459] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"308.226998ms","start":"2026-07-20T19:02:26.254321Z","end":"2026-07-20T19:02:26.562548Z","steps":["trace[222169459] 'process raft request' (duration: 257.95755ms)","trace[222169459] 'compare' (duration: 48.816399ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:02:26.562715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:26.254291Z","time spent":"308.352499ms","remote":"127.0.0.1:38292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T19:02:28.289412Z","caller":"traceutil/trace.go:171","msg":"trace[1894579020] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:733; }","duration":"110.009474ms","start":"2026-07-20T19:02:28.179381Z","end":"2026-07-20T19:02:28.289391Z","steps":["trace[1894579020] 'read index received' (duration: 109.980983ms)","trace[1894579020] 'applied index is now lower than readState.Index' (duration: 27.051µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:02:28.289537Z","caller":"traceutil/trace.go:171","msg":"trace[1605370268] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"111.104495ms","start":"2026-07-20T19:02:28.178422Z","end":"2026-07-20T19:02:28.289526Z","steps":["trace[1605370268] 'process raft request' (duration: 110.84836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:28.289728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.32689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-20T19:02:28.289815Z","caller":"traceutil/trace.go:171","msg":"trace[1159413094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:706; }","duration":"110.460603ms","start":"2026-07-20T19:02:28.179334Z","end":"2026-07-20T19:02:28.289795Z","steps":["trace[1159413094] 'agreement among raft nodes before linearized reading' (duration: 110.252839ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:28.295858Z","caller":"traceutil/trace.go:171","msg":"trace[103008600] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"116.092684ms","start":"2026-07-20T19:02:28.179746Z","end":"2026-07-20T19:02:28.295839Z","steps":["trace[103008600] 'process raft request' (duration: 115.978652ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:30.416745Z","caller":"traceutil/trace.go:171","msg":"trace[753076623] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"104.682508ms","start":"2026-07-20T19:02:30.312039Z","end":"2026-07-20T19:02:30.416721Z","steps":["trace[753076623] 'process raft request' (duration: 104.492995ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:30.417087Z","caller":"traceutil/trace.go:171","msg":"trace[1044836230] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"104.760278ms","start":"2026-07-20T19:02:30.312315Z","end":"2026-07-20T19:02:30.417076Z","steps":["trace[1044836230] 'process raft request' (duration: 104.368721ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:30.417342Z","caller":"traceutil/trace.go:171","msg":"trace[49906084] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"104.054316ms","start":"2026-07-20T19:02:30.313275Z","end":"2026-07-20T19:02:30.41733Z","steps":["trace[49906084] 'process raft request' (duration: 103.686788ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:39.440854Z","caller":"traceutil/trace.go:171","msg":"trace[1517693787] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"336.676291ms","start":"2026-07-20T19:02:39.104154Z","end":"2026-07-20T19:02:39.440831Z","steps":["trace[1517693787] 'process raft request' (duration: 336.504008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:39.441021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:39.104117Z","time spent":"336.823005ms","remote":"127.0.0.1:38462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T19:02:39.925138Z","caller":"traceutil/trace.go:171","msg":"trace[658042364] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"615.887574ms","start":"2026-07-20T19:02:39.309222Z","end":"2026-07-20T19:02:39.92511Z","steps":["trace[658042364] 'read index received' (duration: 131.555491ms)","trace[658042364] 'applied index is now lower than readState.Index' (duration: 484.331053ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:02:39.925267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.030167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:02:39.92527Z","caller":"traceutil/trace.go:171","msg":"trace[1911604657] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"689.104497ms","start":"2026-07-20T19:02:39.236146Z","end":"2026-07-20T19:02:39.925251Z","steps":["trace[1911604657] 'process raft request' (duration: 688.833012ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:02:39.92531Z","caller":"traceutil/trace.go:171","msg":"trace[837247649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"616.125589ms","start":"2026-07-20T19:02:39.309171Z","end":"2026-07-20T19:02:39.925296Z","steps":["trace[837247649] 'agreement among raft nodes before linearized reading' (duration: 616.046598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:02:39.925349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:39.309149Z","time spent":"616.18975ms","remote":"127.0.0.1:45822","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-07-20T19:02:39.925386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:02:39.23612Z","time spent":"689.202059ms","remote":"127.0.0.1:38490","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-07-20T19:03:14.809262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079338,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-20T19:03:14.94905Z","caller":"traceutil/trace.go:171","msg":"trace[691174202] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"839.960663ms","start":"2026-07-20T19:03:14.109059Z","end":"2026-07-20T19:03:14.94902Z","steps":["trace[691174202] 'process raft request' (duration: 839.82452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:14.949201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:14.109028Z","time spent":"840.093276ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:03:15.74201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.949873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T19:03:15.742238Z","caller":"traceutil/trace.go:171","msg":"trace[623801241] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.114124772s","start":"2026-07-20T19:03:14.628078Z","end":"2026-07-20T19:03:15.742202Z","steps":["trace[623801241] 'process raft request' (duration: 813.902904ms)","trace[623801241] 'compare' (duration: 299.859691ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:03:15.742378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:14.62806Z","time spent":"1.114240805s","remote":"127.0.0.1:38490","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-07-20T19:03:15.74251Z","caller":"traceutil/trace.go:171","msg":"trace[1542041579] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1059; }","duration":"1.433249129s","start":"2026-07-20T19:03:14.30923Z","end":"2026-07-20T19:03:15.742479Z","steps":["trace[1542041579] 'read index received' (duration: 639.76138ms)","trace[1542041579] 'applied index is now lower than readState.Index' (duration: 793.484469ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:03:15.743891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350449614s","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-07-20T19:03:15.743936Z","caller":"traceutil/trace.go:171","msg":"trace[529347283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1020; }","duration":"1.350528946s","start":"2026-07-20T19:03:14.393394Z","end":"2026-07-20T19:03:15.743923Z","steps":["trace[529347283] 'agreement among raft nodes before linearized reading' (duration: 1.350345382s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:15.743914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434671417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T19:03:15.743949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066020939s","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-07-20T19:03:15.744001Z","caller":"traceutil/trace.go:171","msg":"trace[1496414544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"1.434763778s","start":"2026-07-20T19:03:14.309213Z","end":"2026-07-20T19:03:15.743976Z","steps":["trace[1496414544] 'agreement among raft nodes before linearized reading' (duration: 1.433405781s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:03:15.744011Z","caller":"traceutil/trace.go:171","msg":"trace[184643966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"1.066140182s","start":"2026-07-20T19:03:14.677854Z","end":"2026-07-20T19:03:15.743994Z","steps":["trace[184643966] 'agreement among raft nodes before linearized reading' (duration: 1.065938647s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:15.743968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:14.393375Z","time spent":"1.350587077s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-20T19:03:15.744043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135514998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-20T19:03:15.744061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:14.309109Z","time spent":"1.434934342s","remote":"127.0.0.1:45822","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-07-20T19:03:15.744087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:14.67784Z","time spent":"1.066232433s","remote":"127.0.0.1:38490","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-07-20T19:03:15.744132Z","caller":"traceutil/trace.go:171","msg":"trace[1255094122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"1.13561355s","start":"2026-07-20T19:03:14.608473Z","end":"2026-07-20T19:03:15.744087Z","steps":["trace[1255094122] 'agreement among raft nodes before linearized reading' (duration: 1.135449567s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:15.744157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068249663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-20T19:03:15.744181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:14.608449Z","time spent":"1.135723512s","remote":"127.0.0.1:38490","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-07-20T19:03:15.744207Z","caller":"traceutil/trace.go:171","msg":"trace[557141491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"1.068339094s","start":"2026-07-20T19:03:14.675854Z","end":"2026-07-20T19:03:15.744193Z","steps":["trace[557141491] 'agreement among raft nodes before linearized reading' (duration: 1.068171611s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:15.744246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:14.675832Z","time spent":"1.068404525s","remote":"127.0.0.1:38490","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":"warn","ts":"2026-07-20T19:03:15.744377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071456256s","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-07-20T19:03:15.744414Z","caller":"traceutil/trace.go:171","msg":"trace[17228348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"1.071512008s","start":"2026-07-20T19:03:14.67289Z","end":"2026-07-20T19:03:15.744402Z","steps":["trace[17228348] 'agreement among raft nodes before linearized reading' (duration: 1.071417086s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:15.744442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:14.672852Z","time spent":"1.071583708s","remote":"127.0.0.1:38490","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-07-20T19:03:40.600527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.321926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-20T19:03:40.600603Z","caller":"traceutil/trace.go:171","msg":"trace[1481068525] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1103; }","duration":"426.455879ms","start":"2026-07-20T19:03:40.174131Z","end":"2026-07-20T19:03:40.600587Z","steps":["trace[1481068525] 'range keys from in-memory index tree' (duration: 426.084441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:40.600656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:40.174106Z","time spent":"426.53999ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-07-20T19:03:40.600782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.335057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:03:40.600859Z","caller":"traceutil/trace.go:171","msg":"trace[8695797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"291.45904ms","start":"2026-07-20T19:03:40.309382Z","end":"2026-07-20T19:03:40.600841Z","steps":["trace[8695797] 'range keys from in-memory index tree' (duration: 291.175765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:40.600845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.518309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-20T19:03:40.601428Z","caller":"traceutil/trace.go:171","msg":"trace[160361557] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1103; }","duration":"489.154832ms","start":"2026-07-20T19:03:40.112252Z","end":"2026-07-20T19:03:40.601407Z","steps":["trace[160361557] 'count revisions from in-memory index tree' (duration: 488.427998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:40.601514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:40.112235Z","time spent":"489.261723ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-07-20T19:03:41.808327Z","caller":"traceutil/trace.go:171","msg":"trace[1297295671] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"498.12405ms","start":"2026-07-20T19:03:41.310183Z","end":"2026-07-20T19:03:41.808307Z","steps":["trace[1297295671] 'read index received' (duration: 497.926747ms)","trace[1297295671] 'applied index is now lower than readState.Index' (duration: 196.633µs)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:03:41.808452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.237362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:03:41.808476Z","caller":"traceutil/trace.go:171","msg":"trace[2025580258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1106; }","duration":"498.348074ms","start":"2026-07-20T19:03:41.310122Z","end":"2026-07-20T19:03:41.80847Z","steps":["trace[2025580258] 'agreement among raft nodes before linearized reading' (duration: 498.275613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:41.808511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:41.310105Z","time spent":"498.400445ms","remote":"127.0.0.1:45822","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-07-20T19:03:41.808582Z","caller":"traceutil/trace.go:171","msg":"trace[670352137] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"779.727066ms","start":"2026-07-20T19:03:41.028824Z","end":"2026-07-20T19:03:41.808551Z","steps":["trace[670352137] 'process raft request' (duration: 779.348999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:41.808723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:41.028801Z","time spent":"779.848419ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T19:03:41.970187Z","caller":"traceutil/trace.go:171","msg":"trace[1229547678] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"105.82027ms","start":"2026-07-20T19:03:41.864323Z","end":"2026-07-20T19:03:41.970143Z","steps":["trace[1229547678] 'process raft request' (duration: 58.856618ms)","trace[1229547678] 'compare' (duration: 46.838559ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:03:42.188094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.585003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T19:03:42.188294Z","caller":"traceutil/trace.go:171","msg":"trace[773581763] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1155; }","duration":"150.418654ms","start":"2026-07-20T19:03:42.037859Z","end":"2026-07-20T19:03:42.188277Z","steps":["trace[773581763] 'read index received' (duration: 38.591196ms)","trace[773581763] 'applied index is now lower than readState.Index' (duration: 111.826408ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:03:42.188342Z","caller":"traceutil/trace.go:171","msg":"trace[353000445] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"159.410502ms","start":"2026-07-20T19:03:42.028901Z","end":"2026-07-20T19:03:42.188311Z","steps":["trace[353000445] 'process raft request' (duration: 159.300309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:42.188509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.630119ms","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-07-20T19:03:42.188569Z","caller":"traceutil/trace.go:171","msg":"trace[1383610050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1110; }","duration":"150.73237ms","start":"2026-07-20T19:03:42.037823Z","end":"2026-07-20T19:03:42.188555Z","steps":["trace[1383610050] 'agreement among raft nodes before linearized reading' (duration: 150.511706ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:03:42.188689Z","caller":"traceutil/trace.go:171","msg":"trace[1295209795] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"160.267869ms","start":"2026-07-20T19:03:42.028402Z","end":"2026-07-20T19:03:42.18867Z","steps":["trace[1295209795] 'process raft request' (duration: 48.026873ms)","trace[1295209795] 'compare' (duration: 111.134754ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:03:42.400671Z","caller":"traceutil/trace.go:171","msg":"trace[1963367445] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"208.815902ms","start":"2026-07-20T19:03:42.191825Z","end":"2026-07-20T19:03:42.400641Z","steps":["trace[1963367445] 'process raft request' (duration: 170.235526ms)","trace[1963367445] 'compare' (duration: 38.216169ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:03:42.40091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.710172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-20T19:03:42.400985Z","caller":"traceutil/trace.go:171","msg":"trace[1754321304] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1111; }","duration":"132.837334ms","start":"2026-07-20T19:03:42.26813Z","end":"2026-07-20T19:03:42.400967Z","steps":["trace[1754321304] 'agreement among raft nodes before linearized reading' (duration: 132.681731ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:03:42.401308Z","caller":"traceutil/trace.go:171","msg":"trace[916380461] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"132.383055ms","start":"2026-07-20T19:03:42.268183Z","end":"2026-07-20T19:03:42.400566Z","steps":["trace[916380461] 'read index received' (duration: 93.79192ms)","trace[916380461] 'applied index is now lower than readState.Index' (duration: 38.588935ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:03:42.401622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.724595ms","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-07-20T19:03:42.401668Z","caller":"traceutil/trace.go:171","msg":"trace[505159927] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1111; }","duration":"126.829366ms","start":"2026-07-20T19:03:42.274825Z","end":"2026-07-20T19:03:42.401654Z","steps":["trace[505159927] 'agreement among raft nodes before linearized reading' (duration: 126.725974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:59.498304Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.020667515s","expected-duration":"1s"} {"level":"info","ts":"2026-07-20T19:03:59.498578Z","caller":"traceutil/trace.go:171","msg":"trace[617454181] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"497.487067ms","start":"2026-07-20T19:03:59.001068Z","end":"2026-07-20T19:03:59.498555Z","steps":["trace[617454181] 'read index received' (duration: 497.357744ms)","trace[617454181] 'applied index is now lower than readState.Index' (duration: 128.453µs)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:03:59.49868Z","caller":"traceutil/trace.go:171","msg":"trace[2131830790] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"1.021101053s","start":"2026-07-20T19:03:58.477571Z","end":"2026-07-20T19:03:59.498672Z","steps":["trace[2131830790] 'process raft request' (duration: 1.020867379s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:59.498767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:58.477562Z","time spent":"1.021142655s","remote":"127.0.0.1:38490","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-07-20T19:03:59.498928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.269393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:03:59.499006Z","caller":"traceutil/trace.go:171","msg":"trace[927112222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"193.383246ms","start":"2026-07-20T19:03:59.305604Z","end":"2026-07-20T19:03:59.498988Z","steps":["trace[927112222] 'agreement among raft nodes before linearized reading' (duration: 193.267854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:59.499179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.890344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:03:59.499326Z","caller":"traceutil/trace.go:171","msg":"trace[669351990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"498.278972ms","start":"2026-07-20T19:03:59.001027Z","end":"2026-07-20T19:03:59.499306Z","steps":["trace[669351990] 'agreement among raft nodes before linearized reading' (duration: 497.848014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:03:59.499557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:03:59.001007Z","time spent":"498.53193ms","remote":"127.0.0.1:45822","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-07-20T19:04:00.072991Z","caller":"traceutil/trace.go:171","msg":"trace[1206749353] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"159.021193ms","start":"2026-07-20T19:03:59.913956Z","end":"2026-07-20T19:04:00.072977Z","steps":["trace[1206749353] 'process raft request' (duration: 154.27783ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:09.659209Z","caller":"traceutil/trace.go:171","msg":"trace[1622525278] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"115.573833ms","start":"2026-07-20T19:04:09.543615Z","end":"2026-07-20T19:04:09.659189Z","steps":["trace[1622525278] 'process raft request' (duration: 115.465071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:10.496205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.462873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T19:04:10.49629Z","caller":"traceutil/trace.go:171","msg":"trace[328937802] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"320.558709ms","start":"2026-07-20T19:04:10.175718Z","end":"2026-07-20T19:04:10.496277Z","steps":["trace[328937802] 'read index received' (duration: 94.777649ms)","trace[328937802] 'applied index is now lower than readState.Index' (duration: 225.78016ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:04:10.496335Z","caller":"traceutil/trace.go:171","msg":"trace[603711] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"325.733921ms","start":"2026-07-20T19:04:10.170578Z","end":"2026-07-20T19:04:10.496312Z","steps":["trace[603711] 'process raft request' (duration: 100.103155ms)","trace[603711] 'compare' (duration: 225.258118ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:10.496423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.719581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-20T19:04:10.496453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:10.17055Z","time spent":"325.838144ms","remote":"127.0.0.1:38490","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-07-20T19:04:10.496457Z","caller":"traceutil/trace.go:171","msg":"trace[268194885] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1249; }","duration":"320.803553ms","start":"2026-07-20T19:04:10.175643Z","end":"2026-07-20T19:04:10.496447Z","steps":["trace[268194885] 'agreement among raft nodes before linearized reading' (duration: 320.700011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:10.496567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.626722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T19:04:10.496571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:10.175616Z","time spent":"320.946166ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"info","ts":"2026-07-20T19:04:10.496601Z","caller":"traceutil/trace.go:171","msg":"trace[943112181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"187.687483ms","start":"2026-07-20T19:04:10.308903Z","end":"2026-07-20T19:04:10.49659Z","steps":["trace[943112181] 'agreement among raft nodes before linearized reading' (duration: 187.630932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:10.496628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.688457ms","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-07-20T19:04:10.496695Z","caller":"traceutil/trace.go:171","msg":"trace[1308862948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1249; }","duration":"100.761818ms","start":"2026-07-20T19:04:10.395917Z","end":"2026-07-20T19:04:10.496679Z","steps":["trace[1308862948] 'agreement among raft nodes before linearized reading' (duration: 100.601805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:10.496523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.643055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-20T19:04:10.496861Z","caller":"traceutil/trace.go:171","msg":"trace[1725548544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1249; }","duration":"295.027543ms","start":"2026-07-20T19:04:10.201826Z","end":"2026-07-20T19:04:10.496853Z","steps":["trace[1725548544] 'agreement among raft nodes before linearized reading' (duration: 294.660106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:12.497914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000206074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-20T19:04:12.497996Z","caller":"traceutil/trace.go:171","msg":"trace[1874323001] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000332346s","start":"2026-07-20T19:04:10.497649Z","end":"2026-07-20T19:04:12.497981Z","steps":["trace[1874323001] 'agreement among raft nodes before linearized reading' (duration: 2.000228564s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:12.498033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:10.497633Z","time spent":"2.000392627s","remote":"127.0.0.1:45832","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-07-20T19:04:14.101101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.951051764s","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-07-20T19:04:14.10122Z","caller":"traceutil/trace.go:171","msg":"trace[1092616083] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1249; }","duration":"2.951181506s","start":"2026-07-20T19:04:11.150021Z","end":"2026-07-20T19:04:14.101202Z","steps":["trace[1092616083] 'range keys from in-memory index tree' (duration: 2.951036623s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.102776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.486183688s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-20T19:04:14.102864Z","caller":"traceutil/trace.go:171","msg":"trace[880691273] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"3.605169628s","start":"2026-07-20T19:04:10.49768Z","end":"2026-07-20T19:04:14.10285Z","steps":["trace[880691273] 'read index received' (duration: 118.888378ms)","trace[880691273] 'applied index is now lower than readState.Index' (duration: 3.48628015s)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:14.10293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:10.497584Z","time spent":"3.605342242s","remote":"127.0.0.1:45848","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-20T19:04:14.191359Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.574768466s","expected-duration":"1s"} {"level":"info","ts":"2026-07-20T19:04:14.191884Z","caller":"traceutil/trace.go:171","msg":"trace[1810310739] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"3.692243366s","start":"2026-07-20T19:04:10.499607Z","end":"2026-07-20T19:04:14.19185Z","steps":["trace[1810310739] 'process raft request' (duration: 3.691965011s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.192066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:10.499587Z","time spent":"3.692387629s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T19:04:14.192215Z","caller":"traceutil/trace.go:171","msg":"trace[1892814342] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"3.691885589s","start":"2026-07-20T19:04:10.500286Z","end":"2026-07-20T19:04:14.192172Z","steps":["trace[1892814342] 'process raft request' (duration: 3.691506432s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.192459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:10.500278Z","time spent":"3.692044542s","remote":"127.0.0.1:38490","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-07-20T19:04:14.193176Z","caller":"traceutil/trace.go:171","msg":"trace[1724544802] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"3.692621273s","start":"2026-07-20T19:04:10.500534Z","end":"2026-07-20T19:04:14.193156Z","steps":["trace[1724544802] 'process raft request' (duration: 3.69243242s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.193321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:10.500526Z","time spent":"3.692704765s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T19:04:14.272497Z","caller":"traceutil/trace.go:171","msg":"trace[1161849874] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1304; }","duration":"169.580424ms","start":"2026-07-20T19:04:14.102892Z","end":"2026-07-20T19:04:14.272472Z","steps":["trace[1161849874] 'read index received' (duration: 88.696749ms)","trace[1161849874] 'applied index is now lower than readState.Index' (duration: 80.882405ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:14.27267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.22456643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-20T19:04:14.272713Z","caller":"traceutil/trace.go:171","msg":"trace[1473381090] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1256; }","duration":"3.224653111s","start":"2026-07-20T19:04:11.048047Z","end":"2026-07-20T19:04:14.272701Z","steps":["trace[1473381090] 'agreement among raft nodes before linearized reading' (duration: 3.224529209s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.272756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:11.048033Z","time spent":"3.224712652s","remote":"127.0.0.1:38296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-20T19:04:14.273273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.768876536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:14.27332Z","caller":"traceutil/trace.go:171","msg":"trace[925775685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"1.768949747s","start":"2026-07-20T19:04:12.504358Z","end":"2026-07-20T19:04:14.273308Z","steps":["trace[925775685] 'agreement among raft nodes before linearized reading' (duration: 1.768862125s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.273355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:12.504347Z","time spent":"1.769001498s","remote":"127.0.0.1:45822","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-07-20T19:04:14.273365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.170797373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:14.27347Z","caller":"traceutil/trace.go:171","msg":"trace[1645574469] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1256; }","duration":"3.170913135s","start":"2026-07-20T19:04:11.102516Z","end":"2026-07-20T19:04:14.273429Z","steps":["trace[1645574469] 'agreement among raft nodes before linearized reading' (duration: 3.170738382s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.273484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.61002504s","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-07-20T19:04:14.273519Z","caller":"traceutil/trace.go:171","msg":"trace[481880411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"2.610083191s","start":"2026-07-20T19:04:11.663426Z","end":"2026-07-20T19:04:14.273509Z","steps":["trace[481880411] 'agreement among raft nodes before linearized reading' (duration: 2.609999029s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.273524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:11.102478Z","time spent":"3.171034717s","remote":"127.0.0.1:44048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-20T19:04:14.27354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:11.66341Z","time spent":"2.610125452s","remote":"127.0.0.1:38490","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-07-20T19:04:14.273526Z","caller":"traceutil/trace.go:171","msg":"trace[84475577] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"1.771735823s","start":"2026-07-20T19:04:12.501677Z","end":"2026-07-20T19:04:14.273412Z","steps":["trace[84475577] 'process raft request' (duration: 1.770709093s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:14.273603Z","caller":"traceutil/trace.go:171","msg":"trace[1570936949] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"2.103612215s","start":"2026-07-20T19:04:12.169982Z","end":"2026-07-20T19:04:14.273594Z","steps":["trace[1570936949] 'process raft request' (duration: 2.102365231s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:14.273584Z","caller":"traceutil/trace.go:171","msg":"trace[1940281018] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"2.87929762s","start":"2026-07-20T19:04:11.394254Z","end":"2026-07-20T19:04:14.273552Z","steps":["trace[1940281018] 'process raft request' (duration: 2.877937393s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.273608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.698245105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T19:04:14.273678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:12.169958Z","time spent":"2.103663036s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T19:04:14.273683Z","caller":"traceutil/trace.go:171","msg":"trace[818493448] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"169.758697ms","start":"2026-07-20T19:04:14.103912Z","end":"2026-07-20T19:04:14.27367Z","steps":["trace[818493448] 'process raft request' (duration: 168.511573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.273394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.773408095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-20T19:04:14.273715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:11.394232Z","time spent":"2.879408973s","remote":"127.0.0.1:38490","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-07-20T19:04:14.273708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:12.501652Z","time spent":"1.771972717s","remote":"127.0.0.1:38272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-20T19:04:14.273714Z","caller":"traceutil/trace.go:171","msg":"trace[204393468] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1256; }","duration":"1.698430019s","start":"2026-07-20T19:04:12.575255Z","end":"2026-07-20T19:04:14.273685Z","steps":["trace[204393468] 'agreement among raft nodes before linearized reading' (duration: 1.698251105s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:14.273743Z","caller":"traceutil/trace.go:171","msg":"trace[481207887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"1.773795094s","start":"2026-07-20T19:04:12.499941Z","end":"2026-07-20T19:04:14.273736Z","steps":["trace[481207887] 'agreement among raft nodes before linearized reading' (duration: 1.773354485s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:14.273768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:12.499921Z","time spent":"1.773837674s","remote":"127.0.0.1:38490","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-07-20T19:04:14.273782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:12.575226Z","time spent":"1.698544242s","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-07-20T19:04:16.465829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.325168ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-20T19:04:16.46612Z","caller":"traceutil/trace.go:171","msg":"trace[1636849735] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"183.833956ms","start":"2026-07-20T19:04:16.28227Z","end":"2026-07-20T19:04:16.466104Z","steps":["trace[1636849735] 'process raft request' (duration: 183.664263ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:16.466242Z","caller":"traceutil/trace.go:171","msg":"trace[1196799339] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"269.360202ms","start":"2026-07-20T19:04:16.196832Z","end":"2026-07-20T19:04:16.466192Z","steps":["trace[1196799339] 'read index received' (duration: 158.709668ms)","trace[1196799339] 'applied index is now lower than readState.Index' (duration: 110.644034ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:16.466531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.7836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-20T19:04:16.466559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.657349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-20T19:04:16.466569Z","caller":"traceutil/trace.go:171","msg":"trace[1925443669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1259; }","duration":"156.84662ms","start":"2026-07-20T19:04:16.30971Z","end":"2026-07-20T19:04:16.466557Z","steps":["trace[1925443669] 'agreement among raft nodes before linearized reading' (duration: 156.727328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:16.466572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.258522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-20T19:04:16.466559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.69998ms","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-07-20T19:04:16.466618Z","caller":"traceutil/trace.go:171","msg":"trace[748980914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"150.302762ms","start":"2026-07-20T19:04:16.316302Z","end":"2026-07-20T19:04:16.466604Z","steps":["trace[748980914] 'agreement among raft nodes before linearized reading' (duration: 150.19484ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:16.466631Z","caller":"traceutil/trace.go:171","msg":"trace[1822456256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1259; }","duration":"269.73263ms","start":"2026-07-20T19:04:16.196862Z","end":"2026-07-20T19:04:16.466595Z","steps":["trace[1822456256] 'agreement among raft nodes before linearized reading' (duration: 269.546157ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:16.466646Z","caller":"traceutil/trace.go:171","msg":"trace[292068494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1259; }","duration":"269.841803ms","start":"2026-07-20T19:04:16.196785Z","end":"2026-07-20T19:04:16.466627Z","steps":["trace[292068494] 'agreement among raft nodes before linearized reading' (duration: 269.605968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:16.466708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.472793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:16.466735Z","caller":"traceutil/trace.go:171","msg":"trace[398548463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"158.531494ms","start":"2026-07-20T19:04:16.308194Z","end":"2026-07-20T19:04:16.466726Z","steps":["trace[398548463] 'agreement among raft nodes before linearized reading' (duration: 158.495993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:16.466825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.930404ms","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-07-20T19:04:16.466864Z","caller":"traceutil/trace.go:171","msg":"trace[1717606687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1259; }","duration":"269.975985ms","start":"2026-07-20T19:04:16.196877Z","end":"2026-07-20T19:04:16.466853Z","steps":["trace[1717606687] 'agreement among raft nodes before linearized reading' (duration: 269.868763ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:16.902845Z","caller":"traceutil/trace.go:171","msg":"trace[312160660] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"430.59353ms","start":"2026-07-20T19:04:16.472224Z","end":"2026-07-20T19:04:16.902817Z","steps":["trace[312160660] 'process raft request' (duration: 430.218873ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:16.902961Z","caller":"traceutil/trace.go:171","msg":"trace[144686876] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"430.638091ms","start":"2026-07-20T19:04:16.472315Z","end":"2026-07-20T19:04:16.902953Z","steps":["trace[144686876] 'process raft request' (duration: 430.453497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:16.903008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:16.472303Z","time spent":"430.677022ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:16.903011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:16.472186Z","time spent":"430.747133ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T19:04:16.903175Z","caller":"traceutil/trace.go:171","msg":"trace[222204784] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"430.541591ms","start":"2026-07-20T19:04:16.472617Z","end":"2026-07-20T19:04:16.903159Z","steps":["trace[222204784] 'process raft request' (duration: 430.304755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:16.903246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:16.472609Z","time spent":"430.597782ms","remote":"127.0.0.1:38490","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-07-20T19:04:16.903329Z","caller":"traceutil/trace.go:171","msg":"trace[1626885458] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"430.480589ms","start":"2026-07-20T19:04:16.472825Z","end":"2026-07-20T19:04:16.903305Z","steps":["trace[1626885458] 'process raft request' (duration: 430.297225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:16.903483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:16.472811Z","time spent":"430.587401ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:17.695064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.520125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T19:04:17.695165Z","caller":"traceutil/trace.go:171","msg":"trace[1013279922] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"920.138791ms","start":"2026-07-20T19:04:16.775007Z","end":"2026-07-20T19:04:17.695146Z","steps":["trace[1013279922] 'process raft request' (duration: 404.449443ms)","trace[1013279922] 'compare' (duration: 515.403433ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:17.695224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:16.77499Z","time spent":"920.205482ms","remote":"127.0.0.1:38490","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-07-20T19:04:19.067759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079950,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:19.568121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079950,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:20.069049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079950,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:20.570084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079950,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:21.070867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079950,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:21.571666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079950,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:21.686386Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.214699305s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-20T19:04:22.007338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.183666584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-20T19:04:22.007414Z","caller":"traceutil/trace.go:171","msg":"trace[1359148117] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1265; }","duration":"4.183793586s","start":"2026-07-20T19:04:17.823604Z","end":"2026-07-20T19:04:22.007397Z","steps":["trace[1359148117] 'count revisions from in-memory index tree' (duration: 4.183574652s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.007455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:17.823567Z","time spent":"4.183877617s","remote":"127.0.0.1:38414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-07-20T19:04:22.008038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.699538731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:22.00808Z","caller":"traceutil/trace.go:171","msg":"trace[1784276692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"3.699604773s","start":"2026-07-20T19:04:18.308465Z","end":"2026-07-20T19:04:22.00807Z","steps":["trace[1784276692] 'range keys from in-memory index tree' (duration: 3.699478179s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.008106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:18.30845Z","time spent":"3.699650054s","remote":"127.0.0.1:45822","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-07-20T19:04:22.007279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.255794ms","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-07-20T19:04:22.008721Z","caller":"traceutil/trace.go:171","msg":"trace[1693298541] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1265; }","duration":"858.695242ms","start":"2026-07-20T19:04:21.149996Z","end":"2026-07-20T19:04:22.008691Z","steps":["trace[1693298541] 'range keys from in-memory index tree' (duration: 857.240043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.009323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.743251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T19:04:22.009384Z","caller":"traceutil/trace.go:171","msg":"trace[2042397819] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1321; }","duration":"3.442080974s","start":"2026-07-20T19:04:18.567292Z","end":"2026-07-20T19:04:22.009373Z","steps":["trace[2042397819] 'read index received' (duration: 3.119245521s)","trace[2042397819] 'applied index is now lower than readState.Index' (duration: 322.834293ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:04:22.009485Z","caller":"traceutil/trace.go:171","msg":"trace[82689327] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"3.537825983s","start":"2026-07-20T19:04:18.471584Z","end":"2026-07-20T19:04:22.00941Z","steps":["trace[82689327] 'process raft request' (duration: 3.214915329s)","trace[82689327] 'compare' (duration: 321.570058ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:22.009604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.442318629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-20T19:04:22.009626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833614591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-20T19:04:22.009668Z","caller":"traceutil/trace.go:171","msg":"trace[494311432] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1266; }","duration":"1.833691672s","start":"2026-07-20T19:04:20.175964Z","end":"2026-07-20T19:04:22.009656Z","steps":["trace[494311432] 'agreement among raft nodes before linearized reading' (duration: 1.833562159s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.009661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:18.471556Z","time spent":"3.538005527s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:22.009701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:20.175938Z","time spent":"1.833756693s","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-07-20T19:04:22.009853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.164433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:22.009883Z","caller":"traceutil/trace.go:171","msg":"trace[92794458] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1266; }","duration":"483.221655ms","start":"2026-07-20T19:04:21.526651Z","end":"2026-07-20T19:04:22.009872Z","steps":["trace[92794458] 'agreement among raft nodes before linearized reading' (duration: 483.177034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.009909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:21.526637Z","time spent":"483.265185ms","remote":"127.0.0.1:38670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-20T19:04:22.010036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.570734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-20T19:04:22.010064Z","caller":"traceutil/trace.go:171","msg":"trace[624663642] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1266; }","duration":"717.633185ms","start":"2026-07-20T19:04:21.292421Z","end":"2026-07-20T19:04:22.010054Z","steps":["trace[624663642] 'agreement among raft nodes before linearized reading' (duration: 717.581144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.010091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:21.292401Z","time spent":"717.682636ms","remote":"127.0.0.1:38254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-20T19:04:22.010319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.103063359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-20T19:04:22.010397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054772882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:22.010429Z","caller":"traceutil/trace.go:171","msg":"trace[1478293875] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1266; }","duration":"1.054845342s","start":"2026-07-20T19:04:20.955572Z","end":"2026-07-20T19:04:22.010418Z","steps":["trace[1478293875] 'agreement among raft nodes before linearized reading' (duration: 1.054799031s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:22.010427Z","caller":"traceutil/trace.go:171","msg":"trace[691490969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1266; }","duration":"3.103377395s","start":"2026-07-20T19:04:18.906993Z","end":"2026-07-20T19:04:22.010371Z","steps":["trace[691490969] 'agreement among raft nodes before linearized reading' (duration: 3.102512759s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.010456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:20.955554Z","time spent":"1.054895064s","remote":"127.0.0.1:46268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-20T19:04:22.01051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.103014219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-20T19:04:22.01053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.701041821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:22.010573Z","caller":"traceutil/trace.go:171","msg":"trace[2033925679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1266; }","duration":"1.701125942s","start":"2026-07-20T19:04:20.309434Z","end":"2026-07-20T19:04:22.01056Z","steps":["trace[2033925679] 'agreement among raft nodes before linearized reading' (duration: 1.701057581s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:22.010571Z","caller":"traceutil/trace.go:171","msg":"trace[1054395293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1266; }","duration":"3.103094s","start":"2026-07-20T19:04:18.90746Z","end":"2026-07-20T19:04:22.010554Z","steps":["trace[1054395293] 'agreement among raft nodes before linearized reading' (duration: 3.102895206s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:22.009634Z","caller":"traceutil/trace.go:171","msg":"trace[1434670201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"3.442386951s","start":"2026-07-20T19:04:18.567239Z","end":"2026-07-20T19:04:22.009625Z","steps":["trace[1434670201] 'agreement among raft nodes before linearized reading' (duration: 3.442204777s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.010604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:20.309421Z","time spent":"1.701176774s","remote":"127.0.0.1:45832","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-07-20T19:04:22.010625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:18.907451Z","time spent":"3.103160741s","remote":"127.0.0.1:38490","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-07-20T19:04:22.010638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:18.567219Z","time spent":"3.44340451s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-20T19:04:22.010659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.558977587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-20T19:04:22.010733Z","caller":"traceutil/trace.go:171","msg":"trace[1788017044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1266; }","duration":"2.55911627s","start":"2026-07-20T19:04:19.4516Z","end":"2026-07-20T19:04:22.010716Z","steps":["trace[1788017044] 'agreement among raft nodes before linearized reading' (duration: 2.558973317s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.010766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:18.906974Z","time spent":"3.103489287s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-20T19:04:22.010776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:19.451576Z","time spent":"2.559188751s","remote":"127.0.0.1:44086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-20T19:04:22.010845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.801288134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:22.010885Z","caller":"traceutil/trace.go:171","msg":"trace[1910325252] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1266; }","duration":"2.801363746s","start":"2026-07-20T19:04:19.20951Z","end":"2026-07-20T19:04:22.010873Z","steps":["trace[1910325252] 'agreement among raft nodes before linearized reading' (duration: 2.801303534s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.010915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:19.20949Z","time spent":"2.801417237s","remote":"127.0.0.1:44072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-07-20T19:04:22.010914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.10360319s","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-07-20T19:04:22.010948Z","caller":"traceutil/trace.go:171","msg":"trace[1633106192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"3.103673052s","start":"2026-07-20T19:04:18.907265Z","end":"2026-07-20T19:04:22.010939Z","steps":["trace[1633106192] 'agreement among raft nodes before linearized reading' (duration: 3.10357781s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.010976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:18.907247Z","time spent":"3.103723263s","remote":"127.0.0.1:38490","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":"warn","ts":"2026-07-20T19:04:22.010987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.103522459s","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-07-20T19:04:22.011051Z","caller":"traceutil/trace.go:171","msg":"trace[356580368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1266; }","duration":"3.103624561s","start":"2026-07-20T19:04:18.907409Z","end":"2026-07-20T19:04:22.011034Z","steps":["trace[356580368] 'agreement among raft nodes before linearized reading' (duration: 3.103399326s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.011117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:18.907398Z","time spent":"3.103698522s","remote":"127.0.0.1:38490","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-07-20T19:04:22.344048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.939347ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-20T19:04:22.344137Z","caller":"traceutil/trace.go:171","msg":"trace[373551747] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"328.798542ms","start":"2026-07-20T19:04:22.015322Z","end":"2026-07-20T19:04:22.34412Z","steps":["trace[373551747] 'read index received' (duration: 136.784702ms)","trace[373551747] 'applied index is now lower than readState.Index' (duration: 192.01256ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:22.344151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:22.011984Z","time spent":"332.161209ms","remote":"127.0.0.1:45848","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-20T19:04:22.34433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.998376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c414d3db878293\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-07-20T19:04:22.344368Z","caller":"traceutil/trace.go:171","msg":"trace[1923589270] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18c414d3db878293; range_end:; response_count:1; response_revision:1266; }","duration":"329.041797ms","start":"2026-07-20T19:04:22.015316Z","end":"2026-07-20T19:04:22.344358Z","steps":["trace[1923589270] 'agreement among raft nodes before linearized reading' (duration: 328.884584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:22.344408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:22.015273Z","time spent":"329.127338ms","remote":"127.0.0.1:38272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c414d3db878293\" "} {"level":"warn","ts":"2026-07-20T19:04:22.844734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:23.344932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:23.845478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:24.020037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000953979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-20T19:04:24.020119Z","caller":"traceutil/trace.go:171","msg":"trace[76370097] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001078792s","start":"2026-07-20T19:04:22.019023Z","end":"2026-07-20T19:04:24.020102Z","steps":["trace[76370097] 'agreement among raft nodes before linearized reading' (duration: 2.000972939s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:24.020253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:22.019011Z","time spent":"2.001229044s","remote":"127.0.0.1:45822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/20 19:04:24 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-20T19:04:24.346602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:24.348266Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.196143831s","expected-duration":"1s"} {"level":"info","ts":"2026-07-20T19:04:24.3489Z","caller":"traceutil/trace.go:171","msg":"trace[1555505410] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"2.332709969s","start":"2026-07-20T19:04:22.016167Z","end":"2026-07-20T19:04:24.348877Z","steps":["trace[1555505410] 'process raft request' (duration: 2.332665398s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:24.349036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:22.016155Z","time spent":"2.332812031s","remote":"127.0.0.1:38490","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-07-20T19:04:24.349131Z","caller":"traceutil/trace.go:171","msg":"trace[1325120229] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"2.333586906s","start":"2026-07-20T19:04:22.015506Z","end":"2026-07-20T19:04:24.349093Z","steps":["trace[1325120229] 'process raft request' (duration: 2.333268339s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:24.349161Z","caller":"traceutil/trace.go:171","msg":"trace[2105215296] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"2.333620667s","start":"2026-07-20T19:04:22.015515Z","end":"2026-07-20T19:04:24.349136Z","steps":["trace[2105215296] 'process raft request' (duration: 2.33328853s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:24.349306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:22.015494Z","time spent":"2.33371947s","remote":"127.0.0.1:38490","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-07-20T19:04:24.349503Z","caller":"traceutil/trace.go:171","msg":"trace[1496196350] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"2.334067726s","start":"2026-07-20T19:04:22.015421Z","end":"2026-07-20T19:04:24.349489Z","steps":["trace[1496196350] 'process raft request' (duration: 2.333127197s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:24.349502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:22.015496Z","time spent":"2.333771861s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:24.34957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:22.015399Z","time spent":"2.334135407s","remote":"127.0.0.1:38490","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-07-20T19:04:24.349734Z","caller":"traceutil/trace.go:171","msg":"trace[314462076] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"2.334279741s","start":"2026-07-20T19:04:22.015442Z","end":"2026-07-20T19:04:24.349722Z","steps":["trace[314462076] 'process raft request' (duration: 2.3332545s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:24.349806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:22.01543Z","time spent":"2.334342452s","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T19:04:25.000087Z","caller":"traceutil/trace.go:171","msg":"trace[138509183] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1323; }","duration":"2.655890069s","start":"2026-07-20T19:04:22.344175Z","end":"2026-07-20T19:04:25.000065Z","steps":["trace[138509183] 'read index received' (duration: 2.004419287s)","trace[138509183] 'applied index is now lower than readState.Index' (duration: 651.469792ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:04:25.000111Z","caller":"traceutil/trace.go:171","msg":"trace[201957786] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"2.654808908s","start":"2026-07-20T19:04:22.345268Z","end":"2026-07-20T19:04:25.000077Z","steps":["trace[201957786] 'process raft request' (duration: 2.654570953s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:25.000188Z","caller":"traceutil/trace.go:171","msg":"trace[257143948] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"2.653033383s","start":"2026-07-20T19:04:22.347128Z","end":"2026-07-20T19:04:25.000161Z","steps":["trace[257143948] 'process raft request' (duration: 2.652853949s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:25.000206Z","caller":"traceutil/trace.go:171","msg":"trace[162600469] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"984.534249ms","start":"2026-07-20T19:04:24.015657Z","end":"2026-07-20T19:04:25.000191Z","steps":["trace[162600469] 'process raft request' (duration: 984.368525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:25.000253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:22.345245Z","time spent":"2.654932872s","remote":"127.0.0.1:45848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:25.000328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:22.347116Z","time spent":"2.653129535s","remote":"127.0.0.1:38272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:25.000354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:24.015631Z","time spent":"984.625321ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:25.501152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530394835679079966,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-20T19:04:26.020761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000611851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-20T19:04:26.020845Z","caller":"traceutil/trace.go:171","msg":"trace[1574644102] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000743005s","start":"2026-07-20T19:04:24.020084Z","end":"2026-07-20T19:04:26.020827Z","steps":["trace[1574644102] 'agreement among raft nodes before linearized reading' (duration: 2.000636902s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:26.020905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:24.020074Z","time spent":"2.000821277s","remote":"127.0.0.1:45832","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-07-20T19:04:26.152752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.923386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T19:04:26.152904Z","caller":"traceutil/trace.go:171","msg":"trace[206822333] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"1.152727494s","start":"2026-07-20T19:04:25.000156Z","end":"2026-07-20T19:04:26.152883Z","steps":["trace[206822333] 'read index received' (duration: 592.934621ms)","trace[206822333] 'applied index is now lower than readState.Index' (duration: 559.791013ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:04:26.152939Z","caller":"traceutil/trace.go:171","msg":"trace[259486904] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"1.490991243s","start":"2026-07-20T19:04:24.661902Z","end":"2026-07-20T19:04:26.152893Z","steps":["trace[259486904] 'process raft request' (duration: 931.283051ms)","trace[259486904] 'compare' (duration: 558.766593ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:26.153081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:24.66188Z","time spent":"1.491124597s","remote":"127.0.0.1:38490","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-07-20T19:04:26.389407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.764107ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-20T19:04:26.389535Z","caller":"traceutil/trace.go:171","msg":"trace[404727998] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"236.580703ms","start":"2026-07-20T19:04:26.152936Z","end":"2026-07-20T19:04:26.389517Z","steps":["trace[404727998] 'read index received' (duration: 123.622622ms)","trace[404727998] 'applied index is now lower than readState.Index' (duration: 112.956801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:26.389634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.093254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:26.389667Z","caller":"traceutil/trace.go:171","msg":"trace[1616736368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"536.165985ms","start":"2026-07-20T19:04:25.853492Z","end":"2026-07-20T19:04:26.389658Z","steps":["trace[1616736368] 'agreement among raft nodes before linearized reading' (duration: 536.104104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:26.389698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:25.853434Z","time spent":"536.256246ms","remote":"127.0.0.1:45822","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-07-20T19:04:26.389689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.851317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c414d3db878293\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-07-20T19:04:26.389744Z","caller":"traceutil/trace.go:171","msg":"trace[1871656024] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18c414d3db878293; range_end:; response_count:1; response_revision:1275; }","duration":"365.909808ms","start":"2026-07-20T19:04:26.02382Z","end":"2026-07-20T19:04:26.38973Z","steps":["trace[1871656024] 'agreement among raft nodes before linearized reading' (duration: 365.720305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:26.38971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.351487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-20T19:04:26.389777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:26.023785Z","time spent":"365.984269ms","remote":"127.0.0.1:38272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c414d3db878293\" "} {"level":"info","ts":"2026-07-20T19:04:26.389802Z","caller":"traceutil/trace.go:171","msg":"trace[626803423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"360.474789ms","start":"2026-07-20T19:04:26.029307Z","end":"2026-07-20T19:04:26.389782Z","steps":["trace[626803423] 'agreement among raft nodes before linearized reading' (duration: 360.304556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:26.389846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:26.029299Z","time spent":"360.539061ms","remote":"127.0.0.1:45832","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-07-20T19:04:26.389888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.387515712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-07-20T19:04:26.389926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158790365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:26.389971Z","caller":"traceutil/trace.go:171","msg":"trace[2024788926] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1275; }","duration":"1.158874176s","start":"2026-07-20T19:04:25.231083Z","end":"2026-07-20T19:04:26.389957Z","steps":["trace[2024788926] 'agreement among raft nodes before linearized reading' (duration: 1.158797915s)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:26.389964Z","caller":"traceutil/trace.go:171","msg":"trace[45703875] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1275; }","duration":"1.387644804s","start":"2026-07-20T19:04:25.002301Z","end":"2026-07-20T19:04:26.389946Z","steps":["trace[45703875] 'agreement among raft nodes before linearized reading' (duration: 1.387469371s)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:26.389999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:25.231059Z","time spent":"1.158931408s","remote":"127.0.0.1:56182","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\" count_only:true "} {"level":"warn","ts":"2026-07-20T19:04:26.390013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:25.002282Z","time spent":"1.387722405s","remote":"127.0.0.1:38370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-20T19:04:26.508244Z","caller":"traceutil/trace.go:171","msg":"trace[1215651679] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"113.717737ms","start":"2026-07-20T19:04:26.394504Z","end":"2026-07-20T19:04:26.508221Z","steps":["trace[1215651679] 'process raft request' (duration: 113.649645ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:26.508284Z","caller":"traceutil/trace.go:171","msg":"trace[1827816651] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"113.851869ms","start":"2026-07-20T19:04:26.3944Z","end":"2026-07-20T19:04:26.508251Z","steps":["trace[1827816651] 'process raft request' (duration: 113.575703ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:26.508659Z","caller":"traceutil/trace.go:171","msg":"trace[466696519] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"113.872589ms","start":"2026-07-20T19:04:26.394771Z","end":"2026-07-20T19:04:26.508643Z","steps":["trace[466696519] 'process raft request' (duration: 113.42049ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:26.509256Z","caller":"traceutil/trace.go:171","msg":"trace[926824224] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"114.361398ms","start":"2026-07-20T19:04:26.394872Z","end":"2026-07-20T19:04:26.509233Z","steps":["trace[926824224] 'process raft request' (duration: 113.91438ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:26.509277Z","caller":"traceutil/trace.go:171","msg":"trace[1342221781] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"114.357868ms","start":"2026-07-20T19:04:26.394896Z","end":"2026-07-20T19:04:26.509254Z","steps":["trace[1342221781] 'process raft request' (duration: 114.267596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:26.509403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.298857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7102"} {"level":"info","ts":"2026-07-20T19:04:26.509475Z","caller":"traceutil/trace.go:171","msg":"trace[12610727] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1281; }","duration":"114.358978ms","start":"2026-07-20T19:04:26.395083Z","end":"2026-07-20T19:04:26.509442Z","steps":["trace[12610727] 'agreement among raft nodes before linearized reading' (duration: 114.228826ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:27.11201Z","caller":"traceutil/trace.go:171","msg":"trace[1865917222] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"107.051584ms","start":"2026-07-20T19:04:27.00493Z","end":"2026-07-20T19:04:27.111982Z","steps":["trace[1865917222] 'process raft request' (duration: 106.884321ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:28.041998Z","caller":"traceutil/trace.go:171","msg":"trace[142166131] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"185.091531ms","start":"2026-07-20T19:04:27.856882Z","end":"2026-07-20T19:04:28.041974Z","steps":["trace[142166131] 'process raft request' (duration: 184.945558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:28.955089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.682599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T19:04:28.955317Z","caller":"traceutil/trace.go:171","msg":"trace[1741908396] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"435.290775ms","start":"2026-07-20T19:04:28.520012Z","end":"2026-07-20T19:04:28.955303Z","steps":["trace[1741908396] 'process raft request' (duration: 435.255114ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:28.955329Z","caller":"traceutil/trace.go:171","msg":"trace[1943190892] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"437.487327ms","start":"2026-07-20T19:04:28.517812Z","end":"2026-07-20T19:04:28.955299Z","steps":["trace[1943190892] 'process raft request' (duration: 102.534393ms)","trace[1943190892] 'compare' (duration: 334.505655ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:28.955383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:28.519998Z","time spent":"435.349956ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:28.955441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:28.517791Z","time spent":"437.586779ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-20T19:04:28.955549Z","caller":"traceutil/trace.go:171","msg":"trace[1226280477] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"437.238023ms","start":"2026-07-20T19:04:28.518289Z","end":"2026-07-20T19:04:28.955527Z","steps":["trace[1226280477] 'process raft request' (duration: 436.954407ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:28.955562Z","caller":"traceutil/trace.go:171","msg":"trace[2075226998] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"437.632151ms","start":"2026-07-20T19:04:28.517906Z","end":"2026-07-20T19:04:28.955538Z","steps":["trace[2075226998] 'process raft request' (duration: 437.259933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:28.955658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:28.518275Z","time spent":"437.326116ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:28.95568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:28.517854Z","time spent":"437.761714ms","remote":"127.0.0.1:38490","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-07-20T19:04:28.980707Z","caller":"traceutil/trace.go:171","msg":"trace[1162785228] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"347.802468ms","start":"2026-07-20T19:04:28.63289Z","end":"2026-07-20T19:04:28.980693Z","steps":["trace[1162785228] 'process raft request' (duration: 347.711806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:28.980786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:28.632866Z","time spent":"347.88483ms","remote":"127.0.0.1:38272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-20T19:04:29.400552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.120793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T19:04:29.400696Z","caller":"traceutil/trace.go:171","msg":"trace[1593052761] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"283.098535ms","start":"2026-07-20T19:04:29.117567Z","end":"2026-07-20T19:04:29.400665Z","steps":["trace[1593052761] 'process raft request' (duration: 109.785378ms)","trace[1593052761] 'compare' (duration: 173.002021ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:31.553871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.956167ms","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-07-20T19:04:31.553995Z","caller":"traceutil/trace.go:171","msg":"trace[2138873007] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1306; }","duration":"404.094999ms","start":"2026-07-20T19:04:31.149881Z","end":"2026-07-20T19:04:31.553976Z","steps":["trace[2138873007] 'range keys from in-memory index tree' (duration: 403.938186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:31.554035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.814254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-20T19:04:31.554125Z","caller":"traceutil/trace.go:171","msg":"trace[1842552911] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"148.938571ms","start":"2026-07-20T19:04:31.405163Z","end":"2026-07-20T19:04:31.554101Z","steps":["trace[1842552911] 'process raft request' (duration: 133.584738ms)","trace[1842552911] 'compare' (duration: 15.23138ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:04:31.554132Z","caller":"traceutil/trace.go:171","msg":"trace[1369334783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1306; }","duration":"244.961986ms","start":"2026-07-20T19:04:31.30915Z","end":"2026-07-20T19:04:31.554112Z","steps":["trace[1369334783] 'range keys from in-memory index tree' (duration: 244.67122ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:38.769719Z","caller":"traceutil/trace.go:171","msg":"trace[2016465271] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1420; }","duration":"194.072973ms","start":"2026-07-20T19:04:38.575618Z","end":"2026-07-20T19:04:38.769691Z","steps":["trace[2016465271] 'read index received' (duration: 193.92178ms)","trace[2016465271] 'applied index is now lower than readState.Index' (duration: 149.993µs)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:38.769935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.293577ms","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-07-20T19:04:38.770001Z","caller":"traceutil/trace.go:171","msg":"trace[1688824538] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"348.932611ms","start":"2026-07-20T19:04:38.421051Z","end":"2026-07-20T19:04:38.769984Z","steps":["trace[1688824538] 'process raft request' (duration: 348.460592ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:38.76998Z","caller":"traceutil/trace.go:171","msg":"trace[940897612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1361; }","duration":"194.398079ms","start":"2026-07-20T19:04:38.575568Z","end":"2026-07-20T19:04:38.769966Z","steps":["trace[940897612] 'agreement among raft nodes before linearized reading' (duration: 194.230326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:38.770133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:38.421025Z","time spent":"349.020062ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:39.312635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.840085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-20T19:04:39.313005Z","caller":"traceutil/trace.go:171","msg":"trace[1339851163] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1421; }","duration":"282.121602ms","start":"2026-07-20T19:04:39.030858Z","end":"2026-07-20T19:04:39.312979Z","steps":["trace[1339851163] 'read index received' (duration: 41.322µs)","trace[1339851163] 'applied index is now lower than readState.Index' (duration: 282.07824ms)"],"step_count":2} {"level":"info","ts":"2026-07-20T19:04:39.312975Z","caller":"traceutil/trace.go:171","msg":"trace[1927833162] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"538.930403ms","start":"2026-07-20T19:04:38.774007Z","end":"2026-07-20T19:04:39.312938Z","steps":["trace[1927833162] 'process raft request' (duration: 250.485327ms)","trace[1927833162] 'compare' (duration: 287.211462ms)"],"step_count":2} {"level":"warn","ts":"2026-07-20T19:04:39.313187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.311145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-20T19:04:39.313234Z","caller":"traceutil/trace.go:171","msg":"trace[1737519901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1362; }","duration":"282.416868ms","start":"2026-07-20T19:04:39.030807Z","end":"2026-07-20T19:04:39.313224Z","steps":["trace[1737519901] 'agreement among raft nodes before linearized reading' (duration: 282.250855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:39.313272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-20T19:04:38.773983Z","time spent":"539.199178ms","remote":"127.0.0.1:38490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-20T19:04:39.313531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.296148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-20T19:04:39.313576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.990183ms","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-07-20T19:04:39.313625Z","caller":"traceutil/trace.go:171","msg":"trace[1877843594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1362; }","duration":"264.060174ms","start":"2026-07-20T19:04:39.049549Z","end":"2026-07-20T19:04:39.313609Z","steps":["trace[1877843594] 'agreement among raft nodes before linearized reading' (duration: 263.885461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-20T19:04:39.313652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.084245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-20T19:04:39.313689Z","caller":"traceutil/trace.go:171","msg":"trace[1850927083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1362; }","duration":"264.156236ms","start":"2026-07-20T19:04:39.049522Z","end":"2026-07-20T19:04:39.313678Z","steps":["trace[1850927083] 'agreement among raft nodes before linearized reading' (duration: 264.052144ms)"],"step_count":1} {"level":"info","ts":"2026-07-20T19:04:39.313644Z","caller":"traceutil/trace.go:171","msg":"trace[490176455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1362; }","duration":"265.422181ms","start":"2026-07-20T19:04:39.048176Z","end":"2026-07-20T19:04:39.313598Z","steps":["trace[490176455] 'agreement among raft nodes before linearized reading' (duration: 265.131205ms)"],"step_count":1}