{"level":"warn","ts":"2026-03-31T11:06:03.766841Z","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-31T11:06:03.766939Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-31T11:06:03.767017Z","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-31T11:06:03.767031Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-03-31T11:06:03.767049Z","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-31T11:06:03.76766Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-03-31T11:06:03.76773Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-31T11:06:03.775267Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.312474ms"} {"level":"info","ts":"2026-03-31T11:06:03.783334Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-03-31T11:06:03.783433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T11:06:03.783461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-03-31T11:06:03.78348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T11:06:03.783498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-03-31T11:06:03.783601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-03-31T11:06:03.791134Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T11:06:03.793189Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T11:06:03.794364Z","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-31T11:06:03.797099Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T11:06:03.797405Z","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-31T11:06:03.797476Z","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-31T11:06:03.797499Z","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-31T11:06:03.797773Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T11:06:03.803332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-03-31T11:06:03.803754Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-03-31T11:06:03.806994Z","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-31T11:06:03.807128Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-03-31T11:06:03.809094Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T11:06:03.809206Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T11:06:03.808874Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-03-31T11:06:03.984507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T11:06:03.984566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T11:06:03.9846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-03-31T11:06:03.984632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-03-31T11:06:03.984643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-03-31T11:06:03.984657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-03-31T11:06:03.98467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-03-31T11:06:03.985619Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:06:03.986546Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T11:06:03.986549Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T11:06:03.986595Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T11:06:03.986846Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T11:06:03.986896Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T11:06:03.987053Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:06:03.987197Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:06:03.987316Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:06:03.988431Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-03-31T11:06:03.989904Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T11:06:08.296544Z","caller":"traceutil/trace.go:171","msg":"trace[622959662] linearizableReadLoop","detail":"{readStateIndex:241; appliedIndex:240; }","duration":"159.416551ms","start":"2026-03-31T11:06:08.137096Z","end":"2026-03-31T11:06:08.296513Z","steps":["trace[622959662] 'read index received' (duration: 156.507074ms)","trace[622959662] 'applied index is now lower than readState.Index' (duration: 2.908057ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:08.29676Z","caller":"traceutil/trace.go:171","msg":"trace[600698419] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"181.162239ms","start":"2026-03-31T11:06:08.115469Z","end":"2026-03-31T11:06:08.296631Z","steps":["trace[600698419] 'process raft request' (duration: 178.1853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:08.296903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.657547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a1e8510a6244f2\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-03-31T11:06:08.29757Z","caller":"traceutil/trace.go:171","msg":"trace[606927329] range","detail":"{range_begin:/registry/events/default/instance.18a1e8510a6244f2; range_end:; response_count:1; response_revision:235; }","duration":"160.518321ms","start":"2026-03-31T11:06:08.137024Z","end":"2026-03-31T11:06:08.297542Z","steps":["trace[606927329] 'agreement among raft nodes before linearized reading' (duration: 159.626007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:08.64358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.151448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:08.643691Z","caller":"traceutil/trace.go:171","msg":"trace[2068403862] linearizableReadLoop","detail":"{readStateIndex:250; appliedIndex:249; }","duration":"121.668667ms","start":"2026-03-31T11:06:08.522006Z","end":"2026-03-31T11:06:08.643675Z","steps":["trace[2068403862] 'read index received' (duration: 9.919854ms)","trace[2068403862] 'applied index is now lower than readState.Index' (duration: 111.747633ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:08.643819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.804881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T11:06:08.643853Z","caller":"traceutil/trace.go:171","msg":"trace[1463110535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:244; }","duration":"121.872672ms","start":"2026-03-31T11:06:08.521972Z","end":"2026-03-31T11:06:08.643844Z","steps":["trace[1463110535] 'agreement among raft nodes before linearized reading' (duration: 121.78347ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:08.643917Z","caller":"traceutil/trace.go:171","msg":"trace[1206658900] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"156.902724ms","start":"2026-03-31T11:06:08.486816Z","end":"2026-03-31T11:06:08.643719Z","steps":["trace[1206658900] 'process raft request' (duration: 45.158471ms)","trace[1206658900] 'compare' (duration: 111.029304ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:08.64396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.831471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T11:06:08.644036Z","caller":"traceutil/trace.go:171","msg":"trace[482354667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:244; }","duration":"121.940854ms","start":"2026-03-31T11:06:08.522081Z","end":"2026-03-31T11:06:08.644022Z","steps":["trace[482354667] 'agreement among raft nodes before linearized reading' (duration: 121.752709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:09.20168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.394142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:09.201843Z","caller":"traceutil/trace.go:171","msg":"trace[263533744] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"459.386602ms","start":"2026-03-31T11:06:08.742418Z","end":"2026-03-31T11:06:09.201804Z","steps":["trace[263533744] 'process raft request' (duration: 40.800656ms)","trace[263533744] 'compare' (duration: 418.263348ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:09.201791Z","caller":"traceutil/trace.go:171","msg":"trace[1370661395] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:254; }","duration":"310.208004ms","start":"2026-03-31T11:06:08.891566Z","end":"2026-03-31T11:06:09.201774Z","steps":["trace[1370661395] 'read index received' (duration: 46.771µs)","trace[1370661395] 'applied index is now lower than readState.Index' (duration: 310.160162ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:09.202015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:08.742394Z","time spent":"459.534245ms","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:06:09.202026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.432449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-03-31T11:06:09.202119Z","caller":"traceutil/trace.go:171","msg":"trace[1449879213] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:249; }","duration":"310.573824ms","start":"2026-03-31T11:06:08.891526Z","end":"2026-03-31T11:06:09.2021Z","steps":["trace[1449879213] 'agreement among raft nodes before linearized reading' (duration: 310.355717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:09.202174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:08.891501Z","time spent":"310.663815ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3732,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-31T11:06:09.712354Z","caller":"traceutil/trace.go:171","msg":"trace[1458788659] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:266; }","duration":"203.34885ms","start":"2026-03-31T11:06:09.508974Z","end":"2026-03-31T11:06:09.712323Z","steps":["trace[1458788659] 'read index received' (duration: 138.477604ms)","trace[1458788659] 'applied index is now lower than readState.Index' (duration: 64.869876ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:09.712395Z","caller":"traceutil/trace.go:171","msg":"trace[1591055653] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"316.432989ms","start":"2026-03-31T11:06:09.395923Z","end":"2026-03-31T11:06:09.712356Z","steps":["trace[1591055653] 'process raft request' (duration: 316.340716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:09.712538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:09.395904Z","time spent":"316.559112ms","remote":"127.0.0.1:48204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:06:09.71258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.592817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:09.713218Z","caller":"traceutil/trace.go:171","msg":"trace[174905055] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"316.718716ms","start":"2026-03-31T11:06:09.395784Z","end":"2026-03-31T11:06:09.712503Z","steps":["trace[174905055] 'process raft request' (duration: 251.690956ms)","trace[174905055] 'compare' (duration: 64.447455ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:09.714067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:09.39577Z","time spent":"318.116684ms","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:06:09.714295Z","caller":"traceutil/trace.go:171","msg":"trace[1709149872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:262; }","duration":"204.862839ms","start":"2026-03-31T11:06:09.508905Z","end":"2026-03-31T11:06:09.713768Z","steps":["trace[1709149872] 'agreement among raft nodes before linearized reading' (duration: 203.523193ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.26017Z","caller":"traceutil/trace.go:171","msg":"trace[1553828153] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"134.790416ms","start":"2026-03-31T11:06:10.125358Z","end":"2026-03-31T11:06:10.260149Z","steps":["trace[1553828153] 'process raft request' (duration: 131.991772ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.260279Z","caller":"traceutil/trace.go:171","msg":"trace[217344460] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"112.997036ms","start":"2026-03-31T11:06:10.147275Z","end":"2026-03-31T11:06:10.260272Z","steps":["trace[217344460] 'read index received' (duration: 110.086478ms)","trace[217344460] 'applied index is now lower than readState.Index' (duration: 2.909538ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:10.260389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.115028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-31T11:06:10.260422Z","caller":"traceutil/trace.go:171","msg":"trace[2139784931] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:286; }","duration":"113.200381ms","start":"2026-03-31T11:06:10.147213Z","end":"2026-03-31T11:06:10.260414Z","steps":["trace[2139784931] 'agreement among raft nodes before linearized reading' (duration: 113.112709ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.399252Z","caller":"traceutil/trace.go:171","msg":"trace[1560221168] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:292; }","duration":"135.515315ms","start":"2026-03-31T11:06:10.263718Z","end":"2026-03-31T11:06:10.399234Z","steps":["trace[1560221168] 'read index received' (duration: 28.856457ms)","trace[1560221168] 'applied index is now lower than readState.Index' (duration: 106.657828ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:10.399319Z","caller":"traceutil/trace.go:171","msg":"trace[574885630] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"136.309557ms","start":"2026-03-31T11:06:10.26299Z","end":"2026-03-31T11:06:10.399299Z","steps":["trace[574885630] 'process raft request' (duration: 130.635926ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.399354Z","caller":"traceutil/trace.go:171","msg":"trace[1934956380] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"135.618558ms","start":"2026-03-31T11:06:10.263718Z","end":"2026-03-31T11:06:10.399337Z","steps":["trace[1934956380] 'process raft request' (duration: 135.466053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:10.399588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.856705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-31T11:06:10.399616Z","caller":"traceutil/trace.go:171","msg":"trace[1085579987] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:289; }","duration":"135.918826ms","start":"2026-03-31T11:06:10.263689Z","end":"2026-03-31T11:06:10.399608Z","steps":["trace[1085579987] 'agreement among raft nodes before linearized reading' (duration: 135.834243ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.473637Z","caller":"traceutil/trace.go:171","msg":"trace[1338803104] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"156.261727ms","start":"2026-03-31T11:06:10.317354Z","end":"2026-03-31T11:06:10.473616Z","steps":["trace[1338803104] 'process raft request' (duration: 156.099603ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.473701Z","caller":"traceutil/trace.go:171","msg":"trace[2127402515] transaction","detail":"{read_only:false; number_of_response:0; response_revision:293; }","duration":"154.942102ms","start":"2026-03-31T11:06:10.318736Z","end":"2026-03-31T11:06:10.473678Z","steps":["trace[2127402515] 'process raft request' (duration: 154.85451ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.473713Z","caller":"traceutil/trace.go:171","msg":"trace[1424718279] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"155.490627ms","start":"2026-03-31T11:06:10.31819Z","end":"2026-03-31T11:06:10.473681Z","steps":["trace[1424718279] 'process raft request' (duration: 155.377134ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.473757Z","caller":"traceutil/trace.go:171","msg":"trace[1469319481] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"156.149234ms","start":"2026-03-31T11:06:10.317567Z","end":"2026-03-31T11:06:10.473716Z","steps":["trace[1469319481] 'process raft request' (duration: 155.951319ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.473757Z","caller":"traceutil/trace.go:171","msg":"trace[1799306588] transaction","detail":"{read_only:false; number_of_response:0; response_revision:293; }","duration":"154.522291ms","start":"2026-03-31T11:06:10.319183Z","end":"2026-03-31T11:06:10.473706Z","steps":["trace[1799306588] 'process raft request' (duration: 154.438039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:10.473885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.021917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-03-31T11:06:10.47392Z","caller":"traceutil/trace.go:171","msg":"trace[823642001] transaction","detail":"{read_only:false; number_of_response:0; response_revision:290; }","duration":"174.962795ms","start":"2026-03-31T11:06:10.298948Z","end":"2026-03-31T11:06:10.473911Z","steps":["trace[823642001] 'process raft request' (duration: 174.467172ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.473938Z","caller":"traceutil/trace.go:171","msg":"trace[182283002] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:293; }","duration":"210.084879ms","start":"2026-03-31T11:06:10.263829Z","end":"2026-03-31T11:06:10.473914Z","steps":["trace[182283002] 'agreement among raft nodes before linearized reading' (duration: 209.896135ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.474053Z","caller":"traceutil/trace.go:171","msg":"trace[1659329424] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"175.926441ms","start":"2026-03-31T11:06:10.298115Z","end":"2026-03-31T11:06:10.474041Z","steps":["trace[1659329424] 'process raft request' (duration: 175.15778ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.586847Z","caller":"traceutil/trace.go:171","msg":"trace[1877380896] linearizableReadLoop","detail":"{readStateIndex:304; appliedIndex:303; }","duration":"104.15737ms","start":"2026-03-31T11:06:10.482667Z","end":"2026-03-31T11:06:10.586824Z","steps":["trace[1877380896] 'read index received' (duration: 46.731532ms)","trace[1877380896] 'applied index is now lower than readState.Index' (duration: 57.424788ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:10.586996Z","caller":"traceutil/trace.go:171","msg":"trace[148591917] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"108.867277ms","start":"2026-03-31T11:06:10.478067Z","end":"2026-03-31T11:06:10.586934Z","steps":["trace[148591917] 'process raft request' (duration: 51.218222ms)","trace[148591917] 'compare' (duration: 57.414258ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:10.587033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.340635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-03-31T11:06:10.587218Z","caller":"traceutil/trace.go:171","msg":"trace[1791127377] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:295; }","duration":"104.564051ms","start":"2026-03-31T11:06:10.48264Z","end":"2026-03-31T11:06:10.587204Z","steps":["trace[1791127377] 'agreement among raft nodes before linearized reading' (duration: 104.296285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:10.628837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.008514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-31T11:06:10.628933Z","caller":"traceutil/trace.go:171","msg":"trace[1957331417] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:297; }","duration":"146.112147ms","start":"2026-03-31T11:06:10.482798Z","end":"2026-03-31T11:06:10.628911Z","steps":["trace[1957331417] 'agreement among raft nodes before linearized reading' (duration: 145.917961ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.628967Z","caller":"traceutil/trace.go:171","msg":"trace[1711575054] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"142.466811ms","start":"2026-03-31T11:06:10.486465Z","end":"2026-03-31T11:06:10.628931Z","steps":["trace[1711575054] 'process raft request' (duration: 142.192063ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.628918Z","caller":"traceutil/trace.go:171","msg":"trace[636835159] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"145.782679ms","start":"2026-03-31T11:06:10.483108Z","end":"2026-03-31T11:06:10.628891Z","steps":["trace[636835159] 'process raft request' (duration: 145.420269ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.756347Z","caller":"traceutil/trace.go:171","msg":"trace[1944480435] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"103.230187ms","start":"2026-03-31T11:06:10.653088Z","end":"2026-03-31T11:06:10.756318Z","steps":["trace[1944480435] 'process raft request' (duration: 88.606018ms)","trace[1944480435] 'compare' (duration: 14.379352ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:10.866286Z","caller":"traceutil/trace.go:171","msg":"trace[1515803995] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"104.769347ms","start":"2026-03-31T11:06:10.761497Z","end":"2026-03-31T11:06:10.866267Z","steps":["trace[1515803995] 'process raft request' (duration: 103.996987ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.866605Z","caller":"traceutil/trace.go:171","msg":"trace[67846216] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"102.737254ms","start":"2026-03-31T11:06:10.763827Z","end":"2026-03-31T11:06:10.866565Z","steps":["trace[67846216] 'process raft request' (duration: 102.071356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:11.461451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.789438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:11.461612Z","caller":"traceutil/trace.go:171","msg":"trace[1181663365] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"164.2469ms","start":"2026-03-31T11:06:11.29735Z","end":"2026-03-31T11:06:11.461597Z","steps":["trace[1181663365] 'process raft request' (duration: 164.194059ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:11.461615Z","caller":"traceutil/trace.go:171","msg":"trace[1137032298] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"183.497172ms","start":"2026-03-31T11:06:11.278096Z","end":"2026-03-31T11:06:11.461593Z","steps":["trace[1137032298] 'process raft request' (duration: 81.345244ms)","trace[1137032298] 'compare' (duration: 101.662675ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:11.903193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.789862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:11.903332Z","caller":"traceutil/trace.go:171","msg":"trace[752188250] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"356.873845ms","start":"2026-03-31T11:06:11.546415Z","end":"2026-03-31T11:06:11.903289Z","steps":["trace[752188250] 'read index received' (duration: 111.942679ms)","trace[752188250] 'applied index is now lower than readState.Index' (duration: 244.930026ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:11.903404Z","caller":"traceutil/trace.go:171","msg":"trace[1944585387] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"360.47263ms","start":"2026-03-31T11:06:11.542883Z","end":"2026-03-31T11:06:11.903356Z","steps":["trace[1944585387] 'process raft request' (duration: 115.435331ms)","trace[1944585387] 'compare' (duration: 244.644248ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:11.903598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:11.542863Z","time spent":"360.633824ms","remote":"127.0.0.1:48038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:06:11.903449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.026189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-31T11:06:11.903818Z","caller":"traceutil/trace.go:171","msg":"trace[1616976017] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:319; }","duration":"357.396728ms","start":"2026-03-31T11:06:11.546387Z","end":"2026-03-31T11:06:11.903784Z","steps":["trace[1616976017] 'agreement among raft nodes before linearized reading' (duration: 357.017838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:11.903923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.845547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T11:06:11.903933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:11.546376Z","time spent":"357.536883ms","remote":"127.0.0.1:47964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-31T11:06:11.903973Z","caller":"traceutil/trace.go:171","msg":"trace[833044253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:319; }","duration":"140.927639ms","start":"2026-03-31T11:06:11.76303Z","end":"2026-03-31T11:06:11.903958Z","steps":["trace[833044253] 'agreement among raft nodes before linearized reading' (duration: 140.841807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:11.903825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.048117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T11:06:11.904027Z","caller":"traceutil/trace.go:171","msg":"trace[81740042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:319; }","duration":"137.283582ms","start":"2026-03-31T11:06:11.766724Z","end":"2026-03-31T11:06:11.904008Z","steps":["trace[81740042] 'agreement among raft nodes before linearized reading' (duration: 137.020705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:12.300072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.991275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:12.300271Z","caller":"traceutil/trace.go:171","msg":"trace[1479904975] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"392.813511ms","start":"2026-03-31T11:06:11.907431Z","end":"2026-03-31T11:06:12.300245Z","steps":["trace[1479904975] 'process raft request' (duration: 249.59153ms)","trace[1479904975] 'compare' (duration: 142.85457ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:12.300389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:11.907411Z","time spent":"392.918373ms","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:06:12.301835Z","caller":"traceutil/trace.go:171","msg":"trace[899017792] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"393.809146ms","start":"2026-03-31T11:06:11.908007Z","end":"2026-03-31T11:06:12.301816Z","steps":["trace[899017792] 'process raft request' (duration: 392.173263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:12.301936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:11.907982Z","time spent":"393.905319ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:12.30226Z","caller":"traceutil/trace.go:171","msg":"trace[925883631] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"393.302314ms","start":"2026-03-31T11:06:11.908941Z","end":"2026-03-31T11:06:12.302243Z","steps":["trace[925883631] 'process raft request' (duration: 392.820891ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:12.302362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:11.908931Z","time spent":"393.379996ms","remote":"127.0.0.1:48112","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-31T11:06:12.302378Z","caller":"traceutil/trace.go:171","msg":"trace[146362955] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"388.050703ms","start":"2026-03-31T11:06:11.914314Z","end":"2026-03-31T11:06:12.302364Z","steps":["trace[146362955] 'process raft request' (duration: 387.692923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:12.30258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:11.914298Z","time spent":"388.241658ms","remote":"127.0.0.1:48038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:18.546811Z","caller":"traceutil/trace.go:171","msg":"trace[634449909] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"210.431957ms","start":"2026-03-31T11:06:18.336354Z","end":"2026-03-31T11:06:18.546786Z","steps":["trace[634449909] 'read index received' (duration: 210.248883ms)","trace[634449909] 'applied index is now lower than readState.Index' (duration: 182.024µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:18.546888Z","caller":"traceutil/trace.go:171","msg":"trace[2107667930] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"211.809314ms","start":"2026-03-31T11:06:18.33505Z","end":"2026-03-31T11:06:18.546859Z","steps":["trace[2107667930] 'process raft request' (duration: 211.579689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:18.54698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.603432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T11:06:18.547041Z","caller":"traceutil/trace.go:171","msg":"trace[1010945454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"210.714875ms","start":"2026-03-31T11:06:18.33631Z","end":"2026-03-31T11:06:18.547024Z","steps":["trace[1010945454] 'agreement among raft nodes before linearized reading' (duration: 210.581921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:18.912029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.233826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:18.912178Z","caller":"traceutil/trace.go:171","msg":"trace[1783541558] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"361.141498ms","start":"2026-03-31T11:06:18.551003Z","end":"2026-03-31T11:06:18.912144Z","steps":["trace[1783541558] 'process raft request' (duration: 245.690176ms)","trace[1783541558] 'compare' (duration: 115.048682ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:18.912488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:18.550988Z","time spent":"361.343003ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:19.141096Z","caller":"traceutil/trace.go:171","msg":"trace[726873497] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"224.248166ms","start":"2026-03-31T11:06:18.916826Z","end":"2026-03-31T11:06:19.141074Z","steps":["trace[726873497] 'process raft request' (duration: 224.099972ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:19.1441Z","caller":"traceutil/trace.go:171","msg":"trace[958154914] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"226.895357ms","start":"2026-03-31T11:06:18.91718Z","end":"2026-03-31T11:06:19.144075Z","steps":["trace[958154914] 'process raft request' (duration: 226.795514ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:39.633118Z","caller":"traceutil/trace.go:171","msg":"trace[329910609] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"108.026415ms","start":"2026-03-31T11:06:39.525063Z","end":"2026-03-31T11:06:39.63309Z","steps":["trace[329910609] 'process raft request' (duration: 107.781148ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:44.601946Z","caller":"traceutil/trace.go:171","msg":"trace[1103224385] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"266.137297ms","start":"2026-03-31T11:06:44.335774Z","end":"2026-03-31T11:06:44.601911Z","steps":["trace[1103224385] 'read index received' (duration: 265.925352ms)","trace[1103224385] 'applied index is now lower than readState.Index' (duration: 210.695µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:44.602185Z","caller":"traceutil/trace.go:171","msg":"trace[1680470667] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"267.785979ms","start":"2026-03-31T11:06:44.334381Z","end":"2026-03-31T11:06:44.602167Z","steps":["trace[1680470667] 'process raft request' (duration: 267.256435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.6024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.593459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.60245Z","caller":"traceutil/trace.go:171","msg":"trace[1093961886] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:626; }","duration":"266.676841ms","start":"2026-03-31T11:06:44.335756Z","end":"2026-03-31T11:06:44.602433Z","steps":["trace[1093961886] 'agreement among raft nodes before linearized reading' (duration: 266.585688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.851908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.997979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:44.85197Z","caller":"traceutil/trace.go:171","msg":"trace[83907162] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"249.921727ms","start":"2026-03-31T11:06:44.60204Z","end":"2026-03-31T11:06:44.851961Z","steps":["trace[83907162] 'read index received' (duration: 88.309531ms)","trace[83907162] 'applied index is now lower than readState.Index' (duration: 161.611606ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:44.852016Z","caller":"traceutil/trace.go:171","msg":"trace[895496824] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"458.26516ms","start":"2026-03-31T11:06:44.393746Z","end":"2026-03-31T11:06:44.852011Z","steps":["trace[895496824] 'process raft request' (duration: 297.114767ms)","trace[895496824] 'compare' (duration: 160.350302ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:44.852043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:44.393719Z","time spent":"458.309393ms","remote":"127.0.0.1:47944","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-31T11:06:44.852147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.237797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.852184Z","caller":"traceutil/trace.go:171","msg":"trace[1699692668] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:627; }","duration":"516.287279ms","start":"2026-03-31T11:06:44.33589Z","end":"2026-03-31T11:06:44.852178Z","steps":["trace[1699692668] 'agreement among raft nodes before linearized reading' (duration: 516.231738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.852201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:44.335886Z","time spent":"516.30986ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:06:44.852331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.746879ms","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-31T11:06:44.852436Z","caller":"traceutil/trace.go:171","msg":"trace[94228934] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:627; }","duration":"491.900763ms","start":"2026-03-31T11:06:44.360514Z","end":"2026-03-31T11:06:44.852414Z","steps":["trace[94228934] 'agreement among raft nodes before linearized reading' (duration: 491.672616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.85249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:44.360508Z","time spent":"491.971225ms","remote":"127.0.0.1:59488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-03-31T11:06:44.852396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.318782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:06:44.852375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.734875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.853336Z","caller":"traceutil/trace.go:171","msg":"trace[1916130630] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:627; }","duration":"516.150585ms","start":"2026-03-31T11:06:44.336621Z","end":"2026-03-31T11:06:44.852771Z","steps":["trace[1916130630] 'agreement among raft nodes before linearized reading' (duration: 515.737835ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.853569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:44.336615Z","time spent":"516.796843ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-03-31T11:06:44.853878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.02414ms","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":"warn","ts":"2026-03-31T11:06:44.852506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.664866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.854898Z","caller":"traceutil/trace.go:171","msg":"trace[2055336952] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:627; }","duration":"494.44987ms","start":"2026-03-31T11:06:44.359738Z","end":"2026-03-31T11:06:44.854187Z","steps":["trace[2055336952] 'agreement among raft nodes before linearized reading' (duration: 492.374395ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:44.854891Z","caller":"traceutil/trace.go:171","msg":"trace[259842716] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:627; }","duration":"192.455353ms","start":"2026-03-31T11:06:44.661835Z","end":"2026-03-31T11:06:44.85429Z","steps":["trace[259842716] 'agreement among raft nodes before linearized reading' (duration: 190.635016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.856137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.726252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.856756Z","caller":"traceutil/trace.go:171","msg":"trace[340107469] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:627; }","duration":"356.159604ms","start":"2026-03-31T11:06:44.50002Z","end":"2026-03-31T11:06:44.856179Z","steps":["trace[340107469] 'agreement among raft nodes before linearized reading' (duration: 354.598783ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:44.856536Z","caller":"traceutil/trace.go:171","msg":"trace[816417851] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:627; }","duration":"352.727143ms","start":"2026-03-31T11:06:44.499995Z","end":"2026-03-31T11:06:44.852722Z","steps":["trace[816417851] 'agreement among raft nodes before linearized reading' (duration: 352.300682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.856987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:44.499976Z","time spent":"356.969895ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T11:06:44.856902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:44.5Z","time spent":"356.887933ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:06:44.85565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:44.359723Z","time spent":"495.406166ms","remote":"127.0.0.1:59488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T11:06:44.857246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.506836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.857326Z","caller":"traceutil/trace.go:171","msg":"trace[36546839] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:627; }","duration":"137.578798ms","start":"2026-03-31T11:06:44.71969Z","end":"2026-03-31T11:06:44.857269Z","steps":["trace[36546839] 'agreement among raft nodes before linearized reading' (duration: 137.194367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.857987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.252823ms","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-31T11:06:44.858072Z","caller":"traceutil/trace.go:171","msg":"trace[2026120478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:627; }","duration":"176.379197ms","start":"2026-03-31T11:06:44.681655Z","end":"2026-03-31T11:06:44.858034Z","steps":["trace[2026120478] 'agreement among raft nodes before linearized reading' (duration: 175.231416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.858523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.95277ms","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-31T11:06:44.858685Z","caller":"traceutil/trace.go:171","msg":"trace[1406524925] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:627; }","duration":"498.55904ms","start":"2026-03-31T11:06:44.360108Z","end":"2026-03-31T11:06:44.858667Z","steps":["trace[1406524925] 'agreement among raft nodes before linearized reading' (duration: 495.718815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.858782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:44.360031Z","time spent":"498.722734ms","remote":"127.0.0.1:59488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:06:44.860484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.197867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.860528Z","caller":"traceutil/trace.go:171","msg":"trace[104269446] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"114.2772ms","start":"2026-03-31T11:06:44.74624Z","end":"2026-03-31T11:06:44.860517Z","steps":["trace[104269446] 'agreement among raft nodes before linearized reading' (duration: 110.631493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.860912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.796051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.860943Z","caller":"traceutil/trace.go:171","msg":"trace[600335565] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:627; }","duration":"198.865923ms","start":"2026-03-31T11:06:44.66207Z","end":"2026-03-31T11:06:44.860936Z","steps":["trace[600335565] 'agreement among raft nodes before linearized reading' (duration: 194.820895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.86103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.902644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.861056Z","caller":"traceutil/trace.go:171","msg":"trace[1662905016] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:627; }","duration":"198.949335ms","start":"2026-03-31T11:06:44.662099Z","end":"2026-03-31T11:06:44.861049Z","steps":["trace[1662905016] 'agreement among raft nodes before linearized reading' (duration: 194.789164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.861227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.865852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.86126Z","caller":"traceutil/trace.go:171","msg":"trace[728481776] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"115.928693ms","start":"2026-03-31T11:06:44.745324Z","end":"2026-03-31T11:06:44.861253Z","steps":["trace[728481776] 'agreement among raft nodes before linearized reading' (duration: 111.552537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.861385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.178623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.861416Z","caller":"traceutil/trace.go:171","msg":"trace[62944463] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:627; }","duration":"141.250566ms","start":"2026-03-31T11:06:44.720159Z","end":"2026-03-31T11:06:44.861409Z","steps":["trace[62944463] 'agreement among raft nodes before linearized reading' (duration: 136.720685ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.861527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.613106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.861551Z","caller":"traceutil/trace.go:171","msg":"trace[909267762] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:627; }","duration":"141.639456ms","start":"2026-03-31T11:06:44.719906Z","end":"2026-03-31T11:06:44.861545Z","steps":["trace[909267762] 'agreement among raft nodes before linearized reading' (duration: 136.976372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:44.862053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.56811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:44.862083Z","caller":"traceutil/trace.go:171","msg":"trace[942967129] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:627; }","duration":"116.601761ms","start":"2026-03-31T11:06:44.745475Z","end":"2026-03-31T11:06:44.862077Z","steps":["trace[942967129] 'agreement among raft nodes before linearized reading' (duration: 111.399173ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:45.154695Z","caller":"traceutil/trace.go:171","msg":"trace[687233386] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"248.878399ms","start":"2026-03-31T11:06:44.905792Z","end":"2026-03-31T11:06:45.15467Z","steps":["trace[687233386] 'read index received' (duration: 248.616962ms)","trace[687233386] 'applied index is now lower than readState.Index' (duration: 260.557µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:45.155048Z","caller":"traceutil/trace.go:171","msg":"trace[1350220317] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"249.582189ms","start":"2026-03-31T11:06:44.905454Z","end":"2026-03-31T11:06:45.155036Z","steps":["trace[1350220317] 'process raft request' (duration: 249.043004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.155315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.482265ms","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-31T11:06:45.155358Z","caller":"traceutil/trace.go:171","msg":"trace[763303884] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:629; }","duration":"249.585388ms","start":"2026-03-31T11:06:44.905763Z","end":"2026-03-31T11:06:45.155348Z","steps":["trace[763303884] 'agreement among raft nodes before linearized reading' (duration: 249.457614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.155508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.599828ms","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-31T11:06:45.155543Z","caller":"traceutil/trace.go:171","msg":"trace[2065709896] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:629; }","duration":"249.638389ms","start":"2026-03-31T11:06:44.905897Z","end":"2026-03-31T11:06:45.155535Z","steps":["trace[2065709896] 'agreement among raft nodes before linearized reading' (duration: 249.585538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.156267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.045679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:45.156317Z","caller":"traceutil/trace.go:171","msg":"trace[930716668] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:629; }","duration":"121.125301ms","start":"2026-03-31T11:06:45.035176Z","end":"2026-03-31T11:06:45.156301Z","steps":["trace[930716668] 'agreement among raft nodes before linearized reading' (duration: 121.0669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.156436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.236664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:45.156467Z","caller":"traceutil/trace.go:171","msg":"trace[1719380142] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:629; }","duration":"121.345227ms","start":"2026-03-31T11:06:45.035112Z","end":"2026-03-31T11:06:45.156457Z","steps":["trace[1719380142] 'agreement among raft nodes before linearized reading' (duration: 121.301816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.156657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.63328ms","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-31T11:06:45.156693Z","caller":"traceutil/trace.go:171","msg":"trace[270828583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"125.700352ms","start":"2026-03-31T11:06:45.030985Z","end":"2026-03-31T11:06:45.156685Z","steps":["trace[270828583] 'agreement among raft nodes before linearized reading' (duration: 125.571749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.156896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.187429ms","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-31T11:06:45.156946Z","caller":"traceutil/trace.go:171","msg":"trace[1986569133] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:629; }","duration":"151.27281ms","start":"2026-03-31T11:06:45.005662Z","end":"2026-03-31T11:06:45.156934Z","steps":["trace[1986569133] 'agreement among raft nodes before linearized reading' (duration: 151.181308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.157097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.165414ms","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-31T11:06:45.157125Z","caller":"traceutil/trace.go:171","msg":"trace[1229099030] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:629; }","duration":"152.210935ms","start":"2026-03-31T11:06:45.004907Z","end":"2026-03-31T11:06:45.157118Z","steps":["trace[1229099030] 'agreement among raft nodes before linearized reading' (duration: 152.165954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.157243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.436702ms","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-31T11:06:45.157278Z","caller":"traceutil/trace.go:171","msg":"trace[570644138] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:629; }","duration":"152.492673ms","start":"2026-03-31T11:06:45.004778Z","end":"2026-03-31T11:06:45.157271Z","steps":["trace[570644138] 'agreement among raft nodes before linearized reading' (duration: 152.442131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.157536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.635611ms","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":"warn","ts":"2026-03-31T11:06:45.157566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.276842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:45.157828Z","caller":"traceutil/trace.go:171","msg":"trace[936700169] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:629; }","duration":"191.58843ms","start":"2026-03-31T11:06:44.966213Z","end":"2026-03-31T11:06:45.157801Z","steps":["trace[936700169] 'agreement among raft nodes before linearized reading' (duration: 191.257611ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.157991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.0518ms","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-31T11:06:45.15808Z","caller":"traceutil/trace.go:171","msg":"trace[1986160576] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:629; }","duration":"210.185702ms","start":"2026-03-31T11:06:44.947872Z","end":"2026-03-31T11:06:45.158058Z","steps":["trace[1986160576] 'agreement among raft nodes before linearized reading' (duration: 210.017978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.158255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.347596ms","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-31T11:06:45.158334Z","caller":"traceutil/trace.go:171","msg":"trace[1215728741] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:629; }","duration":"210.480102ms","start":"2026-03-31T11:06:44.947823Z","end":"2026-03-31T11:06:45.158303Z","steps":["trace[1215728741] 'agreement among raft nodes before linearized reading' (duration: 210.348547ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:45.157604Z","caller":"traceutil/trace.go:171","msg":"trace[2001115884] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:629; }","duration":"208.741426ms","start":"2026-03-31T11:06:44.948847Z","end":"2026-03-31T11:06:45.157588Z","steps":["trace[2001115884] 'agreement among raft nodes before linearized reading' (duration: 208.611171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.158632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.677905ms","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-31T11:06:45.159013Z","caller":"traceutil/trace.go:171","msg":"trace[1304429006] 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:629; }","duration":"171.072296ms","start":"2026-03-31T11:06:44.987923Z","end":"2026-03-31T11:06:45.158996Z","steps":["trace[1304429006] 'agreement among raft nodes before linearized reading' (duration: 170.606043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.158858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.593846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:45.159204Z","caller":"traceutil/trace.go:171","msg":"trace[2062422603] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:629; }","duration":"192.962607ms","start":"2026-03-31T11:06:44.966223Z","end":"2026-03-31T11:06:45.159186Z","steps":["trace[2062422603] 'agreement among raft nodes before linearized reading' (duration: 192.609907ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.1587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.823879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:45.159656Z","caller":"traceutil/trace.go:171","msg":"trace[1648627488] 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:629; }","duration":"171.784885ms","start":"2026-03-31T11:06:44.987847Z","end":"2026-03-31T11:06:45.159632Z","steps":["trace[1648627488] 'agreement among raft nodes before linearized reading' (duration: 170.823669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.158752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.997014ms","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-31T11:06:45.160127Z","caller":"traceutil/trace.go:171","msg":"trace[152728994] 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:629; }","duration":"172.362079ms","start":"2026-03-31T11:06:44.987724Z","end":"2026-03-31T11:06:45.160086Z","steps":["trace[152728994] 'agreement among raft nodes before linearized reading' (duration: 171.000233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.158811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.772565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:45.160369Z","caller":"traceutil/trace.go:171","msg":"trace[1652221830] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:629; }","duration":"193.332087ms","start":"2026-03-31T11:06:44.967017Z","end":"2026-03-31T11:06:45.160349Z","steps":["trace[1652221830] 'agreement among raft nodes before linearized reading' (duration: 191.757554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.754521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.285277ms","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":"info","ts":"2026-03-31T11:06:45.754612Z","caller":"traceutil/trace.go:171","msg":"trace[1089024325] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:630; }","duration":"353.437171ms","start":"2026-03-31T11:06:45.401149Z","end":"2026-03-31T11:06:45.754587Z","steps":["trace[1089024325] 'range keys from in-memory index tree' (duration: 353.199584ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.754606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.128059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:06:45.754686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.401127Z","time spent":"353.532084ms","remote":"127.0.0.1:59638","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-31T11:06:45.754715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.946461ms","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":"info","ts":"2026-03-31T11:06:45.75475Z","caller":"traceutil/trace.go:171","msg":"trace[1305540389] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:630; }","duration":"400.319333ms","start":"2026-03-31T11:06:45.354387Z","end":"2026-03-31T11:06:45.754706Z","steps":["trace[1305540389] 'range keys from in-memory index tree' (duration: 400.046016ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:45.754767Z","caller":"traceutil/trace.go:171","msg":"trace[1140950215] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:630; }","duration":"338.002982ms","start":"2026-03-31T11:06:45.416747Z","end":"2026-03-31T11:06:45.75475Z","steps":["trace[1140950215] 'count revisions from in-memory index tree' (duration: 337.918009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.754809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.416743Z","time spent":"338.054834ms","remote":"127.0.0.1:59640","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-31T11:06:45.754812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.354182Z","time spent":"400.615633ms","remote":"127.0.0.1:59630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-03-31T11:06:45.755008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.078358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:06:45.755052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.338331ms","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":"info","ts":"2026-03-31T11:06:45.755078Z","caller":"traceutil/trace.go:171","msg":"trace[652165861] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:630; }","duration":"400.188101ms","start":"2026-03-31T11:06:45.354875Z","end":"2026-03-31T11:06:45.755063Z","steps":["trace[652165861] 'range keys from in-memory index tree' (duration: 399.983745ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:45.755104Z","caller":"traceutil/trace.go:171","msg":"trace[1500405562] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:630; }","duration":"338.414083ms","start":"2026-03-31T11:06:45.416678Z","end":"2026-03-31T11:06:45.755092Z","steps":["trace[1500405562] 'range keys from in-memory index tree' (duration: 338.219718ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.755127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.354836Z","time spent":"400.275374ms","remote":"127.0.0.1:59630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"warn","ts":"2026-03-31T11:06:45.75514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.416664Z","time spent":"338.465314ms","remote":"127.0.0.1:59640","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-31T11:06:45.755216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.737972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:06:45.755281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.843497ms","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-31T11:06:45.755287Z","caller":"traceutil/trace.go:171","msg":"trace[1777840376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"461.846555ms","start":"2026-03-31T11:06:45.293429Z","end":"2026-03-31T11:06:45.755276Z","steps":["trace[1777840376] 'range keys from in-memory index tree' (duration: 461.671441ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:45.755311Z","caller":"traceutil/trace.go:171","msg":"trace[171799488] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:630; }","duration":"334.876269ms","start":"2026-03-31T11:06:45.420426Z","end":"2026-03-31T11:06:45.755302Z","steps":["trace[171799488] 'range keys from in-memory index tree' (duration: 334.809247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.755328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.893423ms","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-31T11:06:45.755384Z","caller":"traceutil/trace.go:171","msg":"trace[172240195] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:630; }","duration":"376.980015ms","start":"2026-03-31T11:06:45.37839Z","end":"2026-03-31T11:06:45.75537Z","steps":["trace[172240195] 'count revisions from in-memory index tree' (duration: 376.849342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.755424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.984917ms","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":"warn","ts":"2026-03-31T11:06:45.755443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.378375Z","time spent":"377.047707ms","remote":"127.0.0.1:59632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-03-31T11:06:45.755458Z","caller":"traceutil/trace.go:171","msg":"trace[179485703] 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:630; }","duration":"322.028969ms","start":"2026-03-31T11:06:45.433418Z","end":"2026-03-31T11:06:45.755447Z","steps":["trace[179485703] 'range keys from in-memory index tree' (duration: 321.727171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.755479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.608753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-31T11:06:45.755499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.433412Z","time spent":"322.0775ms","remote":"127.0.0.1:59650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-03-31T11:06:45.755535Z","caller":"traceutil/trace.go:171","msg":"trace[1388695578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"460.690395ms","start":"2026-03-31T11:06:45.294833Z","end":"2026-03-31T11:06:45.755523Z","steps":["trace[1388695578] 'range keys from in-memory index tree' (duration: 460.445068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.755571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.881394ms","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":"warn","ts":"2026-03-31T11:06:45.75558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.294816Z","time spent":"460.748807ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T11:06:45.755614Z","caller":"traceutil/trace.go:171","msg":"trace[254001124] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:630; }","duration":"376.932725ms","start":"2026-03-31T11:06:45.378669Z","end":"2026-03-31T11:06:45.755601Z","steps":["trace[254001124] 'range keys from in-memory index tree' (duration: 376.790601ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.755648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.378643Z","time spent":"376.996307ms","remote":"127.0.0.1:59632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-03-31T11:06:45.755387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.903404ms","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":"warn","ts":"2026-03-31T11:06:45.755679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.267998ms","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-31T11:06:45.755696Z","caller":"traceutil/trace.go:171","msg":"trace[345351120] 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:630; }","duration":"322.219914ms","start":"2026-03-31T11:06:45.43346Z","end":"2026-03-31T11:06:45.75568Z","steps":["trace[345351120] 'count revisions from in-memory index tree' (duration: 321.695219ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:45.75574Z","caller":"traceutil/trace.go:171","msg":"trace[1329666687] 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:630; }","duration":"321.33759ms","start":"2026-03-31T11:06:45.434385Z","end":"2026-03-31T11:06:45.755722Z","steps":["trace[1329666687] 'range keys from in-memory index tree' (duration: 321.241768ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.755749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.433457Z","time spent":"322.273755ms","remote":"127.0.0.1:59650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:06:45.75532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.293408Z","time spent":"461.903697ms","remote":"127.0.0.1:47852","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-31T11:06:45.755781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.43438Z","time spent":"321.387981ms","remote":"127.0.0.1:59650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-03-31T11:06:45.755892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.08722ms","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-31T11:06:45.755926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.908986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:45.755931Z","caller":"traceutil/trace.go:171","msg":"trace[1226136325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"451.164852ms","start":"2026-03-31T11:06:45.304756Z","end":"2026-03-31T11:06:45.755921Z","steps":["trace[1226136325] 'range keys from in-memory index tree' (duration: 451.001718ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:45.755958Z","caller":"traceutil/trace.go:171","msg":"trace[1438673536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"186.961517ms","start":"2026-03-31T11:06:45.568986Z","end":"2026-03-31T11:06:45.755948Z","steps":["trace[1438673536] 'range keys from in-memory index tree' (duration: 186.790913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.755983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.304748Z","time spent":"451.227583ms","remote":"127.0.0.1:48112","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-31T11:06:45.755345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.420403Z","time spent":"334.92976ms","remote":"127.0.0.1:59638","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-31T11:06:45.755528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.177541ms","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":"warn","ts":"2026-03-31T11:06:45.756149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.834335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:45.756167Z","caller":"traceutil/trace.go:171","msg":"trace[1661714213] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:630; }","duration":"377.87864ms","start":"2026-03-31T11:06:45.378276Z","end":"2026-03-31T11:06:45.756155Z","steps":["trace[1661714213] 'range keys from in-memory index tree' (duration: 376.968236ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:45.756184Z","caller":"traceutil/trace.go:171","msg":"trace[2110207462] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:630; }","duration":"401.928278ms","start":"2026-03-31T11:06:45.354246Z","end":"2026-03-31T11:06:45.756174Z","steps":["trace[2110207462] 'count revisions from in-memory index tree' (duration: 401.761643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.756224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.354225Z","time spent":"401.990579ms","remote":"127.0.0.1:59630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:06:45.754656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.219665ms","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":"info","ts":"2026-03-31T11:06:45.756359Z","caller":"traceutil/trace.go:171","msg":"trace[2012614539] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:630; }","duration":"355.002512ms","start":"2026-03-31T11:06:45.401346Z","end":"2026-03-31T11:06:45.756348Z","steps":["trace[2012614539] 'count revisions from in-memory index tree' (duration: 353.111722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:45.756392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.401314Z","time spent":"355.067084ms","remote":"127.0.0.1:59638","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-31T11:06:45.756206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:45.378252Z","time spent":"377.941452ms","remote":"127.0.0.1:59632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"info","ts":"2026-03-31T11:06:45.866876Z","caller":"traceutil/trace.go:171","msg":"trace[850830416] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"265.231362ms","start":"2026-03-31T11:06:45.601604Z","end":"2026-03-31T11:06:45.866835Z","steps":["trace[850830416] 'process raft request' (duration: 264.710818ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:45.877486Z","caller":"traceutil/trace.go:171","msg":"trace[527875390] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"114.855255ms","start":"2026-03-31T11:06:45.762612Z","end":"2026-03-31T11:06:45.877467Z","steps":["trace[527875390] 'process raft request' (duration: 114.812294ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:45.877897Z","caller":"traceutil/trace.go:171","msg":"trace[1139739841] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"115.567674ms","start":"2026-03-31T11:06:45.76229Z","end":"2026-03-31T11:06:45.877858Z","steps":["trace[1139739841] 'process raft request' (duration: 114.978699ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:47.704913Z","caller":"traceutil/trace.go:171","msg":"trace[30843668] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"186.28392ms","start":"2026-03-31T11:06:47.51861Z","end":"2026-03-31T11:06:47.704894Z","steps":["trace[30843668] 'process raft request' (duration: 186.151206ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:00.531973Z","caller":"traceutil/trace.go:171","msg":"trace[1181574800] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"106.112164ms","start":"2026-03-31T11:07:00.425842Z","end":"2026-03-31T11:07:00.531954Z","steps":["trace[1181574800] 'process raft request' (duration: 105.9463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:34.220899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:34.854318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:35.355151Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:35.855549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:36.356225Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:36.567237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00044556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-31T11:07:36.567332Z","caller":"traceutil/trace.go:171","msg":"trace[302663220] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000607864s","start":"2026-03-31T11:07:34.566703Z","end":"2026-03-31T11:07:36.567311Z","steps":["trace[302663220] 'agreement among raft nodes before linearized reading' (duration: 2.00042964s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:36.567387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.566642Z","time spent":"2.000735608s","remote":"127.0.0.1:47852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:07:36.856883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:37.166583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.833037832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-31T11:07:37.166686Z","caller":"traceutil/trace.go:171","msg":"trace[948876594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"3.833177326s","start":"2026-03-31T11:07:33.333487Z","end":"2026-03-31T11:07:37.166664Z","steps":["trace[948876594] 'range keys from in-memory index tree' (duration: 3.832878668s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.166744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.333474Z","time spent":"3.833251288s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T11:07:37.167107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.756062133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-31T11:07:37.166584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.501827919s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:37.167172Z","caller":"traceutil/trace.go:171","msg":"trace[1951773569] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:993; }","duration":"3.502436665s","start":"2026-03-31T11:07:33.664718Z","end":"2026-03-31T11:07:37.167155Z","steps":["trace[1951773569] 'range keys from in-memory index tree' (duration: 3.501806599s)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:37.167134Z","caller":"traceutil/trace.go:171","msg":"trace[1008329579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"3.756122044s","start":"2026-03-31T11:07:33.411004Z","end":"2026-03-31T11:07:37.167126Z","steps":["trace[1008329579] 'range keys from in-memory index tree' (duration: 3.75594424s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.167424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.410984Z","time spent":"3.756422622s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T11:07:37.346863Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.713633384s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T11:07:37.347266Z","caller":"traceutil/trace.go:171","msg":"trace[1754413279] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"3.626897166s","start":"2026-03-31T11:07:33.720344Z","end":"2026-03-31T11:07:37.347241Z","steps":["trace[1754413279] 'read index received' (duration: 3.626760022s)","trace[1754413279] 'applied index is now lower than readState.Index' (duration: 136.184µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:37.347346Z","caller":"traceutil/trace.go:171","msg":"trace[685589481] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"3.714316212s","start":"2026-03-31T11:07:33.632999Z","end":"2026-03-31T11:07:37.347315Z","steps":["trace[685589481] 'process raft request' (duration: 3.714079876s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.347439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.627072981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T11:07:37.34746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.632958Z","time spent":"3.714424124s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:37.34749Z","caller":"traceutil/trace.go:171","msg":"trace[1277426372] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:994; }","duration":"3.627185305s","start":"2026-03-31T11:07:33.720292Z","end":"2026-03-31T11:07:37.347478Z","steps":["trace[1277426372] 'agreement among raft nodes before linearized reading' (duration: 3.627068282s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.347524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.72027Z","time spent":"3.627245597s","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:37.829957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.727555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:07:37.830172Z","caller":"traceutil/trace.go:171","msg":"trace[131844325] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"657.638061ms","start":"2026-03-31T11:07:37.172512Z","end":"2026-03-31T11:07:37.83015Z","steps":["trace[131844325] 'process raft request' (duration: 418.290509ms)","trace[131844325] 'compare' (duration: 238.364187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:37.830236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.172489Z","time spent":"657.714713ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:37.830315Z","caller":"traceutil/trace.go:171","msg":"trace[2129237012] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1029; }","duration":"482.955966ms","start":"2026-03-31T11:07:37.347333Z","end":"2026-03-31T11:07:37.830289Z","steps":["trace[2129237012] 'read index received' (duration: 243.411199ms)","trace[2129237012] 'applied index is now lower than readState.Index' (duration: 239.543397ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:37.830447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.827782524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-03-31T11:07:37.830502Z","caller":"traceutil/trace.go:171","msg":"trace[687273321] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:996; }","duration":"3.827873206s","start":"2026-03-31T11:07:34.002613Z","end":"2026-03-31T11:07:37.830486Z","steps":["trace[687273321] 'agreement among raft nodes before linearized reading' (duration: 3.827752373s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.830545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.431908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-31T11:07:37.830557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.002594Z","time spent":"3.827949248s","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":248,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"info","ts":"2026-03-31T11:07:37.830641Z","caller":"traceutil/trace.go:171","msg":"trace[1098105045] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:996; }","duration":"720.529411ms","start":"2026-03-31T11:07:37.110053Z","end":"2026-03-31T11:07:37.830583Z","steps":["trace[1098105045] 'agreement among raft nodes before linearized reading' (duration: 720.308304ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:37.830652Z","caller":"traceutil/trace.go:171","msg":"trace[1595188416] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"657.55861ms","start":"2026-03-31T11:07:37.173085Z","end":"2026-03-31T11:07:37.830644Z","steps":["trace[1595188416] 'process raft request' (duration: 656.993155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.832235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.110033Z","time spent":"722.177043ms","remote":"127.0.0.1:47872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-03-31T11:07:37.832252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.173075Z","time spent":"659.141562ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:07:37.83242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.552261869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T11:07:37.832483Z","caller":"traceutil/trace.go:171","msg":"trace[800347114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"3.552367412s","start":"2026-03-31T11:07:34.2801Z","end":"2026-03-31T11:07:37.832468Z","steps":["trace[800347114] 'agreement among raft nodes before linearized reading' (duration: 3.552171017s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.832523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.280083Z","time spent":"3.552434343s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:07:37.832524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.241816934s","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-31T11:07:37.832571Z","caller":"traceutil/trace.go:171","msg":"trace[1486687850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"2.241976648s","start":"2026-03-31T11:07:35.590585Z","end":"2026-03-31T11:07:37.832561Z","steps":["trace[1486687850] 'agreement among raft nodes before linearized reading' (duration: 2.241754383s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.832551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.743749778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T11:07:37.832581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.286477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T11:07:37.832636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264620866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:37.832633Z","caller":"traceutil/trace.go:171","msg":"trace[1706819023] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:996; }","duration":"3.743864931s","start":"2026-03-31T11:07:34.088744Z","end":"2026-03-31T11:07:37.832609Z","steps":["trace[1706819023] 'agreement among raft nodes before linearized reading' (duration: 3.743704587s)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:37.832655Z","caller":"traceutil/trace.go:171","msg":"trace[182346085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"1.264681948s","start":"2026-03-31T11:07:36.567967Z","end":"2026-03-31T11:07:37.832649Z","steps":["trace[182346085] 'agreement among raft nodes before linearized reading' (duration: 1.264653418s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.832663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.936078012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:37.832658Z","caller":"traceutil/trace.go:171","msg":"trace[1844754254] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:996; }","duration":"694.39335ms","start":"2026-03-31T11:07:37.138244Z","end":"2026-03-31T11:07:37.832637Z","steps":["trace[1844754254] 'agreement among raft nodes before linearized reading' (duration: 694.265497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.832682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:36.567952Z","time spent":"1.26472618s","remote":"127.0.0.1:47858","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-31T11:07:37.832687Z","caller":"traceutil/trace.go:171","msg":"trace[1584952054] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:996; }","duration":"2.936104262s","start":"2026-03-31T11:07:34.896576Z","end":"2026-03-31T11:07:37.832681Z","steps":["trace[1584952054] 'agreement among raft nodes before linearized reading' (duration: 2.93606755s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.832706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.896515Z","time spent":"2.936186174s","remote":"127.0.0.1:48138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:37.832718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.138231Z","time spent":"694.469213ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:37.832699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.088724Z","time spent":"3.743958994s","remote":"127.0.0.1:48246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:37.832836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.626050046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-31T11:07:37.830518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.859364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:07:37.832606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:35.590552Z","time spent":"2.24204861s","remote":"127.0.0.1:48112","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":"info","ts":"2026-03-31T11:07:37.832904Z","caller":"traceutil/trace.go:171","msg":"trace[1166044629] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:996; }","duration":"680.265208ms","start":"2026-03-31T11:07:37.152629Z","end":"2026-03-31T11:07:37.832895Z","steps":["trace[1166044629] 'agreement among raft nodes before linearized reading' (duration: 677.860555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.832942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.152623Z","time spent":"680.308919ms","remote":"127.0.0.1:47982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-03-31T11:07:37.832893Z","caller":"traceutil/trace.go:171","msg":"trace[1665674635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"3.626119287s","start":"2026-03-31T11:07:34.206759Z","end":"2026-03-31T11:07:37.832878Z","steps":["trace[1665674635] 'agreement among raft nodes before linearized reading' (duration: 3.625967073s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.833045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.206749Z","time spent":"3.626280371s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T11:07:37.833767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.899359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:37.833833Z","caller":"traceutil/trace.go:171","msg":"trace[1041167694] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:996; }","duration":"712.99711ms","start":"2026-03-31T11:07:37.12082Z","end":"2026-03-31T11:07:37.833817Z","steps":["trace[1041167694] 'agreement among raft nodes before linearized reading' (duration: 711.611554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.833886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.120806Z","time spent":"713.064783ms","remote":"127.0.0.1:47974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-03-31T11:07:38.055484Z","caller":"traceutil/trace.go:171","msg":"trace[1301228420] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"218.534812ms","start":"2026-03-31T11:07:37.836924Z","end":"2026-03-31T11:07:38.055459Z","steps":["trace[1301228420] 'read index received' (duration: 210.800818ms)","trace[1301228420] 'applied index is now lower than readState.Index' (duration: 7.732654ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:38.055699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.746337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-03-31T11:07:38.055809Z","caller":"traceutil/trace.go:171","msg":"trace[1425155551] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:996; }","duration":"218.901061ms","start":"2026-03-31T11:07:37.836881Z","end":"2026-03-31T11:07:38.055782Z","steps":["trace[1425155551] 'agreement among raft nodes before linearized reading' (duration: 218.694496ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:38.059299Z","caller":"traceutil/trace.go:171","msg":"trace[1397627441] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"222.27088ms","start":"2026-03-31T11:07:37.837008Z","end":"2026-03-31T11:07:38.059279Z","steps":["trace[1397627441] 'process raft request' (duration: 222.005994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:38.059403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.297689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:38.059466Z","caller":"traceutil/trace.go:171","msg":"trace[20647683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"217.379092ms","start":"2026-03-31T11:07:37.842075Z","end":"2026-03-31T11:07:38.059454Z","steps":["trace[20647683] 'agreement among raft nodes before linearized reading' (duration: 217.275869ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:38.059666Z","caller":"traceutil/trace.go:171","msg":"trace[415679823] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"222.541008ms","start":"2026-03-31T11:07:37.837115Z","end":"2026-03-31T11:07:38.059656Z","steps":["trace[415679823] 'process raft request' (duration: 222.035074ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:38.059765Z","caller":"traceutil/trace.go:171","msg":"trace[1430167233] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"177.952142ms","start":"2026-03-31T11:07:37.881804Z","end":"2026-03-31T11:07:38.059756Z","steps":["trace[1430167233] 'process raft request' (duration: 177.48823ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:38.059768Z","caller":"traceutil/trace.go:171","msg":"trace[912038844] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"221.244104ms","start":"2026-03-31T11:07:37.83851Z","end":"2026-03-31T11:07:38.059754Z","steps":["trace[912038844] 'process raft request' (duration: 220.71558ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:38.0598Z","caller":"traceutil/trace.go:171","msg":"trace[1168976537] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"222.253841ms","start":"2026-03-31T11:07:37.837522Z","end":"2026-03-31T11:07:38.059776Z","steps":["trace[1168976537] 'process raft request' (duration: 221.665275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:52.115906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:52.616358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:53.117551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:53.618428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:54.127463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.04068732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-31T11:07:54.127569Z","caller":"traceutil/trace.go:171","msg":"trace[360190257] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.040855104s","start":"2026-03-31T11:07:52.086689Z","end":"2026-03-31T11:07:54.127544Z","steps":["trace[360190257] 'agreement among raft nodes before linearized reading' (duration: 2.04071733s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:54.127629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:52.086673Z","time spent":"2.040943406s","remote":"127.0.0.1:47852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:07:54.128621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:55.055038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:55.055306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.488235931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-31T11:07:55.055352Z","caller":"traceutil/trace.go:171","msg":"trace[17098277] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.488325553s","start":"2026-03-31T11:07:52.567001Z","end":"2026-03-31T11:07:55.055326Z","steps":["trace[17098277] 'agreement among raft nodes before linearized reading' (duration: 2.488279821s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:55.055385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:52.566981Z","time spent":"2.488398885s","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:07:55.555559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:56.060331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:56.398778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:51.399109Z","time spent":"4.999661672s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-03-31T11:07:56.560582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:56.897528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999643182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-31T11:07:56.897688Z","caller":"traceutil/trace.go:171","msg":"trace[1891504765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999848888s","start":"2026-03-31T11:07:51.897814Z","end":"2026-03-31T11:07:56.897663Z","steps":["trace[1891504765] 'agreement among raft nodes before linearized reading' (duration: 4.999670852s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:56.897768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:51.8978Z","time spent":"4.999956621s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/03/31 11:07:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-31T11:07:56.900292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999640382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-31T11:07:56.900348Z","caller":"traceutil/trace.go:171","msg":"trace[392682566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999709353s","start":"2026-03-31T11:07:51.900626Z","end":"2026-03-31T11:07:56.900335Z","steps":["trace[392682566] 'agreement among raft nodes before linearized reading' (duration: 4.999648482s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:56.900395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:51.900621Z","time spent":"4.999765435s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/03/31 11:07:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-31T11:07:57.060715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:07:57.127912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000254578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-31T11:07:57.128009Z","caller":"traceutil/trace.go:171","msg":"trace[1016980529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000381812s","start":"2026-03-31T11:07:52.127606Z","end":"2026-03-31T11:07:57.127988Z","steps":["trace[1016980529] 'agreement among raft nodes before linearized reading' (duration: 5.000260258s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.128068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:52.1276Z","time spent":"5.000458984s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:07:57.426034Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"6.026801416s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T11:07:57.445417Z","caller":"traceutil/trace.go:171","msg":"trace[1122689186] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"5.158515401s","start":"2026-03-31T11:07:52.286876Z","end":"2026-03-31T11:07:57.445392Z","steps":["trace[1122689186] 'process raft request' (duration: 5.158150011s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.445606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:52.286857Z","time spent":"5.158650014s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:57.450699Z","caller":"traceutil/trace.go:171","msg":"trace[2045152436] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1084; }","duration":"5.835200414s","start":"2026-03-31T11:07:51.615469Z","end":"2026-03-31T11:07:57.450669Z","steps":["trace[2045152436] 'read index received' (duration: 5.810832452s)","trace[2045152436] 'applied index is now lower than readState.Index' (duration: 24.366642ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:57.450929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.728166143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T11:07:57.450937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051376894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-03-31T11:07:57.45097Z","caller":"traceutil/trace.go:171","msg":"trace[66305738] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1047; }","duration":"5.728262435s","start":"2026-03-31T11:07:51.722696Z","end":"2026-03-31T11:07:57.450958Z","steps":["trace[66305738] 'agreement among raft nodes before linearized reading' (duration: 5.728153672s)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:57.450998Z","caller":"traceutil/trace.go:171","msg":"trace[956729316] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1047; }","duration":"1.051473106s","start":"2026-03-31T11:07:56.399508Z","end":"2026-03-31T11:07:57.450981Z","steps":["trace[956729316] 'agreement among raft nodes before linearized reading' (duration: 1.051365573s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.451017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.279172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:07:57.451055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389218991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:07:57.451006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:51.722678Z","time spent":"5.728318066s","remote":"127.0.0.1:47956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-03-31T11:07:57.451084Z","caller":"traceutil/trace.go:171","msg":"trace[1386370741] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1047; }","duration":"329.432437ms","start":"2026-03-31T11:07:57.121631Z","end":"2026-03-31T11:07:57.451063Z","steps":["trace[1386370741] 'agreement among raft nodes before linearized reading' (duration: 329.294133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.451108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.208394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-31T11:07:57.451191Z","caller":"traceutil/trace.go:171","msg":"trace[215144013] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1047; }","duration":"339.359939ms","start":"2026-03-31T11:07:57.111816Z","end":"2026-03-31T11:07:57.451176Z","steps":["trace[215144013] 'agreement among raft nodes before linearized reading' (duration: 339.132403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.451238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.323464101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-31T11:07:57.451239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:57.111782Z","time spent":"339.446711ms","remote":"127.0.0.1:47872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"info","ts":"2026-03-31T11:07:57.451273Z","caller":"traceutil/trace.go:171","msg":"trace[934750547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"5.323536283s","start":"2026-03-31T11:07:52.127728Z","end":"2026-03-31T11:07:57.451265Z","steps":["trace[934750547] 'agreement among raft nodes before linearized reading' (duration: 5.32342078s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.451305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:52.127708Z","time spent":"5.323588535s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T11:07:57.451202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.318353486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T11:07:57.451496Z","caller":"traceutil/trace.go:171","msg":"trace[1341930306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1047; }","duration":"5.318657444s","start":"2026-03-31T11:07:52.132826Z","end":"2026-03-31T11:07:57.451484Z","steps":["trace[1341930306] 'agreement among raft nodes before linearized reading' (duration: 5.318315505s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.451506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.566737486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T11:07:57.451537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:52.132819Z","time spent":"5.318702825s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T11:07:57.451551Z","caller":"traceutil/trace.go:171","msg":"trace[894715434] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1047; }","duration":"5.566821369s","start":"2026-03-31T11:07:51.884714Z","end":"2026-03-31T11:07:57.451535Z","steps":["trace[894715434] 'agreement among raft nodes before linearized reading' (duration: 5.566747467s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.451049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:56.399491Z","time spent":"1.051547668s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":574,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-03-31T11:07:57.451584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:51.884691Z","time spent":"5.56688406s","remote":"127.0.0.1:48038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:57.451123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:57.121618Z","time spent":"329.493779ms","remote":"127.0.0.1:48132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-31T11:07:57.451087Z","caller":"traceutil/trace.go:171","msg":"trace[776278717] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1047; }","duration":"1.389247442s","start":"2026-03-31T11:07:56.061829Z","end":"2026-03-31T11:07:57.451076Z","steps":["trace[776278717] 'agreement among raft nodes before linearized reading' (duration: 1.389196861s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.451772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:56.061787Z","time spent":"1.389966281s","remote":"127.0.0.1:48076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:57.451133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.715331741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T11:07:57.451874Z","caller":"traceutil/trace.go:171","msg":"trace[770302802] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1047; }","duration":"1.716105532s","start":"2026-03-31T11:07:55.735758Z","end":"2026-03-31T11:07:57.451863Z","steps":["trace[770302802] 'agreement among raft nodes before linearized reading' (duration: 1.715348302s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.45191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:55.735743Z","time spent":"1.716155553s","remote":"127.0.0.1:40072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:57.453224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.837743301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:57.453451Z","caller":"traceutil/trace.go:171","msg":"trace[104173601] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1047; }","duration":"5.838009458s","start":"2026-03-31T11:07:51.615419Z","end":"2026-03-31T11:07:57.453429Z","steps":["trace[104173601] 'agreement among raft nodes before linearized reading' (duration: 5.83541165s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.453509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:51.615401Z","time spent":"5.838089662s","remote":"127.0.0.1:59610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:57.451242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.323382972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:57.45363Z","caller":"traceutil/trace.go:171","msg":"trace[768866347] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1047; }","duration":"3.325813068s","start":"2026-03-31T11:07:54.127805Z","end":"2026-03-31T11:07:57.453618Z","steps":["trace[768866347] 'agreement among raft nodes before linearized reading' (duration: 3.323416034s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.453679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:54.12778Z","time spent":"3.325874509s","remote":"127.0.0.1:48088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:08:03.433632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.496981ms","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-31T11:08:03.433727Z","caller":"traceutil/trace.go:171","msg":"trace[697227420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1067; }","duration":"287.631535ms","start":"2026-03-31T11:08:03.146067Z","end":"2026-03-31T11:08:03.433698Z","steps":["trace[697227420] 'range keys from in-memory index tree' (duration: 287.300796ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:03.646354Z","caller":"traceutil/trace.go:171","msg":"trace[2128929068] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"207.469631ms","start":"2026-03-31T11:08:03.438586Z","end":"2026-03-31T11:08:03.646055Z","steps":["trace[2128929068] 'process raft request' (duration: 207.296707ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:03.664194Z","caller":"traceutil/trace.go:171","msg":"trace[825060831] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1109; }","duration":"176.69123ms","start":"2026-03-31T11:08:03.487484Z","end":"2026-03-31T11:08:03.664175Z","steps":["trace[825060831] 'read index received' (duration: 158.265333ms)","trace[825060831] 'applied index is now lower than readState.Index' (duration: 18.424857ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:08:03.664366Z","caller":"traceutil/trace.go:171","msg":"trace[71335975] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"177.053739ms","start":"2026-03-31T11:08:03.487298Z","end":"2026-03-31T11:08:03.664352Z","steps":["trace[71335975] 'process raft request' (duration: 176.800273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:03.664648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.141361ms","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-31T11:08:03.664762Z","caller":"traceutil/trace.go:171","msg":"trace[111351838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1069; }","duration":"177.304316ms","start":"2026-03-31T11:08:03.487442Z","end":"2026-03-31T11:08:03.664746Z","steps":["trace[111351838] 'agreement among raft nodes before linearized reading' (duration: 177.074239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:03.664873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.243215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T11:08:03.664958Z","caller":"traceutil/trace.go:171","msg":"trace[1285436160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1069; }","duration":"177.335217ms","start":"2026-03-31T11:08:03.487601Z","end":"2026-03-31T11:08:03.664936Z","steps":["trace[1285436160] 'agreement among raft nodes before linearized reading' (duration: 177.128501ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:05.966747Z","caller":"traceutil/trace.go:171","msg":"trace[1563188384] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"198.074323ms","start":"2026-03-31T11:08:05.768641Z","end":"2026-03-31T11:08:05.966715Z","steps":["trace[1563188384] 'process raft request' (duration: 194.93801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:10.624054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277835768696841,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:08:10.656621Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.261448434s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T11:08:10.656955Z","caller":"traceutil/trace.go:171","msg":"trace[114509702] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"1.261905065s","start":"2026-03-31T11:08:09.395027Z","end":"2026-03-31T11:08:10.656932Z","steps":["trace[114509702] 'process raft request' (duration: 1.261790012s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:10.657079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:09.395013Z","time spent":"1.261998828s","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:10.657624Z","caller":"traceutil/trace.go:171","msg":"trace[525614786] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1130; }","duration":"976.15461ms","start":"2026-03-31T11:08:09.681442Z","end":"2026-03-31T11:08:10.657596Z","steps":["trace[525614786] 'read index received' (duration: 975.377409ms)","trace[525614786] 'applied index is now lower than readState.Index' (duration: 776.001µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:08:10.657715Z","caller":"traceutil/trace.go:171","msg":"trace[1385874966] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"953.561345ms","start":"2026-03-31T11:08:09.70413Z","end":"2026-03-31T11:08:10.657691Z","steps":["trace[1385874966] 'process raft request' (duration: 953.34607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:10.657763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.313674ms","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-31T11:08:10.657786Z","caller":"traceutil/trace.go:171","msg":"trace[460144923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1089; }","duration":"976.378285ms","start":"2026-03-31T11:08:09.681402Z","end":"2026-03-31T11:08:10.65778Z","steps":["trace[460144923] 'agreement among raft nodes before linearized reading' (duration: 976.275743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:10.657803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:09.681385Z","time spent":"976.415277ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:08:10.657813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.118879ms","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":"warn","ts":"2026-03-31T11:08:10.657854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.257623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:08:10.657867Z","caller":"traceutil/trace.go:171","msg":"trace[969566271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1089; }","duration":"450.321134ms","start":"2026-03-31T11:08:10.207541Z","end":"2026-03-31T11:08:10.657862Z","steps":["trace[969566271] 'agreement among raft nodes before linearized reading' (duration: 450.301724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:10.657879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:10.207516Z","time spent":"450.360976ms","remote":"127.0.0.1:47852","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-31T11:08:10.657853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:09.70412Z","time spent":"953.648017ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:10.657871Z","caller":"traceutil/trace.go:171","msg":"trace[654988578] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1089; }","duration":"976.192731ms","start":"2026-03-31T11:08:09.681659Z","end":"2026-03-31T11:08:10.657851Z","steps":["trace[654988578] 'agreement among raft nodes before linearized reading' (duration: 976.098539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:10.657926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:09.681654Z","time spent":"976.255662ms","remote":"127.0.0.1:59546","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-31T11:08:19.343726Z","caller":"traceutil/trace.go:171","msg":"trace[442943242] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"319.276299ms","start":"2026-03-31T11:08:19.024168Z","end":"2026-03-31T11:08:19.343444Z","steps":["trace[442943242] 'process raft request' (duration: 247.437424ms)","trace[442943242] 'compare' (duration: 71.584067ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:19.343848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:19.024155Z","time spent":"319.632458ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:38.978176Z","caller":"traceutil/trace.go:171","msg":"trace[1639508618] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"146.108432ms","start":"2026-03-31T11:08:38.832049Z","end":"2026-03-31T11:08:38.978157Z","steps":["trace[1639508618] 'read index received' (duration: 124.616306ms)","trace[1639508618] 'applied index is now lower than readState.Index' (duration: 21.491466ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:08:38.978257Z","caller":"traceutil/trace.go:171","msg":"trace[1756207817] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"149.514113ms","start":"2026-03-31T11:08:38.828718Z","end":"2026-03-31T11:08:38.978232Z","steps":["trace[1756207817] 'process raft request' (duration: 127.990925ms)","trace[1756207817] 'compare' (duration: 21.360443ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:38.978433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.367259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T11:08:38.978434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.887073ms","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-31T11:08:38.978472Z","caller":"traceutil/trace.go:171","msg":"trace[504047890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"146.485722ms","start":"2026-03-31T11:08:38.831975Z","end":"2026-03-31T11:08:38.978461Z","steps":["trace[504047890] 'agreement among raft nodes before linearized reading' (duration: 146.304978ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:38.978486Z","caller":"traceutil/trace.go:171","msg":"trace[1102450764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"138.962265ms","start":"2026-03-31T11:08:38.839508Z","end":"2026-03-31T11:08:38.97847Z","steps":["trace[1102450764] 'agreement among raft nodes before linearized reading' (duration: 138.822361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:38.978628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.937794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T11:08:38.978661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.539133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T11:08:38.978751Z","caller":"traceutil/trace.go:171","msg":"trace[1047331430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"138.641936ms","start":"2026-03-31T11:08:38.840099Z","end":"2026-03-31T11:08:38.978741Z","steps":["trace[1047331430] 'agreement among raft nodes before linearized reading' (duration: 138.489263ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:38.978684Z","caller":"traceutil/trace.go:171","msg":"trace[248371183] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1243; }","duration":"117.025186ms","start":"2026-03-31T11:08:38.861643Z","end":"2026-03-31T11:08:38.978668Z","steps":["trace[248371183] 'agreement among raft nodes before linearized reading' (duration: 116.943094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:39.346437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.839632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:08:39.346558Z","caller":"traceutil/trace.go:171","msg":"trace[856507314] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"364.016848ms","start":"2026-03-31T11:08:38.982521Z","end":"2026-03-31T11:08:39.346538Z","steps":["trace[856507314] 'process raft request' (duration: 194.996801ms)","trace[856507314] 'compare' (duration: 168.34619ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:39.34662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:38.982505Z","time spent":"364.088541ms","remote":"127.0.0.1:48112","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-31T11:08:39.376176Z","caller":"traceutil/trace.go:171","msg":"trace[218040177] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1294; }","duration":"204.581834ms","start":"2026-03-31T11:08:39.171571Z","end":"2026-03-31T11:08:39.376153Z","steps":["trace[218040177] 'read index received' (duration: 5.926636ms)","trace[218040177] 'applied index is now lower than readState.Index' (duration: 198.654148ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:08:39.376288Z","caller":"traceutil/trace.go:171","msg":"trace[24592314] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"391.166395ms","start":"2026-03-31T11:08:38.985092Z","end":"2026-03-31T11:08:39.376258Z","steps":["trace[24592314] 'process raft request' (duration: 391.001271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:39.376384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:38.985085Z","time spent":"391.239167ms","remote":"127.0.0.1:48112","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-31T11:08:39.376514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.928913ms","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":"info","ts":"2026-03-31T11:08:39.376601Z","caller":"traceutil/trace.go:171","msg":"trace[422544104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1246; }","duration":"205.067698ms","start":"2026-03-31T11:08:39.171516Z","end":"2026-03-31T11:08:39.376583Z","steps":["trace[422544104] 'agreement among raft nodes before linearized reading' (duration: 204.759829ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:39.376758Z","caller":"traceutil/trace.go:171","msg":"trace[301585759] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"392.12239ms","start":"2026-03-31T11:08:38.984618Z","end":"2026-03-31T11:08:39.37674Z","steps":["trace[301585759] 'process raft request' (duration: 391.318659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:39.376894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:38.984607Z","time spent":"392.227642ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:39.395103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.7165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:08:39.395215Z","caller":"traceutil/trace.go:171","msg":"trace[1766862577] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1247; }","duration":"110.884084ms","start":"2026-03-31T11:08:39.284304Z","end":"2026-03-31T11:08:39.395188Z","steps":["trace[1766862577] 'agreement among raft nodes before linearized reading' (duration: 110.683629ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:39.395379Z","caller":"traceutil/trace.go:171","msg":"trace[1193642239] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"206.984248ms","start":"2026-03-31T11:08:39.188364Z","end":"2026-03-31T11:08:39.395348Z","steps":["trace[1193642239] 'process raft request' (duration: 206.478975ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:40.496181Z","caller":"traceutil/trace.go:171","msg":"trace[1708256712] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"129.909876ms","start":"2026-03-31T11:08:40.366242Z","end":"2026-03-31T11:08:40.496152Z","steps":["trace[1708256712] 'process raft request' (duration: 129.70239ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:40.894285Z","caller":"traceutil/trace.go:171","msg":"trace[1263309786] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"135.265766ms","start":"2026-03-31T11:08:40.759002Z","end":"2026-03-31T11:08:40.894268Z","steps":["trace[1263309786] 'process raft request' (duration: 135.145093ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:53.612971Z","caller":"traceutil/trace.go:171","msg":"trace[363140500] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"162.536776ms","start":"2026-03-31T11:08:53.450411Z","end":"2026-03-31T11:08:53.612948Z","steps":["trace[363140500] 'read index received' (duration: 162.361261ms)","trace[363140500] 'applied index is now lower than readState.Index' (duration: 174.545µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:08:53.613446Z","caller":"traceutil/trace.go:171","msg":"trace[136948541] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"177.999104ms","start":"2026-03-31T11:08:53.43542Z","end":"2026-03-31T11:08:53.613419Z","steps":["trace[136948541] 'process raft request' (duration: 177.408278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:53.613537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.173469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-31T11:08:53.613561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.590821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T11:08:53.613599Z","caller":"traceutil/trace.go:171","msg":"trace[702398898] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1350; }","duration":"131.285982ms","start":"2026-03-31T11:08:53.482296Z","end":"2026-03-31T11:08:53.613582Z","steps":["trace[702398898] 'agreement among raft nodes before linearized reading' (duration: 131.134038ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:53.613625Z","caller":"traceutil/trace.go:171","msg":"trace[1584449009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1350; }","duration":"158.689064ms","start":"2026-03-31T11:08:53.454918Z","end":"2026-03-31T11:08:53.613607Z","steps":["trace[1584449009] 'agreement among raft nodes before linearized reading' (duration: 158.445568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:53.613842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.44273ms","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-31T11:08:53.614024Z","caller":"traceutil/trace.go:171","msg":"trace[1808535155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1350; }","duration":"163.658945ms","start":"2026-03-31T11:08:53.450353Z","end":"2026-03-31T11:08:53.614012Z","steps":["trace[1808535155] 'agreement among raft nodes before linearized reading' (duration: 162.897925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:54.07464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.491473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:08:54.074738Z","caller":"traceutil/trace.go:171","msg":"trace[1960527363] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"456.359313ms","start":"2026-03-31T11:08:53.618361Z","end":"2026-03-31T11:08:54.074721Z","steps":["trace[1960527363] 'process raft request' (duration: 108.706046ms)","trace[1960527363] 'compare' (duration: 347.37393ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:54.074789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:53.618334Z","time spent":"456.431536ms","remote":"127.0.0.1:48112","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-31T11:08:54.104644Z","caller":"traceutil/trace.go:171","msg":"trace[361514562] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"482.682628ms","start":"2026-03-31T11:08:53.621936Z","end":"2026-03-31T11:08:54.104619Z","steps":["trace[361514562] 'process raft request' (duration: 482.609286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:54.104768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:53.621927Z","time spent":"482.77644ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:54.104833Z","caller":"traceutil/trace.go:171","msg":"trace[749532052] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"482.451301ms","start":"2026-03-31T11:08:53.622334Z","end":"2026-03-31T11:08:54.104785Z","steps":["trace[749532052] 'process raft request' (duration: 482.235206ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:54.104987Z","caller":"traceutil/trace.go:171","msg":"trace[1104828726] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"484.042493ms","start":"2026-03-31T11:08:53.620871Z","end":"2026-03-31T11:08:54.104914Z","steps":["trace[1104828726] 'process raft request' (duration: 483.576931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:54.104988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:53.622328Z","time spent":"482.575724ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:54.105028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:53.620851Z","time spent":"484.158186ms","remote":"127.0.0.1:48112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:12:51.624863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.964177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:1 size:220"} {"level":"warn","ts":"2026-03-31T11:12:51.624916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.225826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T11:12:51.624974Z","caller":"traceutil/trace.go:171","msg":"trace[1898695703] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:1; response_revision:2477; }","duration":"117.145751ms","start":"2026-03-31T11:12:51.507806Z","end":"2026-03-31T11:12:51.624951Z","steps":["trace[1898695703] 'range keys from in-memory index tree' (duration: 116.80254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:12:51.624989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.334816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:1 size:232"} {"level":"info","ts":"2026-03-31T11:12:51.624992Z","caller":"traceutil/trace.go:171","msg":"trace[1640040810] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2477; }","duration":"146.341639ms","start":"2026-03-31T11:12:51.478633Z","end":"2026-03-31T11:12:51.624975Z","steps":["trace[1640040810] 'range keys from in-memory index tree' (duration: 146.101822ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:12:51.625046Z","caller":"traceutil/trace.go:171","msg":"trace[1360155065] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:1; response_revision:2477; }","duration":"144.405078ms","start":"2026-03-31T11:12:51.480613Z","end":"2026-03-31T11:12:51.625018Z","steps":["trace[1360155065] 'range keys from in-memory index tree' (duration: 144.267005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:12:51.624914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.280065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-31T11:12:51.625243Z","caller":"traceutil/trace.go:171","msg":"trace[654066452] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2477; }","duration":"134.652054ms","start":"2026-03-31T11:12:51.490578Z","end":"2026-03-31T11:12:51.62523Z","steps":["trace[654066452] 'range keys from in-memory index tree' (duration: 134.141231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:12:51.625452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.593456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-31T11:12:51.625499Z","caller":"traceutil/trace.go:171","msg":"trace[919476772] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2477; }","duration":"133.689308ms","start":"2026-03-31T11:12:51.491798Z","end":"2026-03-31T11:12:51.625487Z","steps":["trace[919476772] 'range keys from in-memory index tree' (duration: 133.36842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:12:51.625518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.035392ms","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-31T11:12:51.625595Z","caller":"traceutil/trace.go:171","msg":"trace[761954829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2477; }","duration":"126.110463ms","start":"2026-03-31T11:12:51.499449Z","end":"2026-03-31T11:12:51.625559Z","steps":["trace[761954829] 'range keys from in-memory index tree' (duration: 125.874495ms)"],"step_count":1}