Michael Grzeschik wrote: > Hi Thinh! > > I am currently debugging a strange error with transferring isoc requests > with > the current mainline driver. > > When the pending and started list is empty the complete interrupt > handler is > sending an End Transfer to the controller. This End Transfer will then get > successfully completed. > > irq/50-dwc3-305 [000] d..1. 166.661845: dwc3_gadget_ep_cmd: > ep5in: cmd 'End Transfer' [b0d08] params 00000000 00000000 00000000 --> > status: Successful > irq/50-dwc3-305 [000] d..1. 166.661877: dwc3_event: event > (080b01d6): ep5in: Endpoint Command Complete > irq/50-dwc3-305 [000] d..1. 166.661979: dwc3_event: event > (482a10d6): ep5in: Transfer Not Ready [0000482a] (Not Active) > > > After this the Transfer needs to start again. But unfortunately this > sometimes > does not succeed. You see in the following trace that the Start of the just > prepared trbs did complete with an -18. > > kworker/u5:2-347 [001] d..2. 166.691288: dwc3_prepare_trb: > ep5in: trb 000000007ff95a12 (E233:D232) buf 0000ffffffb1c2a8 size 1x 12 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691293: dwc3_prepare_trb: > ep5in: trb 00000000f9cd175e (E234:D232) buf 0000ffffffb1d000 size 1x > 7156 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691313: dwc3_gadget_ep_cmd: > ep5in: cmd 'Start Transfer' [49140406] params 0000ffff ffff3e80 00000000 > --> status: Bus Expiry > kworker/u5:2-347 [001] d..2. 166.691327: dwc3_gadget_ep_cmd: > ep5in: cmd 'Start Transfer' [49150406] params 0000ffff ffff3e80 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691351: dwc3_prepare_trb: > ep5in: trb 00000000207832b0 (E235:D232) buf 0000ffffffb202e8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691356: dwc3_prepare_trb: > ep5in: trb 000000007a081501 (E236:D232) buf 0000ffffffb21bf4 size 1x > 7160 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691362: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691384: dwc3_prepare_trb: > ep5in: trb 0000000072f4be38 (E237:D232) buf 0000ffffffb24168 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691389: dwc3_prepare_trb: > ep5in: trb 00000000596ad302 (E238:D232) buf 0000ffffffb257ec size 1x > 7160 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691394: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691419: dwc3_prepare_trb: > ep5in: trb 00000000b313a087 (E239:D232) buf 0000ffffffb284e8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691423: dwc3_prepare_trb: > ep5in: trb 000000000667492d (E240:D232) buf 0000ffffffb293e4 size 1x > 7160 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691429: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691451: dwc3_prepare_trb: > ep5in: trb 00000000459ec17b (E241:D232) buf 0000ffffffb305a8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691455: dwc3_prepare_trb: > ep5in: trb 00000000f5ca2c22 (E242:D232) buf 0000ffffffb31fdc size 1x > 7160 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691461: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691483: dwc3_prepare_trb: > ep5in: trb 0000000074be44bd (E243:D232) buf 0000ffffffb34b28 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691487: dwc3_prepare_trb: > ep5in: trb 000000009b1475ad (E244:D232) buf 0000ffffffb35bd4 size 1x > 7160 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691492: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > irq/50-dwc3-305 [000] d..1. 166.691502: dwc3_complete_trb: > ep5in: trb 000000007ff95a12 (E244:D233) buf 0000ffffffb1c2a8 size 1x 0 > ctrl 12454464:00004915 (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.691505: dwc3_complete_trb: > ep5in: trb 00000000f9cd175e (E244:D234) buf 0000ffffffb1d000 size 1x > 7168 ctrl 12454470:00004915 (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.691515: dwc3_gadget_giveback: > ep5in: req 0000000090943319 length 0/7168 zsi ==> -18 > kworker/u5:2-347 [001] d..2. 166.691525: dwc3_prepare_trb: > ep5in: trb 0000000008992ded (E245:D234) buf 0000ffffffe00d68 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691530: dwc3_prepare_trb: > ep5in: trb 0000000099db8537 (E246:D234) buf 0000ffffffe017cc size 1x > 7160 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691535: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691560: dwc3_prepare_trb: > ep5in: trb 000000009a32f3df (E247:D234) buf 0000ffffffdfc8e8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691564: dwc3_prepare_trb: > ep5in: trb 0000000099e18287 (E248:D234) buf 0000ffffffdfd3c4 size 1x > 7160 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691570: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691593: dwc3_prepare_trb: > ep5in: trb 0000000069755327 (E249:D234) buf 0000ffffffdf8e28 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691597: dwc3_prepare_trb: > ep5in: trb 00000000b4ea3844 (E250:D234) buf 0000ffffffdf9fbc size 1x > 7160 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691603: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691626: dwc3_prepare_trb: > ep5in: trb 000000004356a51c (E251:D234) buf 0000ffffffdf4f68 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691630: dwc3_prepare_trb: > ep5in: trb 0000000005790196 (E252:D234) buf 0000ffffffdf5bb4 size 1x > 7160 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691636: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691657: dwc3_prepare_trb: > ep5in: trb 00000000eabf4b9c (E253:D234) buf 0000ffffffdf0ce8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691661: dwc3_prepare_trb: > ep5in: trb 00000000e5b7688c (E254:D234) buf 0000ffffffdf17ac size 1x > 7160 ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691667: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691688: dwc3_prepare_trb: > ep5in: trb 000000007a2518a4 (E0:D234) buf 0000ffffffdec8a8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691693: dwc3_prepare_trb: > ep5in: trb 000000005d8805e7 (E1:D234) buf 0000ffffffded3a4 size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691698: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691720: dwc3_prepare_trb: > ep5in: trb 00000000b2243432 (E2:D234) buf 0000ffffffde8568 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691724: dwc3_prepare_trb: > ep5in: trb 000000009d420079 (E3:D234) buf 0000ffffffde9f9c size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691730: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691751: dwc3_prepare_trb: > ep5in: trb 00000000649fe5d6 (E4:D234) buf 0000ffffff9c8c28 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691756: dwc3_prepare_trb: > ep5in: trb 000000006d01d4b7 (E5:D234) buf 0000ffffff9c9b94 size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691761: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691783: dwc3_prepare_trb: > ep5in: trb 00000000a7d5ae23 (E6:D234) buf 0000ffffffbcc368 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691788: dwc3_prepare_trb: > ep5in: trb 00000000f63c85d3 (E7:D234) buf 0000ffffffbcd78c size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691793: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691816: dwc3_prepare_trb: > ep5in: trb 00000000893379d8 (E8:D234) buf 0000ffffffbd09a8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691820: dwc3_prepare_trb: > ep5in: trb 0000000010adff65 (E9:D234) buf 0000ffffffbd1384 size 1x 7160 > ctrl 00000c71:00000000 (Hlcs:SC:isoc) > kworker/u5:2-347 [001] d..2. 166.691826: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691848: dwc3_prepare_trb: > ep5in: trb 000000003c91f4e3 (E10:D234) buf 0000ffffffbd47e8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691852: dwc3_prepare_trb: > ep5in: trb 000000001eb9a46d (E11:D234) buf 0000ffffffbd5f7c size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691858: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691880: dwc3_prepare_trb: > ep5in: trb 000000008039b4f5 (E12:D234) buf 0000ffffffbd80a8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691884: dwc3_prepare_trb: > ep5in: trb 000000003acd9b86 (E13:D234) buf 0000ffffffbd9b74 size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691890: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691912: dwc3_prepare_trb: > ep5in: trb 0000000016625d76 (E14:D234) buf 0000ffffffbbcc68 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691916: dwc3_prepare_trb: > ep5in: trb 000000005060be63 (E15:D234) buf 0000ffffffbbd76c size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691922: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691943: dwc3_prepare_trb: > ep5in: trb 000000006c7ec562 (E16:D234) buf 0000ffffffbc0768 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691948: dwc3_prepare_trb: > ep5in: trb 00000000153e7b58 (E17:D234) buf 0000ffffffbc1364 size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691953: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.691975: dwc3_prepare_trb: > ep5in: trb 00000000d79e8ccd (E18:D234) buf 0000ffffffbc4f28 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.691979: dwc3_prepare_trb: > ep5in: trb 000000002ebdf5db (E19:D234) buf 0000ffffffbc5f5c size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.691985: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.692008: dwc3_prepare_trb: > ep5in: trb 0000000036246e8f (E20:D234) buf 0000ffffffbc8aa8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.692012: dwc3_prepare_trb: > ep5in: trb 000000000f75f134 (E21:D234) buf 0000ffffffbc9b54 size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.692018: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.692040: dwc3_prepare_trb: > ep5in: trb 00000000ca9d0693 (E22:D234) buf 0000ffffff9c4528 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.692044: dwc3_prepare_trb: > ep5in: trb 000000008587c7c2 (E23:D234) buf 0000ffffff9c574c size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.692050: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.692072: dwc3_prepare_trb: > ep5in: trb 0000000003ad0d16 (E24:D234) buf 0000ffffff9c07a8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.692076: dwc3_prepare_trb: > ep5in: trb 0000000054e33de9 (E25:D234) buf 0000ffffff9c1344 size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.692081: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.692103: dwc3_prepare_trb: > ep5in: trb 000000006598a295 (E26:D234) buf 0000ffffff9a0668 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.692108: dwc3_prepare_trb: > ep5in: trb 0000000015487935 (E27:D234) buf 0000ffffff9a1f3c size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.692114: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.692136: dwc3_prepare_trb: > ep5in: trb 00000000ef2871fc (E28:D234) buf 0000ffffff978ea8 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.692140: dwc3_prepare_trb: > ep5in: trb 0000000013e0668f (E29:D234) buf 0000ffffff979b34 size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.692146: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.692167: dwc3_prepare_trb: > ep5in: trb 0000000092e94032 (E30:D234) buf 0000ffffff94c428 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.692172: dwc3_prepare_trb: > ep5in: trb 0000000002970903 (E31:D234) buf 0000ffffff94d72c size 1x 7160 > ctrl 00000471:00000000 (Hlcs:Sc:isoc) > kworker/u5:2-347 [001] d..2. 166.692177: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > kworker/u5:2-347 [001] d..2. 166.692200: dwc3_prepare_trb: > ep5in: trb 00000000b3249549 (E32:D234) buf 0000ffffff8bc068 size 1x 8 > ctrl 00000465:00000000 (HlCs:Sc:isoc-first) > kworker/u5:2-347 [001] d..2. 166.692205: dwc3_prepare_trb: > ep5in: trb 00000000ec88603f (E33:D234) buf 0000ffffff8bd324 size 1x 1221 > ctrl 00000c71:00000000 (Hlcs:SC:isoc) > kworker/u5:2-347 [001] d..2. 166.692211: dwc3_gadget_ep_cmd: > ep5in: cmd 'Update Transfer' [b0407] params 00000000 00000000 00000000 > --> status: Successful > irq/50-dwc3-305 [000] d..1. 166.698763: dwc3_complete_trb: > ep5in: trb 00000000207832b0 (E33:D235) buf 0000ffffffb202e8 size 1x 0 > ctrl 12458464:00004916 (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.698766: dwc3_complete_trb: > ep5in: trb 000000007a081501 (E33:D236) buf 0000ffffffb21bf4 size 1x 7168 > ctrl 12458470:00004916 (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.698773: dwc3_gadget_giveback: > ep5in: req 00000000a3af67aa length 0/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.705998: dwc3_complete_trb: > ep5in: trb 0000000072f4be38 (E33:D237) buf 0000ffffffb24168 size 1x 0 > ctrl 1245c464:00004917 (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.706002: dwc3_complete_trb: > ep5in: trb 00000000596ad302 (E33:D238) buf 0000ffffffb257ec size 1x 0 > ctrl 1245c470:00004917 (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.706008: dwc3_gadget_giveback: > ep5in: req 00000000efe24442 length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.713228: dwc3_complete_trb: > ep5in: trb 00000000b313a087 (E33:D239) buf 0000ffffffb284e8 size 1x 0 > ctrl 12460464:00004918 (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.713231: dwc3_complete_trb: > ep5in: trb 000000000667492d (E33:D240) buf 0000ffffffb293e4 size 1x 0 > ctrl 12460470:00004918 (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.713238: dwc3_gadget_giveback: > ep5in: req 00000000be20abd7 length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.720459: dwc3_complete_trb: > ep5in: trb 00000000459ec17b (E33:D241) buf 0000ffffffb305a8 size 1x 0 > ctrl 12464464:00004919 (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.720462: dwc3_complete_trb: > ep5in: trb 00000000f5ca2c22 (E33:D242) buf 0000ffffffb31fdc size 1x 0 > ctrl 12464470:00004919 (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.720469: dwc3_gadget_giveback: > ep5in: req 00000000c7d419e1 length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.727689: dwc3_complete_trb: > ep5in: trb 0000000074be44bd (E33:D243) buf 0000ffffffb34b28 size 1x 0 > ctrl 12468464:0000491a (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.727693: dwc3_complete_trb: > ep5in: trb 000000009b1475ad (E33:D244) buf 0000ffffffb35bd4 size 1x 0 > ctrl 12468470:0000491a (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.727699: dwc3_gadget_giveback: > ep5in: req 000000002b31980b length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.734920: dwc3_complete_trb: > ep5in: trb 0000000008992ded (E33:D245) buf 0000ffffffe00d68 size 1x 0 > ctrl 1246c464:0000491b (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.734923: dwc3_complete_trb: > ep5in: trb 0000000099db8537 (E33:D246) buf 0000ffffffe017cc size 1x 0 > ctrl 1246c470:0000491b (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.734930: dwc3_gadget_giveback: > ep5in: req 00000000e62a3292 length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.742150: dwc3_complete_trb: > ep5in: trb 000000009a32f3df (E33:D247) buf 0000ffffffdfc8e8 size 1x 0 > ctrl 12470464:0000491c (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.742153: dwc3_complete_trb: > ep5in: trb 0000000099e18287 (E33:D248) buf 0000ffffffdfd3c4 size 1x 0 > ctrl 12470470:0000491c (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.742161: dwc3_gadget_giveback: > ep5in: req 00000000ebfe68aa length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.749381: dwc3_complete_trb: > ep5in: trb 0000000069755327 (E33:D249) buf 0000ffffffdf8e28 size 1x 0 > ctrl 12474464:0000491d (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.749384: dwc3_complete_trb: > ep5in: trb 00000000b4ea3844 (E33:D250) buf 0000ffffffdf9fbc size 1x 0 > ctrl 12474470:0000491d (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.749390: dwc3_gadget_giveback: > ep5in: req 0000000048685fa4 length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.756611: dwc3_complete_trb: > ep5in: trb 000000004356a51c (E33:D251) buf 0000ffffffdf4f68 size 1x 0 > ctrl 12478464:0000491e (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.756614: dwc3_complete_trb: > ep5in: trb 0000000005790196 (E33:D252) buf 0000ffffffdf5bb4 size 1x 0 > ctrl 12478470:0000491e (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.756621: dwc3_gadget_giveback: > ep5in: req 0000000053b4813f length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.763842: dwc3_complete_trb: > ep5in: trb 00000000eabf4b9c (E33:D253) buf 0000ffffffdf0ce8 size 1x 0 > ctrl 1247c464:0000491f (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.763845: dwc3_complete_trb: > ep5in: trb 00000000e5b7688c (E33:D254) buf 0000ffffffdf17ac size 1x 0 > ctrl 1247c470:0000491f (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.763851: dwc3_gadget_giveback: > ep5in: req 000000009ccc1e16 length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.771072: dwc3_complete_trb: > ep5in: trb 000000007a2518a4 (E33:D0) buf 0000ffffffdec8a8 size 1x 0 ctrl > 12480464:00004920 (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.771075: dwc3_complete_trb: > ep5in: trb 000000005d8805e7 (E33:D1) buf 0000ffffffded3a4 size 1x 0 ctrl > 12480470:00004920 (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.771081: dwc3_gadget_giveback: > ep5in: req 000000001170551f length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.778302: dwc3_complete_trb: > ep5in: trb 00000000b2243432 (E33:D2) buf 0000ffffffde8568 size 1x 0 ctrl > 12484464:00004921 (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.778306: dwc3_complete_trb: > ep5in: trb 000000009d420079 (E33:D3) buf 0000ffffffde9f9c size 1x 0 ctrl > 12484470:00004921 (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.778312: dwc3_gadget_giveback: > ep5in: req 00000000a9695599 length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.785533: dwc3_complete_trb: > ep5in: trb 00000000649fe5d6 (E33:D4) buf 0000ffffff9c8c28 size 1x 0 ctrl > 12488464:00004922 (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.785536: dwc3_complete_trb: > ep5in: trb 000000006d01d4b7 (E33:D5) buf 0000ffffff9c9b94 size 1x 0 ctrl > 12488470:00004922 (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.785545: dwc3_gadget_giveback: > ep5in: req 0000000073dd58a7 length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.792772: dwc3_complete_trb: > ep5in: trb 00000000a7d5ae23 (E33:D6) buf 0000ffffffbcc368 size 1x 0 ctrl > 1248c464:00004923 (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.792775: dwc3_complete_trb: > ep5in: trb 00000000f63c85d3 (E33:D7) buf 0000ffffffbcd78c size 1x 0 ctrl > 1248c470:00004923 (hlcs:Sc:isoc) > irq/50-dwc3-305 [000] d..1. 166.792782: dwc3_gadget_giveback: > ep5in: req 0000000075b98f5a length 7168/7168 zsi ==> -18 > irq/50-dwc3-305 [000] d..1. 166.800002: dwc3_complete_trb: > ep5in: trb 00000000893379d8 (E33:D8) buf 0000ffffffbd09a8 size 1x 0 ctrl > 12490464:00004924 (hlCs:Sc:isoc-first) > irq/50-dwc3-305 [000] d..1. 166.800006: dwc3_complete_trb: > ep5in: trb 0000000010adff65 (E33:D9) buf 0000ffffffbd1384 size 1x 0 ctrl > 12490c70:00004924 (hlcs:SC:isoc) > irq/50-dwc3-305 [000] d..1. 166.800012: dwc3_gadget_giveback: > ep5in: req 00000000cd0ff97f length 7168/7168 zsI ==> -18 > irq/50-dwc3-305 [000] d..1. 166.807238: dwc3_event: event > (49168096): ep5in: Transfer In Progress [00004916] (siM) > irq/50-dwc3-305 [000] d..1. 166.807256: dwc3_gadget_ep_cmd: > ep5in: cmd 'End Transfer' [b0d08] params 00000000 00000000 00000000 --> > status: Successful > > The intermediate Bus Expiry is not always present in that case, but also > completely acceptable as we loop > over the Start Transfer until this succeeds. > > Do you have any Idea why this succeeded Start Streaming does come back > with an > Missed Transfer on the first trb? > Hi Michael, When sending the trace, please leave it unfiltered. It's missing some trace events that I want to check. Please check if the host is actually polling for data at that uframe. Each TRB is scheduled for a particular interval. If the host doesn't request for data at that particular interval, then it's a missed isoc also (err -18). So, if you have a usb traffic analyzer, please verify this. Also, maybe try with a different host vendor to see if the behavior is the same. BR, Thinh
