From mboxrd@z Thu Jan 1 00:00:00 1970 Received: from mail-wr1-f49.google.com (mail-wr1-f49.google.com [209.85.221.49]) (using TLSv1.2 with cipher ECDHE-RSA-AES128-GCM-SHA256 (128/128 bits)) (No client certificate requested) by smtp.subspace.kernel.org (Postfix) with ESMTPS id 1C0F13F5BE5 for ; Sat, 5 Sep 2026 08:18:48 +0000 (UTC) Authentication-Results: smtp.subspace.kernel.org; arc=none smtp.client-ip=209.85.221.49 ARC-Seal:i=1; a=rsa-sha256; d=subspace.kernel.org; s=arc-20240116; t=1788596332; cv=none; b=Pn3pQRMLVujrT3+NdUt0RfwoKsm60iC3MWiTvpbI/nftwHwaOg6ldgPDhry2XlbBYQCjbDX5iTaYg9MHTy1zP15C0olJG+wIaODdZEUCFImWuaYtGD+aMg4DHdY0yvrgkXk7ev/BSd7tgiAkkUDL16PDejZ3KY/Z+HQeIL+bBiY= ARC-Message-Signature:i=1; a=rsa-sha256; d=subspace.kernel.org; s=arc-20240116; t=1788596332; c=relaxed/simple; bh=LcV6lSrpWLoOW3X9p2m00fvDtaDYlaeaqqnY35X9hKc=; h=Date:From:To:Cc:Subject:Message-ID:In-Reply-To:References: MIME-Version:Content-Type; b=BPoCI6T9CuPSyJH1m8R3irk8qSuSNtDXrtq7P8S1/VxqJxn9f+0pksGKZSLMFVvJsIPeQ00tpZxB4B4SJciaZolzBm0xK1OxqHRK4sYMR5Wwo/aqz1w+HGoBkMfANe/weEyU38FKNpnOLlr95YXNOb70OBIeWOl3Y/sBaw0nm30= ARC-Authentication-Results:i=1; smtp.subspace.kernel.org; dmarc=pass (p=none dis=none) header.from=gmail.com; spf=pass smtp.mailfrom=gmail.com; dkim=pass (2048-bit key) header.d=gmail.com header.i=@gmail.com header.b=cKW2f6qZ; arc=none smtp.client-ip=209.85.221.49 Authentication-Results: smtp.subspace.kernel.org; dmarc=pass (p=none dis=none) header.from=gmail.com Authentication-Results: smtp.subspace.kernel.org; spf=pass smtp.mailfrom=gmail.com Authentication-Results: smtp.subspace.kernel.org; dkim=pass (2048-bit key) header.d=gmail.com header.i=@gmail.com header.b="cKW2f6qZ" Received: by mail-wr1-f49.google.com with SMTP id ffacd0b85a97d-482dbe4d247so1101549f8f.2 for ; Sat, 05 Sep 2026 01:18:48 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20251104; t=1788596326; x=1789201126; darn=vger.kernel.org; h=content-transfer-encoding:content-type:mime-version:references :in-reply-to:message-id:subject:cc:to:from:date:from:to:cc:subject :date:message-id:reply-to:content-type; bh=cgqxDORdFMqtuRxeNyJ9bVyTrltmlrg6V0F9LjNGATA=; b=cKW2f6qZC0ek6uWJE5XNB5Nmm3tJoChf8joNq6eZEz1U8zphBKNk59ikWPtB+C+Rhy FbxfoXm58JPbTmc6oR0TC7LMR5QEbGD2Axvpjxq0qcd/R1gUqpmCwgUg2rZwRY9XC9vB 9ftXjT/MSBVtIuOoDhJiFl6K5IUPh/mB3w6bOPc0Uikt3kkGz2R7QDvGmLjqub5adgxk D9/KyMU9omzX7hcPHWUighvZqhUndBo+FqjoXdBq8FobUFz1fWormZ/UL9yjlq49lrP9 nK1uR3uaqi+933ta8h9wBvl6cvRPl/NQdLGjFe+mBeAGAwBIy71mZ39+aKtKH/ng8ji9 xsxA== X-Google-DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=1e100.net; s=20251104; t=1788596326; x=1789201126; h=content-transfer-encoding:content-type:mime-version:references :in-reply-to:message-id:subject:cc:to:from:date:x-gm-gg :x-gm-message-state:from:to:cc:subject:date:message-id:reply-to :content-type; bh=cgqxDORdFMqtuRxeNyJ9bVyTrltmlrg6V0F9LjNGATA=; b=C5pfK32kyt0srXiNWYH5MwRs9jEM4OtJ7mYvmhhZN0QLdt0keb281Fcd1H8nKT3dqs XSbF7yuJBIhkFyspGfT50UrzgkbCDDiQYG4OpkylhiTBoNSuLgDVomh69FnM25xqpra2 aI60dmF7e3quyKZ4VzSI0r79Z+ubaDhggEuUaK8p/o2nsSAxKD4Kv+PxqFqalos8z3lU PGqbqf4eXKSPGg3onksAhuQOWmY/qTOu1BhkHdSCyiXdVubIe9j6O54ZeWGwdynYmlBj RYa0kAHytOIegWmAVtj7AEA16e0hD3U0SOiPyRGX64qdoof35xJ+u7VyrNo1WoliZV7m mAEQ== X-Forwarded-Encrypted: i=1; AKwUvBwc4INwqb0czF34+ERLpb4jJcKldBUTEiGczkgMw08OVhXbAAkBTz3skpf6rDYtl6CC1jVgedRk5M4=@vger.kernel.org X-Gm-Message-State: AFuF++kW6k7kBx73njBtaCR4ZN9r/0Wn0h6Ve0xUyO4iEbMYzzkdoAHi 62aoJsa8Lgck2sydbF0FRe51FkHMMz1EQ+g+UQcVHrlTW/DlKku8VV1t X-Gm-Gg: AYBFou2IZqQTvNXE855/GevsKwdjX5xORyqVEiUWbYIv0xlJ3s8gRXQD5a/fd9ZcSDu gobMJDKtms3inoa+GPjbRU4iiHn0RSC0kNP3BZXxi6/qsKquCBEjiN6hyGXxoCz4qz64W7bciLE oS3XsMcM8sc0QEYZYqx9Ka3DaIVPmqjtcoJbaDQJ4Z6Nz60+xlDNfr9nscsTTz9hMGvB5QWX+UY UST8j4suUz2gmlLnFLX/OKAePbew9pJKy2eQ25Ua+44cq+aAsVG+jjjpB13xlvxco05vuyV44Qr FNOawS8XF9GRdalq/gYSldNFPqjtqgon/YO9HUjmHON+mHvdHPGcAP2sXF3pO4Iz31Vk4R6GYod TZFHHiHlEwPkUzMi3IM1kRc9kzQaB3G1/ZDi5etgINPO79bHnQB1xVaSSEjcRRlDU6zk7alVjMx YGLoGuUjLkL+hfvtMvNR8EN5w09JMuNVTZRBuBMbO/PNUQ5G0dOjFVUaynH5TrKxqTZidiQw== X-Received: by 2002:a05:6000:18a8:b0:482:ea08:8c8a with SMTP id ffacd0b85a97d-48587090294mr20570721f8f.20.1788596325622; Sat, 05 Sep 2026 01:18:45 -0700 (PDT) Received: from foxbook (bfg95.neoplus.adsl.tpnet.pl. [83.28.44.95]) by smtp.gmail.com with ESMTPSA id ffacd0b85a97d-485883c6ba4sm13584171f8f.25.2026.09.05.01.18.44 (version=TLS1_2 cipher=AES128-SHA bits=128/128); Sat, 05 Sep 2026 01:18:45 -0700 (PDT) Date: Sat, 5 Sep 2026 10:18:37 +0200 From: Michal Pecio To: wakasio Cc: Mathias Nyman , Greg Kroah-Hartman , linux-usb@vger.kernel.org, linux-kernel@vger.kernel.org Subject: Re: xhci_hcd / ASMedia ASM4242: Bulk-OUT -EPROTO with Logitec 0789:0308 during DVD+RW recording Message-ID: <20260905101837.4b7849c5.michal.pecio@gmail.com> In-Reply-To: <20260905031011.954183-1-scarabeeta@gmail.com> References: <20260904203024.52398f1f.michal.pecio@gmail.com> <20260905031011.954183-1-scarabeeta@gmail.com> Precedence: bulk X-Mailing-List: linux-usb@vger.kernel.org List-Id: List-Subscribe: List-Unsubscribe: MIME-Version: 1.0 Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: 7bit On Sat, 5 Sep 2026 12:10:11 +0900, wakasio wrote: > Cluster 1, right at the very start of the cdrecord invocation > (before any error is visible to userspace), ~45 seconds before the > actual failure: > > Stalled endpoint for slot 1 ep 2 > Hard-reset ep 2, slot 1 Seems unrelated. > Cluster 2, at the exact moment cdrecord reported the write error > (matches the timestamp of "write track data: error after 103088128 > bytes" in cdrecord's own log): > > Transfer error for slot 1 ep 3 on endpoint > Soft-reset ep 3, slot 1 > Transfer error for slot 1 ep 3 on endpoint > Soft-reset ep 3, slot 1 > Transfer error for slot 1 ep 3 on endpoint > Soft-reset ep 3, slot 1 > Transfer error for slot 1 ep 3 on endpoint > Soft-reset ep 3, slot 1 > Transfer error for slot 1 ep 3 on endpoint > Hard-reset ep 3, slot 1 > usb 6-1: reset SuperSpeed USB device number 2 using xhci_hcd > xhci_hcd 0000:77:00.0: Stopped on No-op or Link TRB for slot 1 ep 2 > > So to answer your question directly: it is not constant background > noise throughout the write. It's a single, tight burst of 5 "Transfer > error" events on the same endpoint within about one second. Short fraction of a second, I suppose. Timestamps would help. 4 is the number of retries without ep->err_count reset, so it seems they all happened in the same URB, possibly in the same place. Let's add more debug logging and see if it's some transient stupid problem that we can wait out or plow through with more retries. One more dynamic debug will be needed with this patch: echo 'func handle_tx_event +p' >/proc/dynamic_debug/control echo 'func process_bulk_intr_td +p' >/proc/dynamic_debug/control echo 'func xhci_reset_halted_ep +p' >/proc/dynamic_debug/control --- a/drivers/usb/host/xhci-ring.c +++ b/drivers/usb/host/xhci-ring.c @@ -2511,6 +2511,8 @@ static void process_bulk_intr_td(struct xhci_hcd *xhci, struct xhci_virt_ep *ep, switch (trb_comp_code) { case COMP_SUCCESS: + if (ep->err_count) + xhci_dbg(xhci, "clear err_count at %px\n", ep_trb); ep->err_count = 0; /* handle success with untransferred data as short packet */ if (ep_trb != td->end_trb || remaining) { @@ -2522,6 +2524,8 @@ static void process_bulk_intr_td(struct xhci_hcd *xhci, struct xhci_virt_ep *ep, td->status = 0; break; case COMP_SHORT_PACKET: + if (ep->err_count) + xhci_dbg(xhci, "clear err_count short at %px\n", ep_trb); ep->err_count = 0; td->status = 0; break; @@ -2534,9 +2538,10 @@ static void process_bulk_intr_td(struct xhci_hcd *xhci, struct xhci_virt_ep *ep, goto finish_td; case COMP_USB_TRANSACTION_ERROR: if (xhci->quirks & XHCI_NO_SOFT_RETRY || - (ep->err_count++ > MAX_SOFT_RETRY) || + (ep->err_count++ > 10) || le32_to_cpu(slot_ctx->tt_info) & TT_SLOT) break; + udelay(5 << ep->err_count); td->status = 0; @@ -2706,8 +2711,8 @@ static int handle_tx_event(struct xhci_hcd *xhci, status = -EPROTO; break; case COMP_USB_TRANSACTION_ERROR: - xhci_dbg(xhci, "Transfer error for slot %u ep %u on endpoint\n", - slot_id, ep_index); + xhci_dbg(xhci, "Transfer error for slot %u ep %u on endpoint at %px resid %u\n", + slot_id, ep_index, ep_trb, EVENT_TRB_LEN(le32_to_cpu(event->transfer_len))); status = -EPROTO; break; case COMP_BABBLE_DETECTED_ERROR: