Hi Andrea, On 12/18/23 18:36, Andrea della Porta wrote: > Hi Minas, > > On 12:23 Thu 14 Dec , Minas Harutyunyan wrote: >> Hi Andrea, >> >> On 12/3/23 10:52, Andrea della Porta wrote: >>> Hi Minas, >>> >>> On 10:26 Fri 01 Dec , Minas Harutyunyan wrote: >>>> Hi Ivan, >>>> >>>> On 11/28/23 18:43, Ivan Ivanov wrote: >>>>> >>>>> Hi Minas, >>>>> >>>>> On Tue, 2023-11-28 at 11:48 +0000, Minas Harutyunyan wrote: >>>>>> >>>>>> Does this "spurious" interrupt broke your tests? >>>>> >>>>> It is not just some kind of synthetic test case that was broken. >>>>> but real world usage. You can find complains about this error on >>>>> various internet forums, just search for dwc2_hc_chhltd_intr_dma >>>>> and it is not so difficult to reproduce. >>>>> >>>>> Without databook I am not sure we can create better fix, but if >>>>> you develop different solution I will gladly tested it. >>>>> >>>>> Regards, >>>>> Ivan >>>> 1. In addition to HCCHARx and ep_type printing please add printing of >>>> GRXFSTSR if EP is IN or GNPTXSTS if EP is OUT, and provide dmesg with >>>> error case. >>> >>> Here's the log, before comenting the 'goto' out: >>> >>> >>> [684829.206854] --Host Channel Interrupt--, Channel 2 >>> [684829.206866] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 >>> [684829.206875] hcchar[2] = 0x015c9810, chan->ep_type=3 >>> [684829.206883] GRXSTSR = 0x000E0002 >>> [684829.214851] --Host Channel Interrupt--, Channel 6 >>> [684829.214864] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 >>> [684829.214876] hcchar[6] = 0x015c9810, chan->ep_type=3 >>> [684829.214886] GRXSTSR = 0x000E0007 >>> [684829.217853] --Host Channel Interrupt--, Channel 5 >>> [684829.217869] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 >>> [684829.217881] hcchar[5] = 0x009c8801, chan->ep_type=3 >>> [684829.217891] GRXSTSR = 0x000E0005 >>> [684829.222647] --Host Channel Interrupt--, Channel 0 >>> [684829.222659] hcint 0x00000021, hcintmsk 0x00000426, hcint&hcintmsk 0x00000020 >>> [684829.222671] hcchar[0] = 0x01d8d200, chan->ep_type=2 >>> [684829.222681] GRXSTSR = 0x00070044 >>> [684829.222696] --Host Channel Interrupt--, Channel 0 >>> [684829.222704] hcint 0x00000002, hcintmsk 0x00000406, hcint&hcintmsk 0x00000002 >>> [684829.222714] hcchar[0] = 0x01d8d200, chan->ep_type=2 >>> [684829.222724] GRXSTSR = 0x00070044 >>> [684829.222740] dwc2 3f980000.usb: dwc2_hc_chhltd_intr_dma: Channel 0 - ChHltd set, but reason is unknown >>> [684829.222758] dwc2 3f980000.usb: hcint 0x00000002, intsts 0x04000009 >> >> >> Sorry for delayed response. >> I guess the cause of issue is because of channel halted interrupt late >> for about ~40-50us. In above log, Channel 0 twice assert interrupt: >> first for ACK (XferComplete masked) and second for Channel_Halted. These >> all interrupts related to same BULK IN transfer. Ideally these 3 source >> of interrupt (ACK, XferCompl and ChHalt) should be asserted together. >> To check it lets do follow: >> 1. Do not allow unmask ACK interrupt in function >> dwc2_hc_enable_dma_ints(). Just comment "hcintmsk |= HCINTMSK_ACK;" >> 2. remove comment for "goto error" >> 3. remove printing GRXSTSR and GNPTXSTS >> 4. build in non verbose debug mode >> It's just temporary solution to check ACK influence on the issue. >> >> Thanks, >> Minas > > Testing the changes you suggested revealed that "ChHltd set, but reason is unknown" error > is not showing up anymore, but we now have some "Transaction error" as shown in the > following log: > > > [13941.590252] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.590263] hcchar[5] = 0x00dc8801, chan->ep_type=3 > [13941.592240] --Host Channel Interrupt--, Channel 4 > [13941.592249] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.592258] hcchar[4] = 0x015c9810, chan->ep_type=3 > [13941.600243] --Host Channel Interrupt--, Channel 2 > [13941.600263] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.600273] hcchar[2] = 0x015c9810, chan->ep_type=3 > [13941.605521] --Host Channel Interrupt--, Channel 3 > [13941.605539] hcint 0x00000003, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.605549] hcchar[3] = 0x01d83200, chan->ep_type=2 > [13941.608242] --Host Channel Interrupt--, Channel 6 > [13941.608256] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.608266] hcchar[6] = 0x015c9810, chan->ep_type=3 > [13941.609685] --Host Channel Interrupt--, Channel 1 > [13941.609696] hcint 0x00000023, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.609706] hcchar[1] = 0x01d8d200, chan->ep_type=2 > [13941.616243] --Host Channel Interrupt--, Channel 5 > [13941.616262] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.616272] hcchar[5] = 0x015c9810, chan->ep_type=3 > [13941.619514] --Host Channel Interrupt--, Channel 0 > [13941.619527] hcint 0x00000023, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.619538] hcchar[0] = 0x01d8d200, chan->ep_type=2 > [13941.620015] --Host Channel Interrupt--, Channel 2 > [13941.620027] hcint 0x00000003, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.620037] hcchar[2] = 0x01d83200, chan->ep_type=2 > [13941.624240] --Host Channel Interrupt--, Channel 3 > [13941.624249] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.624259] hcchar[3] = 0x015c9810, chan->ep_type=3 > [13941.627347] --Host Channel Interrupt--, Channel 4 > [13941.627363] hcint 0x00000092, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.627373] hcchar[4] = 0x01d8d200, chan->ep_type=2 > [13941.627391] dwc2 3f980000.usb: --Host Channel 4 Interrupt: Transaction Error-- > [13941.627432] --Host Channel Interrupt--, Channel 6 > [13941.627440] hcint 0x00000010, hcintmsk 0x00000416, hcint&hcintmsk 0x00000010 > [13941.627450] hcchar[6] = 0x81d8d200, chan->ep_type=2 > [13941.632240] --Host Channel Interrupt--, Channel 1 > [13941.632248] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.632257] hcchar[1] = 0x015c9810, chan->ep_type=3 > [13941.635528] --Host Channel Interrupt--, Channel 5 > [13941.635543] hcint 0x00000003, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.635553] hcchar[5] = 0x01d83200, chan->ep_type=2 > [13941.640244] --Host Channel Interrupt--, Channel 0 > [13941.640263] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.640272] hcchar[0] = 0x015c9810, chan->ep_type=3 > [13941.642078] --Host Channel Interrupt--, Channel 6 > [13941.642086] hcint 0x00000023, hcintmsk 0x00000406, hcint&hcintmsk 0x00000002 > [13941.642095] hcchar[6] = 0x01d8d200, chan->ep_type=2 > [13941.648243] --Host Channel Interrupt--, Channel 3 > [13941.648262] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.648272] hcchar[3] = 0x015c9810, chan->ep_type=3 > [13941.652240] --Host Channel Interrupt--, Channel 4 > [13941.652248] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.652257] hcchar[4] = 0x009c8801, chan->ep_type=3 > [13941.656241] --Host Channel Interrupt--, Channel 1 > [13941.656256] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.656266] hcchar[1] = 0x015c9810, chan->ep_type=3 > [13941.664240] --Host Channel Interrupt--, Channel 5 > [13941.664248] hcint 0x00000012, hcintmsk 0x00000006, hcint&hcintmsk 0x00000002 > [13941.664257] hcchar[5] = 0x015c9810, chan->ep_type=3 > > > The ping flood is otherwise working, except for a minor percentage loss (~0.7%). > Many thanks, > > Andrea Thanks for testing. Minas
