{"level":"warn","ts":"2026-03-31T20:39:27.67065Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T20:39:27.670738Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-03-31T20:39:27.670808Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T20:39:27.67082Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-03-31T20:39:27.670838Z","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-03-31T20:39:27.671487Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-03-31T20:39:27.671564Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-03-31T20:39:27.675031Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.273652ms"} {"level":"info","ts":"2026-03-31T20:39:27.681006Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-03-31T20:39:27.681075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T20:39:27.681098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-03-31T20:39:27.681109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T20:39:27.681118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-03-31T20:39:27.681155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-03-31T20:39:27.689934Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T20:39:27.691912Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T20:39:27.693104Z","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-03-31T20:39:27.699138Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T20:39:27.70001Z","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-03-31T20:39:27.700091Z","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-03-31T20:39:27.700103Z","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-03-31T20:39:27.70017Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T20:39:27.701962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-03-31T20:39:27.702568Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-03-31T20:39:27.703968Z","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-03-31T20:39:27.704289Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T20:39:27.704327Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T20:39:27.70443Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-03-31T20:39:27.704449Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-03-31T20:39:28.282054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T20:39:28.28213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T20:39:28.282181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-03-31T20:39:28.282193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-03-31T20:39:28.282198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-03-31T20:39:28.282211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-03-31T20:39:28.282223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-03-31T20:39:28.284129Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:39:28.284978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T20:39:28.28499Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T20:39:28.285192Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T20:39:28.285479Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:39:28.285526Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T20:39:28.285571Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T20:39:28.285659Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:39:28.285756Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:39:28.288853Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-03-31T20:39:28.288861Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-31T20:39:28.576961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.939715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-31T20:39:28.57696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.448696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T20:39:28.577053Z","caller":"traceutil/trace.go:171","msg":"trace[63313223] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"105.569209ms","start":"2026-03-31T20:39:28.471475Z","end":"2026-03-31T20:39:28.577044Z","steps":["trace[63313223] 'count revisions from in-memory index tree' (duration: 105.416255ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:28.577051Z","caller":"traceutil/trace.go:171","msg":"trace[1137536133] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"130.090678ms","start":"2026-03-31T20:39:28.446949Z","end":"2026-03-31T20:39:28.57704Z","steps":["trace[1137536133] 'range keys from in-memory index tree' (duration: 129.877183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:39:28.5771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.647801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T20:39:28.57713Z","caller":"traceutil/trace.go:171","msg":"trace[109821223] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"105.679942ms","start":"2026-03-31T20:39:28.471442Z","end":"2026-03-31T20:39:28.577122Z","steps":["trace[109821223] 'range keys from in-memory index tree' (duration: 105.565049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:39:28.577196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.024982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T20:39:28.577222Z","caller":"traceutil/trace.go:171","msg":"trace[1291948159] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"117.092643ms","start":"2026-03-31T20:39:28.460123Z","end":"2026-03-31T20:39:28.577215Z","steps":["trace[1291948159] 'range keys from in-memory index tree' (duration: 116.943099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:39:28.577445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.51921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-31T20:39:28.577456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.574436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-31T20:39:28.577446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.388664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-31T20:39:28.5775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.888372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T20:39:28.577591Z","caller":"traceutil/trace.go:171","msg":"trace[1196780854] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"141.053837ms","start":"2026-03-31T20:39:28.436522Z","end":"2026-03-31T20:39:28.577576Z","steps":["trace[1196780854] 'range keys from in-memory index tree' (duration: 140.699866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:39:28.577632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.547369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T20:39:28.577657Z","caller":"traceutil/trace.go:171","msg":"trace[613499949] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"158.58447ms","start":"2026-03-31T20:39:28.419066Z","end":"2026-03-31T20:39:28.577651Z","steps":["trace[613499949] 'count revisions from in-memory index tree' (duration: 158.519698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:39:28.57764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.956755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T20:39:28.577729Z","caller":"traceutil/trace.go:171","msg":"trace[1363550382] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"178.118359ms","start":"2026-03-31T20:39:28.399589Z","end":"2026-03-31T20:39:28.577708Z","steps":["trace[1363550382] 'count revisions from in-memory index tree' (duration: 177.839982ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:28.577548Z","caller":"traceutil/trace.go:171","msg":"trace[654878467] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"158.521498ms","start":"2026-03-31T20:39:28.41898Z","end":"2026-03-31T20:39:28.577502Z","steps":["trace[654878467] 'range keys from in-memory index tree' (duration: 158.22706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:39:28.577995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.278783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T20:39:28.578023Z","caller":"traceutil/trace.go:171","msg":"trace[965091392] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"141.343475ms","start":"2026-03-31T20:39:28.436672Z","end":"2026-03-31T20:39:28.578016Z","steps":["trace[965091392] 'count revisions from in-memory index tree' (duration: 141.241192ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:28.57748Z","caller":"traceutil/trace.go:171","msg":"trace[225234218] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"112.627698ms","start":"2026-03-31T20:39:28.464845Z","end":"2026-03-31T20:39:28.577473Z","steps":["trace[225234218] 'count revisions from in-memory index tree' (duration: 112.509104ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:28.577474Z","caller":"traceutil/trace.go:171","msg":"trace[1019022703] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"130.591592ms","start":"2026-03-31T20:39:28.446875Z","end":"2026-03-31T20:39:28.577466Z","steps":["trace[1019022703] 'count revisions from in-memory index tree' (duration: 130.479139ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:42.94127Z","caller":"traceutil/trace.go:171","msg":"trace[1728251054] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"109.716076ms","start":"2026-03-31T20:39:42.831531Z","end":"2026-03-31T20:39:42.941247Z","steps":["trace[1728251054] 'read index received' (duration: 109.49682ms)","trace[1728251054] 'applied index is now lower than readState.Index' (duration: 218.266µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:39:42.941464Z","caller":"traceutil/trace.go:171","msg":"trace[414725244] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"134.924855ms","start":"2026-03-31T20:39:42.80652Z","end":"2026-03-31T20:39:42.941445Z","steps":["trace[414725244] 'process raft request' (duration: 134.598486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:39:42.941524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.973403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:39:42.941584Z","caller":"traceutil/trace.go:171","msg":"trace[544922691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"110.079446ms","start":"2026-03-31T20:39:42.831489Z","end":"2026-03-31T20:39:42.941568Z","steps":["trace[544922691] 'agreement among raft nodes before linearized reading' (duration: 109.966343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:39:46.340469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.595114ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-31T20:39:52.892447Z","caller":"traceutil/trace.go:171","msg":"trace[1628580131] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"168.781977ms","start":"2026-03-31T20:39:52.723635Z","end":"2026-03-31T20:39:52.892417Z","steps":["trace[1628580131] 'process raft request' (duration: 168.608542ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:56.397112Z","caller":"traceutil/trace.go:171","msg":"trace[150929151] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"119.715677ms","start":"2026-03-31T20:39:56.277372Z","end":"2026-03-31T20:39:56.397087Z","steps":["trace[150929151] 'read index received' (duration: 119.586734ms)","trace[150929151] 'applied index is now lower than readState.Index' (duration: 127.993µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:39:56.397296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.878562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-03-31T20:39:56.397304Z","caller":"traceutil/trace.go:171","msg":"trace[1106673935] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"123.38628ms","start":"2026-03-31T20:39:56.273901Z","end":"2026-03-31T20:39:56.397287Z","steps":["trace[1106673935] 'process raft request' (duration: 123.03422ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:56.397384Z","caller":"traceutil/trace.go:171","msg":"trace[1551731492] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:470; }","duration":"120.016565ms","start":"2026-03-31T20:39:56.277329Z","end":"2026-03-31T20:39:56.397345Z","steps":["trace[1551731492] 'agreement among raft nodes before linearized reading' (duration: 119.862421ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:57.412636Z","caller":"traceutil/trace.go:171","msg":"trace[314077740] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"108.194833ms","start":"2026-03-31T20:39:57.304409Z","end":"2026-03-31T20:39:57.412604Z","steps":["trace[314077740] 'process raft request' (duration: 48.665459ms)","trace[314077740] 'compare' (duration: 59.335518ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:39:57.633836Z","caller":"traceutil/trace.go:171","msg":"trace[766862722] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"123.498804ms","start":"2026-03-31T20:39:57.510308Z","end":"2026-03-31T20:39:57.633807Z","steps":["trace[766862722] 'process raft request' (duration: 65.822432ms)","trace[766862722] 'compare' (duration: 57.496557ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:39:59.117653Z","caller":"traceutil/trace.go:171","msg":"trace[1466029142] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"186.456704ms","start":"2026-03-31T20:39:58.931177Z","end":"2026-03-31T20:39:59.117633Z","steps":["trace[1466029142] 'process raft request' (duration: 170.001371ms)","trace[1466029142] 'compare' (duration: 16.329229ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:39:59.118111Z","caller":"traceutil/trace.go:171","msg":"trace[1172690596] transaction","detail":"{read_only:false; number_of_response:1; response_revision:511; }","duration":"186.447184ms","start":"2026-03-31T20:39:58.931654Z","end":"2026-03-31T20:39:59.118101Z","steps":["trace[1172690596] 'process raft request' (duration: 185.95352ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.118202Z","caller":"traceutil/trace.go:171","msg":"trace[762401583] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"184.359575ms","start":"2026-03-31T20:39:58.933835Z","end":"2026-03-31T20:39:59.118195Z","steps":["trace[762401583] 'process raft request' (duration: 184.160869ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.118252Z","caller":"traceutil/trace.go:171","msg":"trace[983662328] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"177.131621ms","start":"2026-03-31T20:39:58.941086Z","end":"2026-03-31T20:39:59.118217Z","steps":["trace[983662328] 'process raft request' (duration: 177.09192ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.118426Z","caller":"traceutil/trace.go:171","msg":"trace[744934916] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"182.539754ms","start":"2026-03-31T20:39:58.935878Z","end":"2026-03-31T20:39:59.118418Z","steps":["trace[744934916] 'process raft request' (duration: 182.207585ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.118441Z","caller":"traceutil/trace.go:171","msg":"trace[1915977836] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"181.937937ms","start":"2026-03-31T20:39:58.936488Z","end":"2026-03-31T20:39:59.118426Z","steps":["trace[1915977836] 'process raft request' (duration: 181.650899ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.119437Z","caller":"traceutil/trace.go:171","msg":"trace[1183513947] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"127.465085ms","start":"2026-03-31T20:39:58.991959Z","end":"2026-03-31T20:39:59.119424Z","steps":["trace[1183513947] 'process raft request' (duration: 127.389833ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.119558Z","caller":"traceutil/trace.go:171","msg":"trace[345105296] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"175.65556ms","start":"2026-03-31T20:39:58.943897Z","end":"2026-03-31T20:39:59.119553Z","steps":["trace[345105296] 'process raft request' (duration: 175.138735ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.11962Z","caller":"traceutil/trace.go:171","msg":"trace[1623611592] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"170.398903ms","start":"2026-03-31T20:39:58.949216Z","end":"2026-03-31T20:39:59.119615Z","steps":["trace[1623611592] 'process raft request' (duration: 169.988531ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.119703Z","caller":"traceutil/trace.go:171","msg":"trace[1661353539] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"170.264299ms","start":"2026-03-31T20:39:58.949433Z","end":"2026-03-31T20:39:59.119697Z","steps":["trace[1661353539] 'process raft request' (duration: 169.824247ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.119774Z","caller":"traceutil/trace.go:171","msg":"trace[977593315] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"136.454108ms","start":"2026-03-31T20:39:58.983314Z","end":"2026-03-31T20:39:59.119768Z","steps":["trace[977593315] 'process raft request' (duration: 135.983275ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.119761Z","caller":"traceutil/trace.go:171","msg":"trace[431001591] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"174.94457ms","start":"2026-03-31T20:39:58.944798Z","end":"2026-03-31T20:39:59.119743Z","steps":["trace[431001591] 'process raft request' (duration: 174.295442ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.11992Z","caller":"traceutil/trace.go:171","msg":"trace[545886822] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"173.406357ms","start":"2026-03-31T20:39:58.946509Z","end":"2026-03-31T20:39:59.119915Z","steps":["trace[545886822] 'process raft request' (duration: 172.627585ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:59.120015Z","caller":"traceutil/trace.go:171","msg":"trace[1549850834] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"171.246616ms","start":"2026-03-31T20:39:58.948764Z","end":"2026-03-31T20:39:59.120011Z","steps":["trace[1549850834] 'process raft request' (duration: 170.412282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:00.302754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.472288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:40:00.303064Z","caller":"traceutil/trace.go:171","msg":"trace[1522960617] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.086170626s","start":"2026-03-31T20:39:59.216864Z","end":"2026-03-31T20:40:00.303035Z","steps":["trace[1522960617] 'process raft request' (duration: 330.786962ms)","trace[1522960617] 'compare' (duration: 754.239482ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:40:00.303196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:39:59.216844Z","time spent":"1.08630533s","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:00.953472Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.357846797s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-31T20:40:01.831609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000683685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-31T20:40:01.831748Z","caller":"traceutil/trace.go:171","msg":"trace[606913746] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000884692s","start":"2026-03-31T20:39:59.830836Z","end":"2026-03-31T20:40:01.831721Z","steps":["trace[606913746] 'agreement among raft nodes before linearized reading' (duration: 2.000715927s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:01.831914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:39:59.830813Z","time spent":"2.001046886s","remote":"127.0.0.1:54006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/31 20:40:01 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-31T20:40:01.910631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.722006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:40:01.91206Z","caller":"traceutil/trace.go:171","msg":"trace[1642675559] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"2.690576717s","start":"2026-03-31T20:39:59.221449Z","end":"2026-03-31T20:40:01.912025Z","steps":["trace[1642675559] 'process raft request' (duration: 1.732366489s)","trace[1642675559] 'compare' (duration: 955.905113ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:40:01.912194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:39:59.221429Z","time spent":"2.6906841s","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:01.912697Z","caller":"traceutil/trace.go:171","msg":"trace[884534397] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"2.689289161s","start":"2026-03-31T20:39:59.223389Z","end":"2026-03-31T20:40:01.912679Z","steps":["trace[884534397] 'process raft request' (duration: 2.688383146s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:01.912755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:39:59.223368Z","time spent":"2.689351962s","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:01.913367Z","caller":"traceutil/trace.go:171","msg":"trace[1319898413] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"2.683111737s","start":"2026-03-31T20:39:59.230241Z","end":"2026-03-31T20:40:01.913353Z","steps":["trace[1319898413] 'process raft request' (duration: 2.682549491s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:01.913359Z","caller":"traceutil/trace.go:171","msg":"trace[493116918] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"2.679579219s","start":"2026-03-31T20:39:59.233721Z","end":"2026-03-31T20:40:01.9133Z","steps":["trace[493116918] 'process raft request' (duration: 2.679390843s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:01.913525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:39:59.230218Z","time spent":"2.6831833s","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:01.913599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:39:59.233705Z","time spent":"2.679833355s","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:40:01.915561Z","caller":"traceutil/trace.go:171","msg":"trace[1923065746] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.465457253s","start":"2026-03-31T20:40:00.450089Z","end":"2026-03-31T20:40:01.915547Z","steps":["trace[1923065746] 'process raft request' (duration: 1.465410422s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:01.915643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:00.450058Z","time spent":"1.465542786s","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:01.915757Z","caller":"traceutil/trace.go:171","msg":"trace[275618236] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:552; }","duration":"2.652397914s","start":"2026-03-31T20:39:59.263321Z","end":"2026-03-31T20:40:01.915719Z","steps":["trace[275618236] 'read index received' (duration: 284.359287ms)","trace[275618236] 'applied index is now lower than readState.Index' (duration: 2.368037057s)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:40:01.915801Z","caller":"traceutil/trace.go:171","msg":"trace[365712493] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.600182443s","start":"2026-03-31T20:40:00.315608Z","end":"2026-03-31T20:40:01.915791Z","steps":["trace[365712493] 'process raft request' (duration: 1.599841383s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:01.915841Z","caller":"traceutil/trace.go:171","msg":"trace[1984576747] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"2.639152641s","start":"2026-03-31T20:39:59.276678Z","end":"2026-03-31T20:40:01.915831Z","steps":["trace[1984576747] 'process raft request' (duration: 2.63732988s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:01.915858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:00.315583Z","time spent":"1.600244374s","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:01.915896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:39:59.276651Z","time spent":"2.639208923s","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:01.916063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752968599s","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-03-31T20:40:01.916098Z","caller":"traceutil/trace.go:171","msg":"trace[903198851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"1.753034211s","start":"2026-03-31T20:40:00.163053Z","end":"2026-03-31T20:40:01.916088Z","steps":["trace[903198851] 'agreement among raft nodes before linearized reading' (duration: 1.752917448s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:01.916127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:00.163023Z","time spent":"1.753098293s","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T20:40:01.916184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.268054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T20:40:01.916226Z","caller":"traceutil/trace.go:171","msg":"trace[1083061190] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:541; }","duration":"727.364506ms","start":"2026-03-31T20:40:01.188851Z","end":"2026-03-31T20:40:01.916216Z","steps":["trace[1083061190] 'agreement among raft nodes before linearized reading' (duration: 727.298724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:01.916256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:01.188832Z","time spent":"727.416778ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-03-31T20:40:01.916392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.653068703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-03-31T20:40:01.916395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.328222ms","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-03-31T20:40:01.916418Z","caller":"traceutil/trace.go:171","msg":"trace[1900132077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"2.653123844s","start":"2026-03-31T20:39:59.263287Z","end":"2026-03-31T20:40:01.91641Z","steps":["trace[1900132077] 'agreement among raft nodes before linearized reading' (duration: 2.653075023s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:01.916476Z","caller":"traceutil/trace.go:171","msg":"trace[1393858887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"793.398523ms","start":"2026-03-31T20:40:01.123031Z","end":"2026-03-31T20:40:01.91643Z","steps":["trace[1393858887] 'agreement among raft nodes before linearized reading' (duration: 793.30352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:01.916507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:01.123008Z","time spent":"793.486976ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T20:40:01.916528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:39:59.26327Z","time spent":"2.653247457s","remote":"127.0.0.1:54314","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-03-31T20:40:01.916676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476720501s","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-03-31T20:40:01.916698Z","caller":"traceutil/trace.go:171","msg":"trace[200395204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"1.476743951s","start":"2026-03-31T20:40:00.439947Z","end":"2026-03-31T20:40:01.916691Z","steps":["trace[200395204] 'agreement among raft nodes before linearized reading' (duration: 1.476681049s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:01.916718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:00.439889Z","time spent":"1.476824633s","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T20:40:03.290277Z","caller":"traceutil/trace.go:171","msg":"trace[1116652022] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"394.450103ms","start":"2026-03-31T20:40:02.895799Z","end":"2026-03-31T20:40:03.290249Z","steps":["trace[1116652022] 'process raft request' (duration: 394.053252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:03.290462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:02.895778Z","time spent":"394.592437ms","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:03.291047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.346207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-03-31T20:40:03.291111Z","caller":"traceutil/trace.go:171","msg":"trace[1384703318] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:587; }","duration":"417.43929ms","start":"2026-03-31T20:40:02.873653Z","end":"2026-03-31T20:40:03.291092Z","steps":["trace[1384703318] 'range keys from in-memory index tree' (duration: 417.152442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:03.291158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:02.873639Z","time spent":"417.506251ms","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29594,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-03-31T20:40:03.302839Z","caller":"traceutil/trace.go:171","msg":"trace[1895222505] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"301.033846ms","start":"2026-03-31T20:40:03.001787Z","end":"2026-03-31T20:40:03.302821Z","steps":["trace[1895222505] 'process raft request' (duration: 300.451169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:03.307877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:03.001751Z","time spent":"301.141089ms","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:08.634766Z","caller":"traceutil/trace.go:171","msg":"trace[766394574] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"308.686271ms","start":"2026-03-31T20:40:08.326055Z","end":"2026-03-31T20:40:08.634742Z","steps":["trace[766394574] 'process raft request' (duration: 308.117935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.634916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.326032Z","time spent":"308.814124ms","remote":"127.0.0.1:54118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:08.634908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.668033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.635065Z","caller":"traceutil/trace.go:171","msg":"trace[603395858] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:623; }","duration":"307.885399ms","start":"2026-03-31T20:40:08.327163Z","end":"2026-03-31T20:40:08.635049Z","steps":["trace[603395858] 'agreement among raft nodes before linearized reading' (duration: 307.662293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.635146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.327151Z","time spent":"307.979831ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-03-31T20:40:08.634713Z","caller":"traceutil/trace.go:171","msg":"trace[994437260] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"307.499328ms","start":"2026-03-31T20:40:08.327189Z","end":"2026-03-31T20:40:08.634688Z","steps":["trace[994437260] 'read index received' (duration: 306.774918ms)","trace[994437260] 'applied index is now lower than readState.Index' (duration: 723.5µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:40:08.635781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.531727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:40:08.636176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.862065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.636222Z","caller":"traceutil/trace.go:171","msg":"trace[1006375760] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:623; }","duration":"159.920928ms","start":"2026-03-31T20:40:08.47629Z","end":"2026-03-31T20:40:08.636211Z","steps":["trace[1006375760] 'agreement among raft nodes before linearized reading' (duration: 159.688581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.636825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.048737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.63687Z","caller":"traceutil/trace.go:171","msg":"trace[1941945674] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:623; }","duration":"125.105728ms","start":"2026-03-31T20:40:08.511753Z","end":"2026-03-31T20:40:08.636859Z","steps":["trace[1941945674] 'agreement among raft nodes before linearized reading' (duration: 125.044056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.637011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.977499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.637083Z","caller":"traceutil/trace.go:171","msg":"trace[326707864] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"144.069622ms","start":"2026-03-31T20:40:08.492994Z","end":"2026-03-31T20:40:08.637064Z","steps":["trace[326707864] 'agreement among raft nodes before linearized reading' (duration: 143.962629ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:08.637292Z","caller":"traceutil/trace.go:171","msg":"trace[749014220] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:623; }","duration":"308.62531ms","start":"2026-03-31T20:40:08.327222Z","end":"2026-03-31T20:40:08.635847Z","steps":["trace[749014220] 'agreement among raft nodes before linearized reading' (duration: 308.525957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.637324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.183459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.637374Z","caller":"traceutil/trace.go:171","msg":"trace[249418196] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:623; }","duration":"126.24406ms","start":"2026-03-31T20:40:08.511113Z","end":"2026-03-31T20:40:08.637357Z","steps":["trace[249418196] 'agreement among raft nodes before linearized reading' (duration: 126.169988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.637386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.327216Z","time spent":"310.141342ms","remote":"127.0.0.1:44886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T20:40:08.63759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.677516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.637626Z","caller":"traceutil/trace.go:171","msg":"trace[1207136789] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:623; }","duration":"161.743478ms","start":"2026-03-31T20:40:08.475874Z","end":"2026-03-31T20:40:08.637618Z","steps":["trace[1207136789] 'agreement among raft nodes before linearized reading' (duration: 161.684687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.637642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.252687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.637693Z","caller":"traceutil/trace.go:171","msg":"trace[1873054831] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"144.311428ms","start":"2026-03-31T20:40:08.493369Z","end":"2026-03-31T20:40:08.63768Z","steps":["trace[1873054831] 'agreement among raft nodes before linearized reading' (duration: 144.244696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.637788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.937614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.637815Z","caller":"traceutil/trace.go:171","msg":"trace[906312398] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:623; }","duration":"161.998196ms","start":"2026-03-31T20:40:08.475808Z","end":"2026-03-31T20:40:08.637806Z","steps":["trace[906312398] 'agreement among raft nodes before linearized reading' (duration: 161.936924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.637879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.715549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:40:08.637945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.655907ms","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-03-31T20:40:08.63823Z","caller":"traceutil/trace.go:171","msg":"trace[739418462] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:623; }","duration":"187.985696ms","start":"2026-03-31T20:40:08.450238Z","end":"2026-03-31T20:40:08.638224Z","steps":["trace[739418462] 'agreement among raft nodes before linearized reading' (duration: 187.692848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.638326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.246924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.638344Z","caller":"traceutil/trace.go:171","msg":"trace[956341270] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:623; }","duration":"188.310846ms","start":"2026-03-31T20:40:08.450027Z","end":"2026-03-31T20:40:08.638338Z","steps":["trace[956341270] 'agreement among raft nodes before linearized reading' (duration: 188.281125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.638421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.453107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.638437Z","caller":"traceutil/trace.go:171","msg":"trace[1303632897] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:623; }","duration":"216.483898ms","start":"2026-03-31T20:40:08.421947Z","end":"2026-03-31T20:40:08.638431Z","steps":["trace[1303632897] 'agreement among raft nodes before linearized reading' (duration: 216.458878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.63851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.143556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.638522Z","caller":"traceutil/trace.go:171","msg":"trace[1629355347] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:623; }","duration":"217.172218ms","start":"2026-03-31T20:40:08.421346Z","end":"2026-03-31T20:40:08.638519Z","steps":["trace[1629355347] 'agreement among raft nodes before linearized reading' (duration: 217.151967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.63858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.315611ms","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-03-31T20:40:08.638592Z","caller":"traceutil/trace.go:171","msg":"trace[648265737] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:623; }","duration":"217.351493ms","start":"2026-03-31T20:40:08.421237Z","end":"2026-03-31T20:40:08.638588Z","steps":["trace[648265737] 'agreement among raft nodes before linearized reading' (duration: 217.331722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.638651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.443139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.638665Z","caller":"traceutil/trace.go:171","msg":"trace[1199234039] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:623; }","duration":"268.4774ms","start":"2026-03-31T20:40:08.370183Z","end":"2026-03-31T20:40:08.638661Z","steps":["trace[1199234039] 'agreement among raft nodes before linearized reading' (duration: 268.452539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.638725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.788999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.63874Z","caller":"traceutil/trace.go:171","msg":"trace[1313643170] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:623; }","duration":"268.865711ms","start":"2026-03-31T20:40:08.369869Z","end":"2026-03-31T20:40:08.638735Z","steps":["trace[1313643170] 'agreement among raft nodes before linearized reading' (duration: 268.84204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.638808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.035196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.63882Z","caller":"traceutil/trace.go:171","msg":"trace[358231417] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:623; }","duration":"269.073947ms","start":"2026-03-31T20:40:08.369742Z","end":"2026-03-31T20:40:08.638816Z","steps":["trace[358231417] 'agreement among raft nodes before linearized reading' (duration: 269.054457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.637586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.59663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.63889Z","caller":"traceutil/trace.go:171","msg":"trace[1383680728] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:623; }","duration":"127.938208ms","start":"2026-03-31T20:40:08.510945Z","end":"2026-03-31T20:40:08.638883Z","steps":["trace[1383680728] 'agreement among raft nodes before linearized reading' (duration: 126.6043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:08.637904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.38993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:08.638971Z","caller":"traceutil/trace.go:171","msg":"trace[1031479035] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:623; }","duration":"188.47664ms","start":"2026-03-31T20:40:08.450489Z","end":"2026-03-31T20:40:08.638966Z","steps":["trace[1031479035] 'agreement among raft nodes before linearized reading' (duration: 187.39947ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:08.637961Z","caller":"traceutil/trace.go:171","msg":"trace[978370071] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:623; }","duration":"144.784952ms","start":"2026-03-31T20:40:08.493138Z","end":"2026-03-31T20:40:08.637923Z","steps":["trace[978370071] 'agreement among raft nodes before linearized reading' (duration: 144.70638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:09.196354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725296413230650,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T20:40:09.325332Z","caller":"traceutil/trace.go:171","msg":"trace[1725362565] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"629.815123ms","start":"2026-03-31T20:40:08.695499Z","end":"2026-03-31T20:40:09.325314Z","steps":["trace[1725362565] 'read index received' (duration: 629.644258ms)","trace[1725362565] 'applied index is now lower than readState.Index' (duration: 170.145µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:40:09.325627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.027918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:09.3256Z","caller":"traceutil/trace.go:171","msg":"trace[1263773147] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"643.076116ms","start":"2026-03-31T20:40:08.682483Z","end":"2026-03-31T20:40:09.325559Z","steps":["trace[1263773147] 'process raft request' (duration: 642.706055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:09.325663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.122621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:09.325663Z","caller":"traceutil/trace.go:171","msg":"trace[668634425] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"630.120021ms","start":"2026-03-31T20:40:08.695534Z","end":"2026-03-31T20:40:09.325654Z","steps":["trace[668634425] 'agreement among raft nodes before linearized reading' (duration: 630.010348ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:09.325689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.001493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:40:09.325726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.695518Z","time spent":"630.199583ms","remote":"127.0.0.1:44994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-03-31T20:40:09.325727Z","caller":"traceutil/trace.go:171","msg":"trace[1747120358] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"630.232834ms","start":"2026-03-31T20:40:08.695473Z","end":"2026-03-31T20:40:09.325706Z","steps":["trace[1747120358] 'agreement among raft nodes before linearized reading' (duration: 629.985397ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:09.325753Z","caller":"traceutil/trace.go:171","msg":"trace[1588096121] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"590.069975ms","start":"2026-03-31T20:40:08.735663Z","end":"2026-03-31T20:40:09.325733Z","steps":["trace[1588096121] 'agreement among raft nodes before linearized reading' (duration: 589.966902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:09.325777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.695461Z","time spent":"630.300476ms","remote":"127.0.0.1:44994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T20:40:09.325774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.682467Z","time spent":"643.23899ms","remote":"127.0.0.1:54568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:40:09.325794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.735654Z","time spent":"590.129297ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T20:40:09.325835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.623052ms","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:5"} {"level":"warn","ts":"2026-03-31T20:40:09.325892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.189699ms","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:5"} {"level":"warn","ts":"2026-03-31T20:40:09.325938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.154567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:40:09.325964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.034459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:09.325989Z","caller":"traceutil/trace.go:171","msg":"trace[1747158513] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"590.335112ms","start":"2026-03-31T20:40:08.735632Z","end":"2026-03-31T20:40:09.325967Z","steps":["trace[1747158513] 'agreement among raft nodes before linearized reading' (duration: 590.132987ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:09.325976Z","caller":"traceutil/trace.go:171","msg":"trace[1238867359] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"605.802027ms","start":"2026-03-31T20:40:08.720151Z","end":"2026-03-31T20:40:09.325953Z","steps":["trace[1238867359] 'agreement among raft nodes before linearized reading' (duration: 605.506659ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:09.326025Z","caller":"traceutil/trace.go:171","msg":"trace[316757521] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"606.285591ms","start":"2026-03-31T20:40:08.719715Z","end":"2026-03-31T20:40:09.326Z","steps":["trace[316757521] 'agreement among raft nodes before linearized reading' (duration: 606.110396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:09.32604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.735617Z","time spent":"590.412405ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-31T20:40:09.326047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.008653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:40:09.326061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.720147Z","time spent":"605.898199ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-31T20:40:09.326077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.719701Z","time spent":"606.363263ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T20:40:09.326097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.661184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:09.326872Z","caller":"traceutil/trace.go:171","msg":"trace[1895991543] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"630.09759ms","start":"2026-03-31T20:40:08.695906Z","end":"2026-03-31T20:40:09.326004Z","steps":["trace[1895991543] 'agreement among raft nodes before linearized reading' (duration: 630.037129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:09.326976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.695899Z","time spent":"631.052258ms","remote":"127.0.0.1:44994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-03-31T20:40:09.327086Z","caller":"traceutil/trace.go:171","msg":"trace[611561395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"494.084795ms","start":"2026-03-31T20:40:08.831987Z","end":"2026-03-31T20:40:09.326072Z","steps":["trace[611561395] 'agreement among raft nodes before linearized reading' (duration: 494.033094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:09.327128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.831964Z","time spent":"495.153756ms","remote":"127.0.0.1:53990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:40:09.327171Z","caller":"traceutil/trace.go:171","msg":"trace[494061505] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"589.703624ms","start":"2026-03-31T20:40:08.736412Z","end":"2026-03-31T20:40:09.326115Z","steps":["trace[494061505] 'agreement among raft nodes before linearized reading' (duration: 589.663183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:09.327241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:08.736405Z","time spent":"590.814145ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-03-31T20:40:10.331402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725296413230654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:40:10.832178Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725296413230654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:40:10.871146Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.053436017s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T20:40:10.872516Z","caller":"traceutil/trace.go:171","msg":"trace[1697501333] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"1.041139611s","start":"2026-03-31T20:40:09.831351Z","end":"2026-03-31T20:40:10.87249Z","steps":["trace[1697501333] 'read index received' (duration: 1.039937717s)","trace[1697501333] 'applied index is now lower than readState.Index' (duration: 1.200964ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:40:10.873559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0421877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:10.873626Z","caller":"traceutil/trace.go:171","msg":"trace[1880007723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"1.042293193s","start":"2026-03-31T20:40:09.831319Z","end":"2026-03-31T20:40:10.873612Z","steps":["trace[1880007723] 'agreement among raft nodes before linearized reading' (duration: 1.04218563s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:10.873691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:09.831296Z","time spent":"1.042382295s","remote":"127.0.0.1:54006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:40:10.873728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.968825ms","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-03-31T20:40:10.873739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.93672ms","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-03-31T20:40:10.873795Z","caller":"traceutil/trace.go:171","msg":"trace[983149474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"903.058717ms","start":"2026-03-31T20:40:09.970721Z","end":"2026-03-31T20:40:10.87378Z","steps":["trace[983149474] 'agreement among raft nodes before linearized reading' (duration: 902.856371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:10.873827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:09.970715Z","time spent":"903.106559ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T20:40:10.873825Z","caller":"traceutil/trace.go:171","msg":"trace[1481051156] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"905.075054ms","start":"2026-03-31T20:40:09.968731Z","end":"2026-03-31T20:40:10.873806Z","steps":["trace[1481051156] 'agreement among raft nodes before linearized reading' (duration: 904.831677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:10.873875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:09.968702Z","time spent":"905.159856ms","remote":"127.0.0.1:54314","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-03-31T20:40:10.874021Z","caller":"traceutil/trace.go:171","msg":"trace[483344824] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.124121764s","start":"2026-03-31T20:40:09.749882Z","end":"2026-03-31T20:40:10.874003Z","steps":["trace[483344824] 'process raft request' (duration: 1.121770988s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:10.874091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.476246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:40:10.874117Z","caller":"traceutil/trace.go:171","msg":"trace[1526769553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"857.524317ms","start":"2026-03-31T20:40:10.016586Z","end":"2026-03-31T20:40:10.87411Z","steps":["trace[1526769553] 'agreement among raft nodes before linearized reading' (duration: 857.459435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:10.87412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.967183ms","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-03-31T20:40:10.874135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:10.016572Z","time spent":"857.559188ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T20:40:10.874152Z","caller":"traceutil/trace.go:171","msg":"trace[1919163626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"904.009944ms","start":"2026-03-31T20:40:09.970133Z","end":"2026-03-31T20:40:10.874143Z","steps":["trace[1919163626] 'agreement among raft nodes before linearized reading' (duration: 903.87638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:10.874181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:09.970126Z","time spent":"904.048035ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T20:40:10.874218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:09.749851Z","time spent":"1.124213197s","remote":"127.0.0.1:54118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:11.648311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.545935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:40:11.64859Z","caller":"traceutil/trace.go:171","msg":"trace[1762795973] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:646; }","duration":"760.059455ms","start":"2026-03-31T20:40:10.888515Z","end":"2026-03-31T20:40:11.648574Z","steps":["trace[1762795973] 'read index received' (duration: 45.135799ms)","trace[1762795973] 'applied index is now lower than readState.Index' (duration: 714.922646ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:40:11.648819Z","caller":"traceutil/trace.go:171","msg":"trace[1963509404] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"769.47123ms","start":"2026-03-31T20:40:10.879335Z","end":"2026-03-31T20:40:11.648807Z","steps":["trace[1963509404] 'process raft request' (duration: 54.305077ms)","trace[1963509404] 'compare' (duration: 714.428022ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:40:11.648895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:10.879311Z","time spent":"769.547582ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:11.649056Z","caller":"traceutil/trace.go:171","msg":"trace[26097353] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"769.587144ms","start":"2026-03-31T20:40:10.87946Z","end":"2026-03-31T20:40:11.649047Z","steps":["trace[26097353] 'process raft request' (duration: 768.969266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:11.649136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:10.879448Z","time spent":"769.660515ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:11.649261Z","caller":"traceutil/trace.go:171","msg":"trace[2000064072] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"769.751918ms","start":"2026-03-31T20:40:10.879501Z","end":"2026-03-31T20:40:11.649253Z","steps":["trace[2000064072] 'process raft request' (duration: 768.979717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:11.649313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:10.879481Z","time spent":"769.807939ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:11.64961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.794481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T20:40:11.649651Z","caller":"traceutil/trace.go:171","msg":"trace[204731450] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:630; }","duration":"459.867173ms","start":"2026-03-31T20:40:11.189771Z","end":"2026-03-31T20:40:11.649639Z","steps":["trace[204731450] 'agreement among raft nodes before linearized reading' (duration: 459.74222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:11.649687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:11.189749Z","time spent":"459.931324ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"info","ts":"2026-03-31T20:40:11.649726Z","caller":"traceutil/trace.go:171","msg":"trace[1284161882] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"770.113738ms","start":"2026-03-31T20:40:10.879586Z","end":"2026-03-31T20:40:11.649699Z","steps":["trace[1284161882] 'process raft request' (duration: 768.924765ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:11.649758Z","caller":"traceutil/trace.go:171","msg":"trace[967893295] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"722.89926ms","start":"2026-03-31T20:40:10.926833Z","end":"2026-03-31T20:40:11.649733Z","steps":["trace[967893295] 'process raft request' (duration: 721.708366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:11.649821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.30362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:11.649854Z","caller":"traceutil/trace.go:171","msg":"trace[619234054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"761.358492ms","start":"2026-03-31T20:40:10.888488Z","end":"2026-03-31T20:40:11.649846Z","steps":["trace[619234054] 'agreement among raft nodes before linearized reading' (duration: 761.312641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:11.649879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:10.888474Z","time spent":"761.399573ms","remote":"127.0.0.1:54006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:40:11.649878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:10.87958Z","time spent":"770.208431ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:11.649883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:10.926813Z","time spent":"723.019504ms","remote":"127.0.0.1:54292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:40:13.758309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725296413230734,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T20:40:13.767723Z","caller":"traceutil/trace.go:171","msg":"trace[1814764541] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"514.334104ms","start":"2026-03-31T20:40:13.253367Z","end":"2026-03-31T20:40:13.767701Z","steps":["trace[1814764541] 'process raft request' (duration: 514.207561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:13.767859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:13.253333Z","time spent":"514.458368ms","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:40:13.769753Z","caller":"traceutil/trace.go:171","msg":"trace[287146018] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:688; }","duration":"511.494285ms","start":"2026-03-31T20:40:13.258228Z","end":"2026-03-31T20:40:13.769723Z","steps":["trace[287146018] 'read index received' (duration: 509.335384ms)","trace[287146018] 'applied index is now lower than readState.Index' (duration: 2.157491ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:40:13.769931Z","caller":"traceutil/trace.go:171","msg":"trace[267647147] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"262.021979ms","start":"2026-03-31T20:40:13.507895Z","end":"2026-03-31T20:40:13.769917Z","steps":["trace[267647147] 'process raft request' (duration: 261.660258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:13.770337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.083142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-03-31T20:40:13.770391Z","caller":"traceutil/trace.go:171","msg":"trace[1042924349] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:667; }","duration":"512.168433ms","start":"2026-03-31T20:40:13.258203Z","end":"2026-03-31T20:40:13.770371Z","steps":["trace[1042924349] 'agreement among raft nodes before linearized reading' (duration: 511.913106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:13.770361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.044473ms","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":"warn","ts":"2026-03-31T20:40:13.770432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:13.258191Z","time spent":"512.230286ms","remote":"127.0.0.1:54118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329980,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-03-31T20:40:13.770454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.692254ms","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-03-31T20:40:13.770462Z","caller":"traceutil/trace.go:171","msg":"trace[1610856041] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"319.189977ms","start":"2026-03-31T20:40:13.451243Z","end":"2026-03-31T20:40:13.770433Z","steps":["trace[1610856041] 'agreement among raft nodes before linearized reading' (duration: 319.036003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:13.770491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.389797ms","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-03-31T20:40:13.770504Z","caller":"traceutil/trace.go:171","msg":"trace[1305619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"115.770936ms","start":"2026-03-31T20:40:13.65472Z","end":"2026-03-31T20:40:13.770491Z","steps":["trace[1305619] 'agreement among raft nodes before linearized reading' (duration: 115.601331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:13.770519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:13.451224Z","time spent":"319.281429ms","remote":"127.0.0.1:54048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-03-31T20:40:13.770545Z","caller":"traceutil/trace.go:171","msg":"trace[787351700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"114.4754ms","start":"2026-03-31T20:40:13.656051Z","end":"2026-03-31T20:40:13.770526Z","steps":["trace[787351700] 'agreement among raft nodes before linearized reading' (duration: 114.320125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:13.770664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.729746ms","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-03-31T20:40:13.770691Z","caller":"traceutil/trace.go:171","msg":"trace[809227889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"114.771688ms","start":"2026-03-31T20:40:13.655911Z","end":"2026-03-31T20:40:13.770683Z","steps":["trace[809227889] 'agreement among raft nodes before linearized reading' (duration: 114.690316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:13.770712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.173956ms","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-03-31T20:40:13.770742Z","caller":"traceutil/trace.go:171","msg":"trace[1549011343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"117.221227ms","start":"2026-03-31T20:40:13.65351Z","end":"2026-03-31T20:40:13.770732Z","steps":["trace[1549011343] 'agreement among raft nodes before linearized reading' (duration: 117.142984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:13.770776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.150121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:13.770819Z","caller":"traceutil/trace.go:171","msg":"trace[1950426610] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:667; }","duration":"119.230644ms","start":"2026-03-31T20:40:13.651573Z","end":"2026-03-31T20:40:13.770803Z","steps":["trace[1950426610] 'agreement among raft nodes before linearized reading' (duration: 119.166072ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:16.572272Z","caller":"traceutil/trace.go:171","msg":"trace[1574909845] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"140.15186ms","start":"2026-03-31T20:40:16.432099Z","end":"2026-03-31T20:40:16.572251Z","steps":["trace[1574909845] 'read index received' (duration: 139.883703ms)","trace[1574909845] 'applied index is now lower than readState.Index' (duration: 267.157µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:40:16.572373Z","caller":"traceutil/trace.go:171","msg":"trace[416690771] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"191.887826ms","start":"2026-03-31T20:40:16.380478Z","end":"2026-03-31T20:40:16.572366Z","steps":["trace[416690771] 'process raft request' (duration: 191.605918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:16.572615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.50917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:678"} {"level":"info","ts":"2026-03-31T20:40:16.572644Z","caller":"traceutil/trace.go:171","msg":"trace[171043234] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:707; }","duration":"140.580412ms","start":"2026-03-31T20:40:16.432057Z","end":"2026-03-31T20:40:16.572637Z","steps":["trace[171043234] 'agreement among raft nodes before linearized reading' (duration: 140.51328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:16.572749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.598871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-03-31T20:40:16.572827Z","caller":"traceutil/trace.go:171","msg":"trace[158222860] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:707; }","duration":"136.718704ms","start":"2026-03-31T20:40:16.436091Z","end":"2026-03-31T20:40:16.57281Z","steps":["trace[158222860] 'agreement among raft nodes before linearized reading' (duration: 136.58587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:16.572824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.373623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-03-31T20:40:16.572882Z","caller":"traceutil/trace.go:171","msg":"trace[385872910] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:707; }","duration":"121.469885ms","start":"2026-03-31T20:40:16.451399Z","end":"2026-03-31T20:40:16.572869Z","steps":["trace[385872910] 'agreement among raft nodes before linearized reading' (duration: 121.28736ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:24.199456Z","caller":"traceutil/trace.go:171","msg":"trace[931309383] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"356.442644ms","start":"2026-03-31T20:40:23.842992Z","end":"2026-03-31T20:40:24.199434Z","steps":["trace[931309383] 'process raft request' (duration: 356.29727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:24.199603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:23.842982Z","time spent":"356.549858ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:24.200207Z","caller":"traceutil/trace.go:171","msg":"trace[1318612900] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:797; }","duration":"347.049641ms","start":"2026-03-31T20:40:23.853122Z","end":"2026-03-31T20:40:24.200172Z","steps":["trace[1318612900] 'read index received' (duration: 346.170896ms)","trace[1318612900] 'applied index is now lower than readState.Index' (duration: 876.145µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:40:24.200547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.396931ms","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-03-31T20:40:24.20061Z","caller":"traceutil/trace.go:171","msg":"trace[199355731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:771; }","duration":"347.499564ms","start":"2026-03-31T20:40:23.853093Z","end":"2026-03-31T20:40:24.200593Z","steps":["trace[199355731] 'agreement among raft nodes before linearized reading' (duration: 347.254227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:24.200654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:23.85308Z","time spent":"347.563485ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T20:40:24.306121Z","caller":"traceutil/trace.go:171","msg":"trace[787214308] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"338.843839ms","start":"2026-03-31T20:40:23.967248Z","end":"2026-03-31T20:40:24.306092Z","steps":["trace[787214308] 'process raft request' (duration: 337.975725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:24.306279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:23.967219Z","time spent":"338.982083ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:24.306285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.4844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:635"} {"level":"info","ts":"2026-03-31T20:40:24.306401Z","caller":"traceutil/trace.go:171","msg":"trace[2103359164] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:773; }","duration":"423.623043ms","start":"2026-03-31T20:40:23.88274Z","end":"2026-03-31T20:40:24.306363Z","steps":["trace[2103359164] 'agreement among raft nodes before linearized reading' (duration: 423.460048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:24.306423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.747716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"warn","ts":"2026-03-31T20:40:24.306448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:23.882723Z","time spent":"423.714906ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":659,"request content":"key:\"/registry/clusterroles/system:node-bootstrapper\" "} {"level":"info","ts":"2026-03-31T20:40:24.30651Z","caller":"traceutil/trace.go:171","msg":"trace[1933529014] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:773; }","duration":"408.891499ms","start":"2026-03-31T20:40:23.897593Z","end":"2026-03-31T20:40:24.306484Z","steps":["trace[1933529014] 'agreement among raft nodes before linearized reading' (duration: 408.716354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:24.306555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:23.897579Z","time spent":"408.966842ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":679,"request content":"key:\"/registry/clusterroles/system:node-problem-detector\" "} {"level":"warn","ts":"2026-03-31T20:40:24.306613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.284588ms","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-03-31T20:40:24.306256Z","caller":"traceutil/trace.go:171","msg":"trace[1715113801] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"105.879437ms","start":"2026-03-31T20:40:24.200312Z","end":"2026-03-31T20:40:24.306191Z","steps":["trace[1715113801] 'read index received' (duration: 104.891969ms)","trace[1715113801] 'applied index is now lower than readState.Index' (duration: 981.998µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:40:24.306659Z","caller":"traceutil/trace.go:171","msg":"trace[1585059333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"453.34509ms","start":"2026-03-31T20:40:23.853301Z","end":"2026-03-31T20:40:24.306646Z","steps":["trace[1585059333] 'agreement among raft nodes before linearized reading' (duration: 453.183785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:24.30664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.404193ms","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-03-31T20:40:24.306794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:23.853292Z","time spent":"453.440852ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T20:40:24.306821Z","caller":"traceutil/trace.go:171","msg":"trace[1056505655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:773; }","duration":"452.671291ms","start":"2026-03-31T20:40:23.854125Z","end":"2026-03-31T20:40:24.306796Z","steps":["trace[1056505655] 'agreement among raft nodes before linearized reading' (duration: 452.141116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:24.306937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:23.854105Z","time spent":"452.818245ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:40:26.857101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.593408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:40:26.857217Z","caller":"traceutil/trace.go:171","msg":"trace[275756339] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"537.502196ms","start":"2026-03-31T20:40:26.319699Z","end":"2026-03-31T20:40:26.857201Z","steps":["trace[275756339] 'read index received' (duration: 153.778164ms)","trace[275756339] 'applied index is now lower than readState.Index' (duration: 383.722672ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:40:26.857271Z","caller":"traceutil/trace.go:171","msg":"trace[1734773498] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"543.841945ms","start":"2026-03-31T20:40:26.313394Z","end":"2026-03-31T20:40:26.857236Z","steps":["trace[1734773498] 'process raft request' (duration: 159.98322ms)","trace[1734773498] 'compare' (duration: 383.439843ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:40:26.857385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.688112ms","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-03-31T20:40:26.857461Z","caller":"traceutil/trace.go:171","msg":"trace[1249130010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"537.799425ms","start":"2026-03-31T20:40:26.31965Z","end":"2026-03-31T20:40:26.85745Z","steps":["trace[1249130010] 'agreement among raft nodes before linearized reading' (duration: 537.62596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:26.857482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:26.313382Z","time spent":"543.963318ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:26.857505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:26.31963Z","time spent":"537.863506ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T20:40:27.3573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725296413231337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:40:27.857994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471725296413231337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:40:28.084383Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.326663949s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T20:40:28.091457Z","caller":"traceutil/trace.go:171","msg":"trace[2054234603] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"1.229100195s","start":"2026-03-31T20:40:26.862332Z","end":"2026-03-31T20:40:28.091432Z","steps":["trace[2054234603] 'process raft request' (duration: 1.22889906s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:28.091583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:26.862312Z","time spent":"1.229207528s","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:28.091677Z","caller":"traceutil/trace.go:171","msg":"trace[224289287] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:813; }","duration":"1.234401175s","start":"2026-03-31T20:40:26.857247Z","end":"2026-03-31T20:40:28.091648Z","steps":["trace[224289287] 'read index received' (duration: 1.227332646s)","trace[224289287] 'applied index is now lower than readState.Index' (duration: 7.067318ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:40:28.091966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721684648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:765"} {"level":"warn","ts":"2026-03-31T20:40:28.091994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.831798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-31T20:40:28.09207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.772346393s","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-03-31T20:40:28.092Z","caller":"traceutil/trace.go:171","msg":"trace[2129208830] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"908.011535ms","start":"2026-03-31T20:40:27.183967Z","end":"2026-03-31T20:40:28.091979Z","steps":["trace[2129208830] 'process raft request' (duration: 907.369737ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:28.092106Z","caller":"traceutil/trace.go:171","msg":"trace[547143923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"1.772423735s","start":"2026-03-31T20:40:26.319673Z","end":"2026-03-31T20:40:28.092097Z","steps":["trace[547143923] 'agreement among raft nodes before linearized reading' (duration: 1.772274571s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:28.092097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709488825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:volume-scheduler\" ","response":"range_response_count:1 size:715"} {"level":"warn","ts":"2026-03-31T20:40:28.092129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:26.319657Z","time spent":"1.772466286s","remote":"127.0.0.1:54314","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-03-31T20:40:28.092166Z","caller":"traceutil/trace.go:171","msg":"trace[264506268] range","detail":"{range_begin:/registry/clusterrolebindings/system:volume-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"1.709584088s","start":"2026-03-31T20:40:26.382563Z","end":"2026-03-31T20:40:28.092147Z","steps":["trace[264506268] 'agreement among raft nodes before linearized reading' (duration: 1.709466014s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:28.092223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:26.382546Z","time spent":"1.70966407s","remote":"127.0.0.1:54410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":739,"request content":"key:\"/registry/clusterrolebindings/system:volume-scheduler\" "} {"level":"warn","ts":"2026-03-31T20:40:28.092211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:27.183947Z","time spent":"908.17741ms","remote":"127.0.0.1:54292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:28.092108Z","caller":"traceutil/trace.go:171","msg":"trace[478875556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"908.967192ms","start":"2026-03-31T20:40:27.183126Z","end":"2026-03-31T20:40:28.092093Z","steps":["trace[478875556] 'agreement among raft nodes before linearized reading' (duration: 908.782287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:28.092287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:27.183108Z","time spent":"909.172617ms","remote":"127.0.0.1:54314","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":"info","ts":"2026-03-31T20:40:28.092029Z","caller":"traceutil/trace.go:171","msg":"trace[954560819] range","detail":"{range_begin:/registry/clusterrolebindings/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:789; }","duration":"1.721785031s","start":"2026-03-31T20:40:26.370228Z","end":"2026-03-31T20:40:28.092013Z","steps":["trace[954560819] 'agreement among raft nodes before linearized reading' (duration: 1.721649667s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:28.092391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:26.370212Z","time spent":"1.722172442s","remote":"127.0.0.1:54410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":789,"request content":"key:\"/registry/clusterrolebindings/system:service-account-issuer-discovery\" "} {"level":"warn","ts":"2026-03-31T20:40:28.09201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26019734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:28.092482Z","caller":"traceutil/trace.go:171","msg":"trace[1888928740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"1.260724675s","start":"2026-03-31T20:40:26.831748Z","end":"2026-03-31T20:40:28.092473Z","steps":["trace[1888928740] 'agreement among raft nodes before linearized reading' (duration: 1.2602054s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:28.092515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:26.831727Z","time spent":"1.260782146s","remote":"127.0.0.1:54006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}