g_webcam Isoch high bandwidth transfer

PATCH Bin Liu 4 participants 19 messages Sep 20, 2016 – Sep 27, 2016

Subsystems (4)

Media (V4L/DVB) USB DesignWare USB3 DRD IP driver USB webcam gadget

lore

Share & tools

lore

Bin Liu

author

2016-09-20 17:04 UTC #0 lore raw

Hi,

I am trying to check Isoch high bandwidth transfer with g_webcam.ko in
high-speed connection.

First I hacked webcam.c as follows to enable 640x480@30fps mode.

drivers/usb/gadget/legacy/webcam.c +6 -6

index 72c976b..9eb315f 100644
@@ -191,15 +191,15 @@ static const struct UVC_FRAME_UNCOMPRESSED(3) uvc_frame_yuv_360p = {
        .bFrameIndex            = 1,
        .bmCapabilities         = 0,
        .wWidth                 = cpu_to_le16(640),
-       .wHeight                = cpu_to_le16(360),
+       .wHeight                = cpu_to_le16(480),
        .dwMinBitRate           = cpu_to_le32(18432000),
        .dwMaxBitRate           = cpu_to_le32(55296000),
-       .dwMaxVideoFrameBufferSize      = cpu_to_le32(460800),
-       .dwDefaultFrameInterval = cpu_to_le32(666666),
+       .dwMaxVideoFrameBufferSize      = cpu_to_le32(614400),
+       .dwDefaultFrameInterval = cpu_to_le32(333333),
        .bFrameIntervalType     = 3,
-       .dwFrameInterval[0]     = cpu_to_le32(666666),
-       .dwFrameInterval[1]     = cpu_to_le32(1000000),
-       .dwFrameInterval[2]     = cpu_to_le32(5000000),
+       .dwFrameInterval[0]     = cpu_to_le32(333333),
+       .dwFrameInterval[1]     = cpu_to_le32(666666),
+       .dwFrameInterval[2]     = cpu_to_le32(1000000),
 };

then loaded g_webcam.ko as

# modprobe g_webcam streaming_maxpacket=3072

The endpoint descriptor showing on the host is

Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x8d EP 13 IN
bmAttributes 5
Transfer Type Isochronous
Synch Type Asynchronous
Usage Type Data
wMaxPacketSize 0x1400 3x 1024 bytes
bInterval 1

However the usb bus trace shows only one transaction with 1024-bytes packet in every SOF. The host only sends one IN packet in every SOF, I am expecting 2~3 1024-bytes transactions, since this would be required to transfer 640x480@30fps YUV frames in high-speed.

DId I miss anything in the setup?

Thanks,
-Bin.

Felipe Balbi

2016-09-21 08:01 UTC #1 lore raw

Hi,

Quoted 49 lines · Bin Liu

Hi,

I am trying to check Isoch high bandwidth transfer with g_webcam.ko in
high-speed connection.

First I hacked webcam.c as follows to enable 640x480@30fps mode.

drivers/usb/gadget/legacy/webcam.c +6 -6

index 72c976b..9eb315f 100644
@@ -191,15 +191,15 @@ static const struct UVC_FRAME_UNCOMPRESSED(3) uvc_frame_yuv_360p = {
        .bFrameIndex            = 1,
        .bmCapabilities         = 0,
        .wWidth                 = cpu_to_le16(640),
-       .wHeight                = cpu_to_le16(360),
+       .wHeight                = cpu_to_le16(480),
        .dwMinBitRate           = cpu_to_le32(18432000),
        .dwMaxBitRate           = cpu_to_le32(55296000),
-       .dwMaxVideoFrameBufferSize      = cpu_to_le32(460800),
-       .dwDefaultFrameInterval = cpu_to_le32(666666),
+       .dwMaxVideoFrameBufferSize      = cpu_to_le32(614400),
+       .dwDefaultFrameInterval = cpu_to_le32(333333),
        .bFrameIntervalType     = 3,
-       .dwFrameInterval[0]     = cpu_to_le32(666666),
-       .dwFrameInterval[1]     = cpu_to_le32(1000000),
-       .dwFrameInterval[2]     = cpu_to_le32(5000000),
+       .dwFrameInterval[0]     = cpu_to_le32(333333),
+       .dwFrameInterval[1]     = cpu_to_le32(666666),
+       .dwFrameInterval[2]     = cpu_to_le32(1000000),
 };

then loaded g_webcam.ko as

# modprobe g_webcam streaming_maxpacket=3072

The endpoint descriptor showing on the host is

Quoted 6 lines

However the usb bus trace shows only one transaction with 1024-bytes packet in every SOF. The host only sends one IN packet in every SOF, I am expecting 2~3 1024-bytes transactions, since this would be required to transfer 640x480@30fps YUV frames in high-speed.
DId I miss anything in the setup?

MUSB or DWC3? This looks like a UDC bug to me. Can you show a screenshot of your bus analyzer? When host sends IN token, are you replying with DATA0, DATA1 or DATA2?

Signature · 1 line

balbi

Bin Liu

author

2016-09-21 13:27 UTC #2 lore raw

Quoted 53 lines · Felipe Balbi

Hi,
Quoted 49 lines · Bin Liu
Hi,
I am trying to check Isoch high bandwidth transfer with g_webcam.ko in
high-speed connection.
First I hacked webcam.c as follows to enable 640x480@30fps mode.
drivers/usb/gadget/legacy/webcam.c +6 -6
index 72c976b..9eb315f 100644
@@ -191,15 +191,15 @@ static const struct UVC_FRAME_UNCOMPRESSED(3) uvc_frame_yuv_360p = {
        .bFrameIndex            = 1,
        .bmCapabilities         = 0,
        .wWidth                 = cpu_to_le16(640),
-       .wHeight                = cpu_to_le16(360),
+       .wHeight                = cpu_to_le16(480),
        .dwMinBitRate           = cpu_to_le32(18432000),
        .dwMaxBitRate           = cpu_to_le32(55296000),
-       .dwMaxVideoFrameBufferSize      = cpu_to_le32(460800),
-       .dwDefaultFrameInterval = cpu_to_le32(666666),
+       .dwMaxVideoFrameBufferSize      = cpu_to_le32(614400),
+       .dwDefaultFrameInterval = cpu_to_le32(333333),
        .bFrameIntervalType     = 3,
-       .dwFrameInterval[0]     = cpu_to_le32(666666),
-       .dwFrameInterval[1]     = cpu_to_le32(1000000),
-       .dwFrameInterval[2]     = cpu_to_le32(5000000),
+       .dwFrameInterval[0]     = cpu_to_le32(333333),
+       .dwFrameInterval[1]     = cpu_to_le32(666666),
+       .dwFrameInterval[2]     = cpu_to_le32(1000000),
 };
 
then loaded g_webcam.ko as
# modprobe g_webcam streaming_maxpacket=3072
The endpoint descriptor showing on the host is
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x8d EP 13 IN
bmAttributes 5
Transfer Type Isochronous
Synch Type Asynchronous
Usage Type Data
wMaxPacketSize 0x1400 3x 1024 bytes
bInterval 1

Quoted 8 lines

Quoted 6 lines
However the usb bus trace shows only one transaction with 1024-bytes packet in every SOF. The host only sends one IN packet in every SOF, I am expecting 2~3 1024-bytes transactions, since this would be required to transfer 640x480@30fps YUV frames in high-speed.
DId I miss anything in the setup?
MUSB or DWC3? This looks like a UDC bug to me. Can you show a screenshot

Happened on both MUSB and DWC3.

Quoted 1 line

of your bus analyzer? When host sends IN token, are you replying with

The trace screenshot on DWC3 is attached.

Quoted 1 line

DATA0, DATA1 or DATA2?

Good hint! It is DATA0!

Quoted 3 lines

Signature · 2 lines
-- 
balbi

Regards,
-Bin.

Bin Liu

author

2016-09-21 14:58 UTC #3 lore raw

Quoted 59 lines · Bin Liu

Quoted 58 lines · Felipe Balbi
Hi,
Quoted 54 lines · Bin Liu
Hi,
I am trying to check Isoch high bandwidth transfer with g_webcam.ko in
high-speed connection.
First I hacked webcam.c as follows to enable 640x480@30fps mode.
drivers/usb/gadget/legacy/webcam.c +6 -6
index 72c976b..9eb315f 100644
@@ -191,15 +191,15 @@ static const struct UVC_FRAME_UNCOMPRESSED(3) uvc_frame_yuv_360p = {
        .bFrameIndex            = 1,
        .bmCapabilities         = 0,
        .wWidth                 = cpu_to_le16(640),
-       .wHeight                = cpu_to_le16(360),
+       .wHeight                = cpu_to_le16(480),
        .dwMinBitRate           = cpu_to_le32(18432000),
        .dwMaxBitRate           = cpu_to_le32(55296000),
-       .dwMaxVideoFrameBufferSize      = cpu_to_le32(460800),
-       .dwDefaultFrameInterval = cpu_to_le32(666666),
+       .dwMaxVideoFrameBufferSize      = cpu_to_le32(614400),
+       .dwDefaultFrameInterval = cpu_to_le32(333333),
        .bFrameIntervalType     = 3,
-       .dwFrameInterval[0]     = cpu_to_le32(666666),
-       .dwFrameInterval[1]     = cpu_to_le32(1000000),
-       .dwFrameInterval[2]     = cpu_to_le32(5000000),
+       .dwFrameInterval[0]     = cpu_to_le32(333333),
+       .dwFrameInterval[1]     = cpu_to_le32(666666),
+       .dwFrameInterval[2]     = cpu_to_le32(1000000),
 };
 
then loaded g_webcam.ko as
# modprobe g_webcam streaming_maxpacket=3072
The endpoint descriptor showing on the host is
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x8d EP 13 IN
bmAttributes 5
Transfer Type Isochronous
Synch Type Asynchronous
Usage Type Data
wMaxPacketSize 0x1400 3x 1024 bytes
bInterval 1
However the usb bus trace shows only one transaction with 1024-bytes packet in every SOF. The host only sends one IN packet in every SOF, I am expecting 2~3 1024-bytes transactions, since this would be required to transfer 640x480@30fps YUV frames in high-speed.

Quoted 5 lines

Quoted 3 lines
Quoted 1 line
DId I miss anything in the setup?
MUSB or DWC3? This looks like a UDC bug to me. Can you show a screenshot
Happened on both MUSB and DWC3.

Indeed, it is MUSB gadget driver problem.

Quoted 15 lines

Quoted 2 lines
of your bus analyzer? When host sends IN token, are you replying with
The trace screenshot on DWC3 is attached.
Quoted 1 line
DATA0, DATA1 or DATA2?
Good hint! It is DATA0!
Quoted 3 lines
Signature · 2 lines
-- 
balbi
Regards,
-Bin.

Felipe Balbi

2016-09-22 07:38 UTC #4 lore raw

In reply to Bin Liu

Hi,

Quoted 65 lines · Bin Liu

Quoted 53 lines · Felipe Balbi
Hi,
Quoted 49 lines · Bin Liu
Hi,
I am trying to check Isoch high bandwidth transfer with g_webcam.ko in
high-speed connection.
First I hacked webcam.c as follows to enable 640x480@30fps mode.
drivers/usb/gadget/legacy/webcam.c +6 -6
index 72c976b..9eb315f 100644
@@ -191,15 +191,15 @@ static const struct UVC_FRAME_UNCOMPRESSED(3) uvc_frame_yuv_360p = {
        .bFrameIndex            = 1,
        .bmCapabilities         = 0,
        .wWidth                 = cpu_to_le16(640),
-       .wHeight                = cpu_to_le16(360),
+       .wHeight                = cpu_to_le16(480),
        .dwMinBitRate           = cpu_to_le32(18432000),
        .dwMaxBitRate           = cpu_to_le32(55296000),
-       .dwMaxVideoFrameBufferSize      = cpu_to_le32(460800),
-       .dwDefaultFrameInterval = cpu_to_le32(666666),
+       .dwMaxVideoFrameBufferSize      = cpu_to_le32(614400),
+       .dwDefaultFrameInterval = cpu_to_le32(333333),
        .bFrameIntervalType     = 3,
-       .dwFrameInterval[0]     = cpu_to_le32(666666),
-       .dwFrameInterval[1]     = cpu_to_le32(1000000),
-       .dwFrameInterval[2]     = cpu_to_le32(5000000),
+       .dwFrameInterval[0]     = cpu_to_le32(333333),
+       .dwFrameInterval[1]     = cpu_to_le32(666666),
+       .dwFrameInterval[2]     = cpu_to_le32(1000000),
 };
 
then loaded g_webcam.ko as
# modprobe g_webcam streaming_maxpacket=3072
The endpoint descriptor showing on the host is
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x8d EP 13 IN
bmAttributes 5
Transfer Type Isochronous
Synch Type Asynchronous
Usage Type Data
wMaxPacketSize 0x1400 3x 1024 bytes
bInterval 1
Quoted 8 lines
Quoted 6 lines
However the usb bus trace shows only one transaction with 1024-bytes packet in every SOF. The host only sends one IN packet in every SOF, I am expecting 2~3 1024-bytes transactions, since this would be required to transfer 640x480@30fps YUV frames in high-speed.
DId I miss anything in the setup?
MUSB or DWC3? This looks like a UDC bug to me. Can you show a screenshot
Happened on both MUSB and DWC3.

Quoted 7 lines

Quoted 1 line
of your bus analyzer? When host sends IN token, are you replying with
The trace screenshot on DWC3 is attached.
Quoted 1 line
DATA0, DATA1 or DATA2?
Good hint! It is DATA0!

yeah, should've been DATA2. I'll check if we're missing anything for High Bandwidth Iso on DWC3. Can you confirm if it works of tails on DWC3? On your follow-up mail you mentioned it's a bug in MUSB. What about DWC3?

Signature · 1 line

balbi

Felipe Balbi

2016-09-22 08:09 UTC #5 lore raw

Hi,

Quoted 70 lines · Felipe Balbi

Quoted 69 lines · Bin Liu
Quoted 53 lines · Felipe Balbi
Hi,
Quoted 49 lines · Bin Liu
Hi,
I am trying to check Isoch high bandwidth transfer with g_webcam.ko in
high-speed connection.
First I hacked webcam.c as follows to enable 640x480@30fps mode.
drivers/usb/gadget/legacy/webcam.c +6 -6
index 72c976b..9eb315f 100644
@@ -191,15 +191,15 @@ static const struct UVC_FRAME_UNCOMPRESSED(3) uvc_frame_yuv_360p = {
        .bFrameIndex            = 1,
        .bmCapabilities         = 0,
        .wWidth                 = cpu_to_le16(640),
-       .wHeight                = cpu_to_le16(360),
+       .wHeight                = cpu_to_le16(480),
        .dwMinBitRate           = cpu_to_le32(18432000),
        .dwMaxBitRate           = cpu_to_le32(55296000),
-       .dwMaxVideoFrameBufferSize      = cpu_to_le32(460800),
-       .dwDefaultFrameInterval = cpu_to_le32(666666),
+       .dwMaxVideoFrameBufferSize      = cpu_to_le32(614400),
+       .dwDefaultFrameInterval = cpu_to_le32(333333),
        .bFrameIntervalType     = 3,
-       .dwFrameInterval[0]     = cpu_to_le32(666666),
-       .dwFrameInterval[1]     = cpu_to_le32(1000000),
-       .dwFrameInterval[2]     = cpu_to_le32(5000000),
+       .dwFrameInterval[0]     = cpu_to_le32(333333),
+       .dwFrameInterval[1]     = cpu_to_le32(666666),
+       .dwFrameInterval[2]     = cpu_to_le32(1000000),
 };
 
then loaded g_webcam.ko as
# modprobe g_webcam streaming_maxpacket=3072
The endpoint descriptor showing on the host is
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x8d EP 13 IN
bmAttributes 5
Transfer Type Isochronous
Synch Type Asynchronous
Usage Type Data
wMaxPacketSize 0x1400 3x 1024 bytes
bInterval 1
Quoted 8 lines
Quoted 6 lines
However the usb bus trace shows only one transaction with 1024-bytes packet in every SOF. The host only sends one IN packet in every SOF, I am expecting 2~3 1024-bytes transactions, since this would be required to transfer 640x480@30fps YUV frames in high-speed.
DId I miss anything in the setup?
MUSB or DWC3? This looks like a UDC bug to me. Can you show a screenshot
Happened on both MUSB and DWC3.
Quoted 1 line
of your bus analyzer? When host sends IN token, are you replying with
The trace screenshot on DWC3 is attached.

Quoted 8 lines

Quoted 3 lines
Quoted 1 line
DATA0, DATA1 or DATA2?
Good hint! It is DATA0!
yeah, should've been DATA2. I'll check if we're missing anything for High Bandwidth Iso on DWC3. Can you confirm if it works of tails on DWC3? On your follow-up mail you mentioned it's a bug in MUSB. What about DWC3?

I'm assuming DWC3 really breaks. Here's a patch for that:

8<---------------------------------- cut here ---------------------------------- From 62807011c00055785575bb39d92bfe8836817e2f Mon Sep 17 00:00:00 2001
From: Felipe Balbi <[email protected]>
Date: Thu, 22 Sep 2016 11:01:01 +0300
Subject: [PATCH] usb: dwc3: gadget: set PCM1 field of isochronous-first TRBs

In case of High-Speed, High-Bandwidth endpoints, we need to tell DWC3 that we have more than one packet per interval. We do that by setting PCM1 field of Isochronous-First TRB.

Signed-off-by Felipe Balbi <[email protected]>

---
 drivers/usb/dwc3/gadget.c | 14 ++++++++++++--
 1 file changed, 12 insertions(+), 2 deletions(-)

drivers/usb/dwc3/gadget.c +12 -2

index 602f12254161..106623faf060 100644
@@ -787,6 +787,9 @@ static void dwc3_prepare_one_trb(struct dwc3_ep *dep,
 		unsigned length, unsigned chain, unsigned node)
 {
 	struct dwc3_trb		*trb;
+	struct dwc3		*dwc = dep->dwc;
+	struct usb_gadget	*gadget = &dwc->gadget;
+	enum usb_device_speed	speed = speed;
 
 	dwc3_trace(trace_dwc3_gadget, "%s: req %p dma %08llx length %d%s",
 			dep->name, req, (unsigned long long) dma,

@@ -813,10 +816,17 @@ static void dwc3_prepare_one_trb(struct dwc3_ep *dep,
 		break;
 
 	case USB_ENDPOINT_XFER_ISOC:
-		if (!node)
+		if (!node) {
 			trb->ctrl = DWC3_TRBCTL_ISOCHRONOUS_FIRST;
-		else
+
+			if (speed == USB_SPEED_HIGH) {
+				struct usb_endpoint *ep = &dep->endpoint;
+				u8 pkts = DIV_ROUNDUP(ep->maxpacket, 1024);
+				trb->size |= DWC3_TRB_SIZE_PCM1(pkts - 1);
+			}
+		} else {
 			trb->ctrl = DWC3_TRBCTL_ISOCHRONOUS;
+		}
 
 		/* always enable Interrupt on Missed ISOC */
 		trb->ctrl |= DWC3_TRB_CTRL_ISP_IMI;

Signature · 7 lines

2.10.0

Let me know if this helps DWC3, also, let me know how you're using
g_webcam, I'd like to test that out on my side too ;-)

-- 
balbi

Felipe Balbi

2016-09-22 10:07 UTC #6 lore raw

Hi,

Quoted 103 lines · Felipe Balbi

Quoted 70 lines · Felipe Balbi
Quoted 69 lines · Bin Liu
Quoted 53 lines · Felipe Balbi
Hi,
Quoted 49 lines · Bin Liu
Hi,
I am trying to check Isoch high bandwidth transfer with g_webcam.ko in
high-speed connection.
First I hacked webcam.c as follows to enable 640x480@30fps mode.
drivers/usb/gadget/legacy/webcam.c +6 -6
index 72c976b..9eb315f 100644
@@ -191,15 +191,15 @@ static const struct UVC_FRAME_UNCOMPRESSED(3) uvc_frame_yuv_360p = {
        .bFrameIndex            = 1,
        .bmCapabilities         = 0,
        .wWidth                 = cpu_to_le16(640),
-       .wHeight                = cpu_to_le16(360),
+       .wHeight                = cpu_to_le16(480),
        .dwMinBitRate           = cpu_to_le32(18432000),
        .dwMaxBitRate           = cpu_to_le32(55296000),
-       .dwMaxVideoFrameBufferSize      = cpu_to_le32(460800),
-       .dwDefaultFrameInterval = cpu_to_le32(666666),
+       .dwMaxVideoFrameBufferSize      = cpu_to_le32(614400),
+       .dwDefaultFrameInterval = cpu_to_le32(333333),
        .bFrameIntervalType     = 3,
-       .dwFrameInterval[0]     = cpu_to_le32(666666),
-       .dwFrameInterval[1]     = cpu_to_le32(1000000),
-       .dwFrameInterval[2]     = cpu_to_le32(5000000),
+       .dwFrameInterval[0]     = cpu_to_le32(333333),
+       .dwFrameInterval[1]     = cpu_to_le32(666666),
+       .dwFrameInterval[2]     = cpu_to_le32(1000000),
 };
 
then loaded g_webcam.ko as
# modprobe g_webcam streaming_maxpacket=3072
The endpoint descriptor showing on the host is
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x8d EP 13 IN
bmAttributes 5
Transfer Type Isochronous
Synch Type Asynchronous
Usage Type Data
wMaxPacketSize 0x1400 3x 1024 bytes
bInterval 1
Quoted 8 lines
Quoted 6 lines
However the usb bus trace shows only one transaction with 1024-bytes packet in every SOF. The host only sends one IN packet in every SOF, I am expecting 2~3 1024-bytes transactions, since this would be required to transfer 640x480@30fps YUV frames in high-speed.
DId I miss anything in the setup?
MUSB or DWC3? This looks like a UDC bug to me. Can you show a screenshot
Happened on both MUSB and DWC3.
Quoted 1 line
of your bus analyzer? When host sends IN token, are you replying with
The trace screenshot on DWC3 is attached.
Quoted 8 lines
Quoted 3 lines
Quoted 1 line
DATA0, DATA1 or DATA2?
Good hint! It is DATA0!
yeah, should've been DATA2. I'll check if we're missing anything for High Bandwidth Iso on DWC3. Can you confirm if it works of tails on DWC3? On your follow-up mail you mentioned it's a bug in MUSB. What about DWC3?
I'm assuming DWC3 really breaks. Here's a patch for that:
8<---------------------------------- cut here ---------------------------------- From 62807011c00055785575bb39d92bfe8836817e2f Mon Sep 17 00:00:00 2001
From: Felipe Balbi <[email protected]>
Date: Thu, 22 Sep 2016 11:01:01 +0300
Subject: [PATCH] usb: dwc3: gadget: set PCM1 field of isochronous-first TRBs
In case of High-Speed, High-Bandwidth endpoints, we need to tell DWC3 that we have more than one packet per interval. We do that by setting PCM1 field of Isochronous-First TRB.
Signed-off-by Felipe Balbi <[email protected]>
---
 drivers/usb/dwc3/gadget.c | 14 ++++++++++++--
 1 file changed, 12 insertions(+), 2 deletions(-)
drivers/usb/dwc3/gadget.c +0 -0
index 602f12254161..106623faf060 100644

drivers/usb/dwc3/gadget.c static void dwc3_prepare_one_trb(struct dwc3_ep *dep, · line 792

Quoted 6 lines

drivers/usb/dwc3/gadget.c +3 -0

@@ -787,6 +787,9 @@ static void dwc3_prepare_one_trb(struct dwc3_ep *dep,
 		unsigned length, unsigned chain, unsigned node)
 {
 	struct dwc3_trb		*trb;
+	struct dwc3		*dwc = dep->dwc;
+	struct usb_gadget	*gadget = &dwc->gadget;
+	enum usb_device_speed	speed = speed;

and of course I sent the wrong version :-)

Here's one that actually compiles, sorry about that.

8<---------------------------------- cut here ---------------------------------- From 44282f6c664b17e6b9dffbc31e72258be84823a4 Mon Sep 17 00:00:00 2001
From: Felipe Balbi <[email protected]>
Date: Thu, 22 Sep 2016 11:01:01 +0300
Subject: [PATCH] usb: dwc3: gadget: set PCM1 field of isochronous-first TRBs

In case of High-Speed, High-Bandwidth endpoints, we need to tell DWC3 that we have more than one packet per interval. We do that by setting PCM1 field of Isochronous-First TRB.

Signed-off-by Felipe Balbi <[email protected]>

---
 drivers/usb/dwc3/gadget.c | 14 ++++++++++++--
 1 file changed, 12 insertions(+), 2 deletions(-)

drivers/usb/dwc3/gadget.c +12 -2

index 602f12254161..34a7b1bf0522 100644
@@ -787,6 +787,9 @@ static void dwc3_prepare_one_trb(struct dwc3_ep *dep,
 		unsigned length, unsigned chain, unsigned node)
 {
 	struct dwc3_trb		*trb;
+	struct dwc3		*dwc = dep->dwc;
+	struct usb_gadget	*gadget = &dwc->gadget;
+	enum usb_device_speed	speed = gadget->speed;
 
 	dwc3_trace(trace_dwc3_gadget, "%s: req %p dma %08llx length %d%s",
 			dep->name, req, (unsigned long long) dma,

@@ -813,10 +816,17 @@ static void dwc3_prepare_one_trb(struct dwc3_ep *dep,
 		break;
 
 	case USB_ENDPOINT_XFER_ISOC:
-		if (!node)
+		if (!node) {
 			trb->ctrl = DWC3_TRBCTL_ISOCHRONOUS_FIRST;
-		else
+
+			if (speed == USB_SPEED_HIGH) {
+				struct usb_ep *ep = &dep->endpoint;
+				u8 pkts = DIV_ROUND_UP(ep->maxpacket, 1024);
+				trb->size |= DWC3_TRB_SIZE_PCM1(pkts - 1);
+			}
+		} else {
 			trb->ctrl = DWC3_TRBCTL_ISOCHRONOUS;
+		}
 
 		/* always enable Interrupt on Missed ISOC */
 		trb->ctrl |= DWC3_TRB_CTRL_ISP_IMI;

Signature · 6 lines

2.10.0



-- 
balbi

Bin Liu

author

2016-09-22 13:33 UTC #7 lore raw

Quoted 116 lines · Felipe Balbi

Hi,
Quoted 103 lines · Felipe Balbi
Quoted 70 lines · Felipe Balbi
Quoted 69 lines · Bin Liu
Quoted 53 lines · Felipe Balbi
Hi,
Quoted 49 lines · Bin Liu
Hi,
I am trying to check Isoch high bandwidth transfer with g_webcam.ko in
high-speed connection.
First I hacked webcam.c as follows to enable 640x480@30fps mode.
drivers/usb/gadget/legacy/webcam.c +6 -6
index 72c976b..9eb315f 100644
@@ -191,15 +191,15 @@ static const struct UVC_FRAME_UNCOMPRESSED(3) uvc_frame_yuv_360p = {
        .bFrameIndex            = 1,
        .bmCapabilities         = 0,
        .wWidth                 = cpu_to_le16(640),
-       .wHeight                = cpu_to_le16(360),
+       .wHeight                = cpu_to_le16(480),
        .dwMinBitRate           = cpu_to_le32(18432000),
        .dwMaxBitRate           = cpu_to_le32(55296000),
-       .dwMaxVideoFrameBufferSize      = cpu_to_le32(460800),
-       .dwDefaultFrameInterval = cpu_to_le32(666666),
+       .dwMaxVideoFrameBufferSize      = cpu_to_le32(614400),
+       .dwDefaultFrameInterval = cpu_to_le32(333333),
        .bFrameIntervalType     = 3,
-       .dwFrameInterval[0]     = cpu_to_le32(666666),
-       .dwFrameInterval[1]     = cpu_to_le32(1000000),
-       .dwFrameInterval[2]     = cpu_to_le32(5000000),
+       .dwFrameInterval[0]     = cpu_to_le32(333333),
+       .dwFrameInterval[1]     = cpu_to_le32(666666),
+       .dwFrameInterval[2]     = cpu_to_le32(1000000),
 };
 
then loaded g_webcam.ko as
# modprobe g_webcam streaming_maxpacket=3072
The endpoint descriptor showing on the host is
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x8d EP 13 IN
bmAttributes 5
Transfer Type Isochronous
Synch Type Asynchronous
Usage Type Data
wMaxPacketSize 0x1400 3x 1024 bytes
bInterval 1
Quoted 8 lines
Quoted 6 lines
However the usb bus trace shows only one transaction with 1024-bytes packet in every SOF. The host only sends one IN packet in every SOF, I am expecting 2~3 1024-bytes transactions, since this would be required to transfer 640x480@30fps YUV frames in high-speed.
DId I miss anything in the setup?
MUSB or DWC3? This looks like a UDC bug to me. Can you show a screenshot
Happened on both MUSB and DWC3.
Quoted 1 line
of your bus analyzer? When host sends IN token, are you replying with
The trace screenshot on DWC3 is attached.
Quoted 8 lines
Quoted 3 lines
Quoted 1 line
DATA0, DATA1 or DATA2?
Good hint! It is DATA0!
yeah, should've been DATA2. I'll check if we're missing anything for High Bandwidth Iso on DWC3. Can you confirm if it works of tails on DWC3? On your follow-up mail you mentioned it's a bug in MUSB. What about DWC3?
I'm assuming DWC3 really breaks. Here's a patch for that:
8<---------------------------------- cut here ---------------------------------- From 62807011c00055785575bb39d92bfe8836817e2f Mon Sep 17 00:00:00 2001
From: Felipe Balbi <[email protected]>
Date: Thu, 22 Sep 2016 11:01:01 +0300
Subject: [PATCH] usb: dwc3: gadget: set PCM1 field of isochronous-first TRBs
In case of High-Speed, High-Bandwidth endpoints, we need to tell DWC3 that we have more than one packet per interval. We do that by setting PCM1 field of Isochronous-First TRB.
Signed-off-by Felipe Balbi <[email protected]>
---
 drivers/usb/dwc3/gadget.c | 14 ++++++++++++--
 1 file changed, 12 insertions(+), 2 deletions(-)
drivers/usb/dwc3/gadget.c +0 -0
index 602f12254161..106623faf060 100644
drivers/usb/dwc3/gadget.c static void dwc3_prepare_one_trb(struct dwc3_ep *dep, · line 792
Quoted 6 lines
drivers/usb/dwc3/gadget.c +3 -0
@@ -787,6 +787,9 @@ static void dwc3_prepare_one_trb(struct dwc3_ep *dep,
 		unsigned length, unsigned chain, unsigned node)
 {
 	struct dwc3_trb		*trb;
+	struct dwc3		*dwc = dep->dwc;
+	struct usb_gadget	*gadget = &dwc->gadget;
+	enum usb_device_speed	speed = speed;
and of course I sent the wrong version :-)

Quoted 1 line

Here's one that actually compiles, sorry about that.

No worries, I was sleeping ;-)

I will test it out early next week. Thanks.

Regards,
-Bin.

Quoted 58 lines

In case of High-Speed, High-Bandwidth endpoints, we need to tell DWC3 that we have more than one packet per interval. We do that by setting PCM1 field of Isochronous-First TRB.

Signed-off-by Felipe Balbi <[email protected]>

---
 drivers/usb/dwc3/gadget.c | 14 ++++++++++++--
 1 file changed, 12 insertions(+), 2 deletions(-)

drivers/usb/dwc3/gadget.c +12 -2

index 602f12254161..34a7b1bf0522 100644
@@ -787,6 +787,9 @@ static void dwc3_prepare_one_trb(struct dwc3_ep *dep,
 		unsigned length, unsigned chain, unsigned node)
 {
 	struct dwc3_trb		*trb;
+	struct dwc3		*dwc = dep->dwc;
+	struct usb_gadget	*gadget = &dwc->gadget;
+	enum usb_device_speed	speed = gadget->speed;
 
 	dwc3_trace(trace_dwc3_gadget, "%s: req %p dma %08llx length %d%s",
 			dep->name, req, (unsigned long long) dma,

@@ -813,10 +816,17 @@ static void dwc3_prepare_one_trb(struct dwc3_ep *dep,
 		break;
 
 	case USB_ENDPOINT_XFER_ISOC:
-		if (!node)
+		if (!node) {
 			trb->ctrl = DWC3_TRBCTL_ISOCHRONOUS_FIRST;
-		else
+
+			if (speed == USB_SPEED_HIGH) {
+				struct usb_ep *ep = &dep->endpoint;
+				u8 pkts = DIV_ROUND_UP(ep->maxpacket, 1024);
+				trb->size |= DWC3_TRB_SIZE_PCM1(pkts - 1);
+			}
+		} else {
 			trb->ctrl = DWC3_TRBCTL_ISOCHRONOUS;
+		}
 
 		/* always enable Interrupt on Missed ISOC */
 		trb->ctrl |= DWC3_TRB_CTRL_ISP_IMI;

Signature · 6 lines

2.10.0



-- 
balbi

Felipe Balbi

2016-09-22 19:48 UTC #8 lore raw

Hi,

Bin Liu <[email protected]> writes:

[...]

Quoted 5 lines

Quoted 1 line
Here's one that actually compiles, sorry about that.
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.

meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)

Signature · 1 line

balbi

Bin Liu

author

2016-09-22 20:11 UTC #9 lore raw

+Fengwei Yin per his request.

Quoted 9 lines · Felipe Balbi

Hi,
Bin Liu <[email protected]> writes:
[...]
Quoted 2 lines
Quoted 1 line
Here's one that actually compiles, sorry about that.

Quoted 8 lines

Quoted 3 lines
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.
meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)

On device side:
- first patch g_webcam as in my first email in this thread to enable 640x480@30fps;
- # modprobe g_webcam streaming_maxpacket=3072
- then run uvc-gadget to feed the YUV frames;
http://git.ideasonboard.org/uvc-gadget.git

On host side:
- first check the device ep descriptor, which should be

	wMaxPacketSize     0x1400  3x 1024 bytes

- then use luvcview or yavta to capture the video stream

Capture the bus trace to check if multiple IN transactions happens in each SOF.

The data buffer size in the usb_request coming from the uvc driver is 5120 bytes, so there should be 3 IN transactions if the UDC works correctly.

Regards,
-Bin.

yfw

2016-09-23 00:23 UTC #10 lore raw

Hi Bin,

Quoted 1 line · Bin Liu

+Fengwei Yin per his request.

Thanks a lot for adding me to this thread.

Quoted 11 lines · Felipe Balbi

Quoted 9 lines
Hi,
Bin Liu <[email protected]> writes:
[...]
Quoted 2 lines
Quoted 1 line
Here's one that actually compiles, sorry about that.

Quoted 8 lines

Quoted 8 lines
Quoted 3 lines
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.
meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)

I am using similar use case with a different gadget function driver.

Quoted 7 lines

On device side:
- first patch g_webcam as in my first email in this thread to enable 640x480@30fps;
- # modprobe g_webcam streaming_maxpacket=3072
- then run uvc-gadget to feed the YUV frames;
http://git.ideasonboard.org/uvc-gadget.git

- I am using uvc function driver + configfs.
- maxpacket in configfs was set to 3072.
- uvc-gadget from the same source as Bin.

Quoted 5 lines

On host side:
- first check the device ep descriptor, which should be
	wMaxPacketSize     0x1400  3x 1024 bytes
- then use luvcview or yavta to capture the video stream

- lsusb give me same wMaxPacketSize.
- I am using example (changed a little bit) from libuvc.

Regards
Yin, Fengwei

Quoted 11 lines

Capture the bus trace to check if multiple IN transactions happens in each SOF.
The data buffer size in the usb_request coming from the uvc driver is 5120 bytes, so there should be 3 IN transactions if the UDC works correctly.
Regards,
-Bin.

Felipe Balbi

2016-09-23 07:50 UTC #11 lore raw

In reply to Bin Liu

Hi,

Quoted 21 lines · Bin Liu

+Fengwei Yin per his request.
Quoted 9 lines · Felipe Balbi
Hi,
Bin Liu <[email protected]> writes:
[...]
Quoted 2 lines
Quoted 1 line
Here's one that actually compiles, sorry about that.
Quoted 8 lines
Quoted 3 lines
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.
meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)

Quoted 6 lines

On device side:
- first patch g_webcam as in my first email in this thread to enable 640x480@30fps;
- # modprobe g_webcam streaming_maxpacket=3072
- then run uvc-gadget to feed the YUV frames;
http://git.ideasonboard.org/uvc-gadget.git

as is, g_webcam never enumerates to the host. It's calls to usb_function_active() and usb_function_deactivate() are unbalanced. Do you have any other changes to g_webcam?

Also, uvc-gadget.git doesn't compile, had to modify it a bit:

-#include "../drivers/usb/gadget/uvc.h"
+#include "../drivers/usb/gadget/function/uvc.h"

Also fixed a build warning:

@@ -732,6 +732,8 @@ int main(int argc, char *argv[])

                fd_set wfds = fds;
 
                ret = select(dev->fd + 1, NULL, &wfds, &efds, NULL);
+               if (ret < 0)
+                       return ret;
                if (FD_ISSET(dev->fd, &efds))
                        uvc_events_process(dev);
                if (FD_ISSET(dev->fd, &wfds))

Laurent, have you tested g_webcam recently? What's the magic to get it working?

Here's what I get out of dmesg:

[   58.568380] usb 1-9: new high-speed USB device number 5 using xhci_hcd
[   58.738680] usb 1-9: New USB device found, idVendor=1d6b, idProduct=0102
[   58.738683] usb 1-9: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[   58.738685] usb 1-9: Product: Webcam gadget
[   58.738687] usb 1-9: Manufacturer: Linux Foundation
[   58.739133] g_webcam gadget: high-speed config #1: Video
[   58.739138] g_webcam gadget: uvc_function_set_alt(0, 0)
[   58.739139] g_webcam gadget: reset UVC Control
[   58.739149] g_webcam gadget: uvc_function_set_alt(1, 0)
[   58.804369] uvcvideo: Found UVC 1.00 device Webcam gadget (1d6b:0102)
[   58.804479] g_webcam gadget: uvc_function_set_alt(1, 0)
[   64.188459] uvcvideo: UVC non compliance - GET_DEF(PROBE) not supported. Enabling workaround.
[   69.307458] uvcvideo: Failed to query (129) UVC probe control : -110 (exp. 26).
[   69.307459] uvcvideo: Failed to initialize the device (-5).
[   69.307505] usbcore: registered new interface driver uvcvideo
[   69.307506] USB Video Class driver (1.1.1)
[  146.646012] ------------[ cut here ]------------
[  146.646023] WARNING: CPU: 0 PID: 2616 at drivers/usb/gadget/composite.c:371 usb_function_activate+0x77/0x80 [libcomposite]
[  146.646024] Modules linked in: uvcvideo g_webcam usb_f_uvc videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_core videodev libcomposite kvm_intel kvm psmouse e1000e input_leds hid_generic usbhid atkbd irqbypass evdev
[  146.646054] CPU: 0 PID: 2616 Comm: gst-launch-1.0 Not tainted 4.8.0-rc7-next-20160922-00004-gc71031593917-dirty #20
[  146.646055] Hardware name: Intel Corporation Skylake Client platform/Skylake Y LPDDR3 RVP3, BIOS SKLSE2R1.R00.B097.B02.1509020030 09/02/2015
[  146.646058]  ffffc9000769bb70
[  146.646059]  ffffffff8132d415
[  146.646060]  0000000000000000
[  146.646061]  0000000000000000

[  146.646063]  ffffc9000769bbb0
[  146.646063]  ffffffff8105ec1b
[  146.646064]  000001730769bb90
[  146.646066]  00000000ffffffea
[  146.646070]  ffff88016c03a150
[  146.646072]  0000000000000282 ffff88016d793000 ffffc9000769befc
[  146.646077] Call Trace:
[  146.646086]  [<ffffffff8132d415>] dump_stack+0x68/0x93
[  146.646090]  [<ffffffff8105ec1b>] __warn+0xcb/0xf0
[  146.646095]  [<ffffffff8105ed0d>] warn_slowpath_null+0x1d/0x20
[  146.646099]  [<ffffffffa03f1ea7>] usb_function_activate+0x77/0x80 [libcomposite]
[  146.646105]  [<ffffffffa044a6de>] uvc_function_connect+0x1e/0x40 [usb_f_uvc]
[  146.646110]  [<ffffffffa044b2ae>] uvc_v4l2_open+0x6e/0x80 [usb_f_uvc]
[  146.646116]  [<ffffffffa04014f0>] v4l2_open+0xa0/0x100 [videodev]
[  146.646121]  [<ffffffff811a5431>] chrdev_open+0xa1/0x1d0
[  146.646125]  [<ffffffff811a5390>] ? cdev_put+0x30/0x30
[  146.646129]  [<ffffffff8119dd60>] do_dentry_open.isra.17+0x150/0x2e0
[  146.646133]  [<ffffffff8119f115>] vfs_open+0x45/0x60
[  146.646137]  [<ffffffff811af1ed>] path_openat+0x62d/0x1370
[  146.646141]  [<ffffffff811b0094>] ? putname+0x54/0x60
[  146.646146]  [<ffffffff811b10ce>] do_filp_open+0x7e/0xe0
[  146.646150]  [<ffffffff81083868>] ? preempt_count_sub+0x48/0x70
[  146.646154]  [<ffffffff816f07d6>] ? _raw_spin_unlock+0x16/0x30
[  146.646160]  [<ffffffff811bf009>] ? __alloc_fd+0xc9/0x180
[  146.646164]  [<ffffffff8119f4e3>] do_sys_open+0x123/0x200
[  146.646170]  [<ffffffff8119f5de>] SyS_open+0x1e/0x20
[  146.646174]  [<ffffffff816f0e2a>] entry_SYSCALL_64_fastpath+0x18/0xa8
[  146.646177] ---[ end trace 1a4f7b9817d19b04 ]---
[  146.646180] g_webcam gadget: UVC connect failed with -22
[  146.653808] usb 1-9: USB disconnect, device number 5
[  146.653986] g_webcam gadget: UVC disconnect failed with -110

And dwc3 trace:

# tracer: nop
#
# entries-in-buffer/entries-written: 445/445 #P:4
#
# _-----=> irqs-off
# / _----=> need-resched

#                            | / _---=> hardirq/softirq
#                            || / _--=> preempt-depth
#                            ||| /     delay
#           TASK-PID   CPU#  ||||    TIMESTAMP  FUNCTION
#              | |       |   ||||       |         |
        modprobe-2560  [001] ....    35.602546: dwc3_alloc_request: ep0out: req ffff88016c03ad80 length 0/0 zsI ==> 0
        modprobe-2560  [001] ....    35.602566: dwc3_alloc_request: ep0out: req ffff88016c03acc0 length 0/0 zsI ==> 0
        modprobe-2560  [001] d..1    35.602725: dwc3_gadget: Enabling ep0out
        modprobe-2560  [001] d..1    35.602730: dwc3_gadget_ep_cmd: ep0out: cmd 'Start New Configuration' [1033] params 00000000 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.602829: dwc3_gadget_ep_cmd: ep0out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.602920: dwc3_gadget_ep_cmd: ep0in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603012: dwc3_gadget_ep_cmd: ep1out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603104: dwc3_gadget_ep_cmd: ep1in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603195: dwc3_gadget_ep_cmd: ep2out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603286: dwc3_gadget_ep_cmd: ep2in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603378: dwc3_gadget_ep_cmd: ep3out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603470: dwc3_gadget_ep_cmd: ep3in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603561: dwc3_gadget_ep_cmd: ep4out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603652: dwc3_gadget_ep_cmd: ep4in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603744: dwc3_gadget_ep_cmd: ep5out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603836: dwc3_gadget_ep_cmd: ep5in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603927: dwc3_gadget_ep_cmd: ep6out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604018: dwc3_gadget_ep_cmd: ep6in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604110: dwc3_gadget_ep_cmd: ep7out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604202: dwc3_gadget_ep_cmd: ep7in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604294: dwc3_gadget_ep_cmd: ep8out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604386: dwc3_gadget_ep_cmd: ep8in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604477: dwc3_gadget_ep_cmd: ep9out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604568: dwc3_gadget_ep_cmd: ep9in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604660: dwc3_gadget_ep_cmd: ep10out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604751: dwc3_gadget_ep_cmd: ep10in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604843: dwc3_gadget_ep_cmd: ep11out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604934: dwc3_gadget_ep_cmd: ep11in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605026: dwc3_gadget_ep_cmd: ep12out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605117: dwc3_gadget_ep_cmd: ep12in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605209: dwc3_gadget_ep_cmd: ep13out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605301: dwc3_gadget_ep_cmd: ep13in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605392: dwc3_gadget_ep_cmd: ep14out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605483: dwc3_gadget_ep_cmd: ep14in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605575: dwc3_gadget_ep_cmd: ep15out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605667: dwc3_gadget_ep_cmd: ep15in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605941: dwc3_gadget_ep_cmd: ep0out: cmd 'Set Endpoint Configuration' [1025] params 00001000 00000500 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605944: dwc3_gadget: Enabling ep0in
        modprobe-2560  [001] d..1    35.606216: dwc3_gadget_ep_cmd: ep0in: cmd 'Set Endpoint Configuration' [1025] params 00001000 02000500 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.606219: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
        modprobe-2560  [001] d..1    35.606222: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
          v4l_id-2582  [001] d..2    35.607976: dwc3_gadget: gadget g_webcam data soft-connect
          v4l_id-2582  [001] d..2    35.608042: dwc3_gadget: gadget g_webcam data soft-disconnect
      uvc-gadget-2598  [003] d..2    58.206076: dwc3_gadget: gadget g_webcam data soft-connect
     irq/17-dwc3-2578  [001] d..1    58.412564: dwc3_event: event (00000101): Reset [U0]
     irq/17-dwc3-2578  [001] d..1    58.469611: dwc3_event: event (00000201): Connection Done [U0]
     irq/17-dwc3-2578  [001] d..1    58.469622: dwc3_gadget: Enabling ep0out
     irq/17-dwc3-2578  [001] d..1    58.469626: dwc3_gadget_ep_cmd: ep0out: cmd 'Set Endpoint Configuration' [1025] params 80000200 00000500 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.469628: dwc3_gadget: Enabling ep0in
     irq/17-dwc3-2578  [001] d..1    58.469632: dwc3_gadget_ep_cmd: ep0in: cmd 'Set Endpoint Configuration' [1025] params 80000200 02000500 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.568509: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.568512: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.568513: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.568514: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0100 wIndex 0000 wLength 64
     irq/17-dwc3-2578  [001] d..1    58.568515: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.568518: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 18 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.568521: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 000000008025e000 size 18 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.568531: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.568534: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.568535: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.568536: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.568641: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.568642: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.568642: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.568643: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 000000008025e000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.568644: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 18/18 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.568645: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.568645: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.568646: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.568646: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.568653: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.568790: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.568792: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.568793: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.568793: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.568794: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.568798: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.572389: dwc3_event: event (00000101): Reset [U0]
     irq/17-dwc3-2578  [001] d..1    58.625961: dwc3_event: event (00000201): Connection Done [U0]
     irq/17-dwc3-2578  [001] d..1    58.625970: dwc3_gadget: Enabling ep0out
     irq/17-dwc3-2578  [001] d..1    58.625975: dwc3_gadget_ep_cmd: ep0out: cmd 'Set Endpoint Configuration' [1025] params 80000200 00000500 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.625976: dwc3_gadget: Enabling ep0in
     irq/17-dwc3-2578  [001] d..1    58.625980: dwc3_gadget_ep_cmd: ep0in: cmd 'Set Endpoint Configuration' [1025] params 80000200 02000500 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.724491: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.724495: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.724495: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.724496: dwc3_ctrl_req: bRequestType 00 bRequest 05 wValue 0004 wIndex 0000 wLength 0
     irq/17-dwc3-2578  [001] d..1    58.724497: dwc3_ep0: USB_REQ_SET_ADDRESS
     irq/17-dwc3-2578  [001] d..1    58.724501: dwc3_event: event (000020c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.724502: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.724503: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.724504: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c33 (HLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.724508: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.724622: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.724624: dwc3_ep0: ep0in: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.724625: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.724625: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c32 (hLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.724626: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.724630: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.736479: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.736481: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.736481: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.736482: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0100 wIndex 0000 wLength 18
     irq/17-dwc3-2578  [001] d..1    58.736482: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.736484: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 18 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.736487: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 000000008025e800 size 18 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.736498: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.736500: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.736501: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736502: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.736606: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.736608: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736608: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.736609: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 000000008025e800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.736610: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 18/18 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.736611: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.736611: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736612: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.736612: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.736620: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.736750: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.736751: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.736752: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.736752: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.736753: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.736757: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.736851: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.736853: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.736854: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.736854: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0f00 wIndex 0000 wLength 5
     irq/17-dwc3-2578  [001] d..1    58.736855: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.736856: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 5 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.736858: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 000000008025f000 size 5 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.736867: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.736870: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.736870: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736871: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.736979: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.736980: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736981: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.736981: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 000000008025f000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.736982: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 5/5 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.736983: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.736983: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736984: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.736985: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.736992: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737131: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737133: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.737134: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.737134: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737135: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.737142: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737208: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737208: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737209: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.737209: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0f00 wIndex 0000 wLength 22
     irq/17-dwc3-2578  [001] d..1    58.737210: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.737211: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 22 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737213: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 000000008025f800 size 22 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737221: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737223: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737224: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737225: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.737299: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737299: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737300: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.737300: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 000000008025f800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737301: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 22/22 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.737301: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737302: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737302: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.737303: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737306: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737411: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737412: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.737412: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.737412: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737413: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.737416: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737501: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737502: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737502: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.737502: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0200 wIndex 0000 wLength 9
     irq/17-dwc3-2578  [001] d..1    58.737502: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.737504: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 9 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737505: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080260000 size 9 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737514: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737516: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737516: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737517: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.737589: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737589: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737590: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.737590: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080260000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737590: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 9/9 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.737591: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737591: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737591: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.737592: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737596: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737682: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737683: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.737683: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.737683: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737684: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.737688: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737750: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737751: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737751: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.737751: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0200 wIndex 0000 wLength 309
     irq/17-dwc3-2578  [001] d..1    58.737751: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.737752: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 309 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737753: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080260800 size 309 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737763: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737765: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737765: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737766: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.737846: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737846: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737846: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.737846: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080260800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737847: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 309/309 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.737847: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737848: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737848: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.737848: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737852: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737917: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737917: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.737917: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.737918: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737918: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.737922: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737985: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737985: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737986: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.737986: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0300 wIndex 0000 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.737986: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.737986: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 4 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737987: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080261000 size 4 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737991: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737993: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737994: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737994: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.738069: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738069: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738069: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.738070: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080261000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.738070: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 4/4 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.738071: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.738071: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738071: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.738072: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738075: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738180: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738181: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.738181: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.738181: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738181: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.738185: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738248: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738248: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.738249: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.738249: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0302 wIndex 0409 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.738249: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.738250: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 28 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.738251: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080261800 size 28 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.738255: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738257: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.738257: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738258: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.738335: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738336: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738336: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.738336: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080261800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.738337: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 28/28 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.738337: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.738338: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738338: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.738338: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738342: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738423: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738423: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.738424: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.738424: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738425: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.738428: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738491: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738491: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.738492: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.738492: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0301 wIndex 0409 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.738492: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.738493: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 34 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.738494: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080262000 size 34 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.738498: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738500: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.738500: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738501: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.738561: dwc3_event: event (000090c2): ep0in: Transfer Not Ready (Active)
     irq/17-dwc3-2578  [001] d..1    58.738562: dwc3_ep0: ep0in: Transfer Not Ready (Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738562: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738563: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738563: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.738563: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080262000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.738564: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 34/34 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.738564: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.738564: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738565: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.738565: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738569: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738674: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738675: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.738675: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.738676: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738676: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.738680: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739128: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739129: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739130: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.739130: dwc3_ctrl_req: bRequestType 00 bRequest 09 wValue 0001 wIndex 0000 wLength 0
     irq/17-dwc3-2578  [001] d..1    58.739131: dwc3_ep0: USB_REQ_SET_CONFIGURATION
     irq/17-dwc3-2578  [001] d..2    58.739141: dwc3_gadget: Enabling ep1in
     irq/17-dwc3-2578  [001] d..2    58.739145: dwc3_gadget_ep_cmd: ep1in: cmd 'Set Endpoint Configuration' [1025] params 00020086 06070200 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739150: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 0 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739159: dwc3_event: event (000020c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.739160: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739160: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.739161: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c33 (HLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.739165: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739241: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739242: dwc3_ep0: ep0in: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.739242: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.739243: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c32 (hLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.739244: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 0/0 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.739245: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.739248: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739313: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739314: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739314: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.739315: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0304 wIndex 0409 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.739315: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.739316: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 12 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739318: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080262800 size 12 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.739326: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739329: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.739330: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739330: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.739400: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739401: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739402: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.739402: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080262800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.739403: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 12/12 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.739404: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.739405: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739405: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.739406: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.739412: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739509: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739510: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.739511: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.739512: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.739512: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.739516: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739660: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739661: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739661: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.739662: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0305 wIndex 0409 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.739662: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.739664: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 22 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739666: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080263000 size 22 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.739672: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739674: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.739675: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739676: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.739753: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739754: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739754: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.739755: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080263000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.739756: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 22/22 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.739757: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.739757: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739758: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.739758: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.739765: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739863: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739864: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.739864: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.739865: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.739866: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.739870: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739976: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739981: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739981: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.739984: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0306 wIndex 0409 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.739986: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.739987: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 32 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739989: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080263800 size 32 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.740000: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.740002: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.740003: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.740004: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.740066: dwc3_event: event (000090c2): ep0in: Transfer Not Ready (Active)
     irq/17-dwc3-2578  [001] d..1    58.740067: dwc3_ep0: ep0in: Transfer Not Ready (Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.740068: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.740069: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.740069: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.740070: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080263800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.740071: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 32/32 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.740071: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.740072: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.740072: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.740073: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.740077: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.740177: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.740178: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.740179: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.740180: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.740180: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.740189: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.804470: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.804473: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.804475: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.804475: dwc3_ctrl_req: bRequestType 01 bRequest 0b wValue 0000 wIndex 0001 wLength 0
     irq/17-dwc3-2578  [001] d..1    58.804476: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.804482: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 0 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.804486: dwc3_event: event (000020c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.804487: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.804488: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.804489: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c33 (HLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.804495: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.804583: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.804584: dwc3_ep0: ep0in: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.804584: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.804585: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c32 (hLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.804586: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 0/0 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.804587: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.804596: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.804660: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.804661: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.804661: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.804661: dwc3_ctrl_req: bRequestType a1 bRequest 87 wValue 0100 wIndex 0001 wLength 26
     irq/17-dwc3-2578  [001] d..1    58.804663: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.804663: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.804664: dwc3_ep0: Control Data

uvc-gadget keeps printing this error message:

159 if ((ret = ioctl(dev->fd, VIDIOC_DQBUF, &buf)) < 0) {
160 printf("Unable to dequeue buffer: %s (%d).\n", strerror(errno),
161 errno);
162 return ret;
163 }

Any ideas?

Signature · 1 line

balbi

yfw

2016-09-23 08:17 UTC #12 lore raw

Hi Felipe,

Quoted 32 lines · Felipe Balbi

Hi,
Quoted 21 lines · Bin Liu
+Fengwei Yin per his request.
Quoted 9 lines · Felipe Balbi
Hi,
Bin Liu <[email protected]> writes:
[...]
Quoted 2 lines
Quoted 1 line
Here's one that actually compiles, sorry about that.
Quoted 8 lines
Quoted 3 lines
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.
meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)
Quoted 6 lines
On device side:
- first patch g_webcam as in my first email in this thread to enable 640x480@30fps;
- # modprobe g_webcam streaming_maxpacket=3072
- then run uvc-gadget to feed the YUV frames;
http://git.ideasonboard.org/uvc-gadget.git

Quoted 3 lines

as is, g_webcam never enumerates to the host. It's calls to usb_function_active() and usb_function_deactivate() are unbalanced. Do you have any other changes to g_webcam?

With uvc function gadget driver, user daemon uvc-gadget must be started before connect to host. Not sure whether g_webcam has same requirement.

Quoted 23 lines

Also, uvc-gadget.git doesn't compile, had to modify it a bit:

(quoted diff) +1 -1

-#include "../drivers/usb/gadget/uvc.h"
+#include "../drivers/usb/gadget/function/uvc.h"

Also fixed a build warning:

(quoted diff) +2 -0

@@ -732,6 +732,8 @@ int main(int argc, char *argv[])
                fd_set wfds = fds;
 
                ret = select(dev->fd + 1, NULL, &wfds, &efds, NULL);
+               if (ret < 0)
+                       return ret;
                if (FD_ISSET(dev->fd, &efds))
                        uvc_events_process(dev);
                if (FD_ISSET(dev->fd, &wfds))

Laurent, have you tested g_webcam recently? What's the magic to get it working?

Here's what I get out of dmesg:

Quoted 12 lines

[   58.568380] usb 1-9: new high-speed USB device number 5 using xhci_hcd
[   58.738680] usb 1-9: New USB device found, idVendor=1d6b, idProduct=0102
[   58.738683] usb 1-9: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[   58.738685] usb 1-9: Product: Webcam gadget
[   58.738687] usb 1-9: Manufacturer: Linux Foundation
[   58.739133] g_webcam gadget: high-speed config #1: Video
[   58.739138] g_webcam gadget: uvc_function_set_alt(0, 0)
[   58.739139] g_webcam gadget: reset UVC Control
[   58.739149] g_webcam gadget: uvc_function_set_alt(1, 0)
[   58.804369] uvcvideo: Found UVC 1.00 device Webcam gadget (1d6b:0102)
[   58.804479] g_webcam gadget: uvc_function_set_alt(1, 0)
[   64.188459] uvcvideo: UVC non compliance - GET_DEF(PROBE) not supported. Enabling workaround.

Looks like you connect your usb device to your usb host port on same board. Nice.

The GET_DEF is handled by user daemon uvc-gadget. It may be related.

Regards
Yin, Fengwei

Quoted 516 lines

[   69.307458] uvcvideo: Failed to query (129) UVC probe control : -110 (exp. 26).
[   69.307459] uvcvideo: Failed to initialize the device (-5).
[   69.307505] usbcore: registered new interface driver uvcvideo
[   69.307506] USB Video Class driver (1.1.1)
[  146.646012] ------------[ cut here ]------------
[  146.646023] WARNING: CPU: 0 PID: 2616 at drivers/usb/gadget/composite.c:371 usb_function_activate+0x77/0x80 [libcomposite]
[  146.646024] Modules linked in: uvcvideo g_webcam usb_f_uvc videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_core videodev libcomposite kvm_intel kvm psmouse e1000e input_leds hid_generic usbhid atkbd irqbypass evdev
[  146.646054] CPU: 0 PID: 2616 Comm: gst-launch-1.0 Not tainted 4.8.0-rc7-next-20160922-00004-gc71031593917-dirty #20
[  146.646055] Hardware name: Intel Corporation Skylake Client platform/Skylake Y LPDDR3 RVP3, BIOS SKLSE2R1.R00.B097.B02.1509020030 09/02/2015
[  146.646058]  ffffc9000769bb70
[  146.646059]  ffffffff8132d415
[  146.646060]  0000000000000000
[  146.646061]  0000000000000000

[  146.646063]  ffffc9000769bbb0
[  146.646063]  ffffffff8105ec1b
[  146.646064]  000001730769bb90
[  146.646066]  00000000ffffffea
[  146.646070]  ffff88016c03a150
[  146.646072]  0000000000000282 ffff88016d793000 ffffc9000769befc
[  146.646077] Call Trace:
[  146.646086]  [<ffffffff8132d415>] dump_stack+0x68/0x93
[  146.646090]  [<ffffffff8105ec1b>] __warn+0xcb/0xf0
[  146.646095]  [<ffffffff8105ed0d>] warn_slowpath_null+0x1d/0x20
[  146.646099]  [<ffffffffa03f1ea7>] usb_function_activate+0x77/0x80 [libcomposite]
[  146.646105]  [<ffffffffa044a6de>] uvc_function_connect+0x1e/0x40 [usb_f_uvc]
[  146.646110]  [<ffffffffa044b2ae>] uvc_v4l2_open+0x6e/0x80 [usb_f_uvc]
[  146.646116]  [<ffffffffa04014f0>] v4l2_open+0xa0/0x100 [videodev]
[  146.646121]  [<ffffffff811a5431>] chrdev_open+0xa1/0x1d0
[  146.646125]  [<ffffffff811a5390>] ? cdev_put+0x30/0x30
[  146.646129]  [<ffffffff8119dd60>] do_dentry_open.isra.17+0x150/0x2e0
[  146.646133]  [<ffffffff8119f115>] vfs_open+0x45/0x60
[  146.646137]  [<ffffffff811af1ed>] path_openat+0x62d/0x1370
[  146.646141]  [<ffffffff811b0094>] ? putname+0x54/0x60
[  146.646146]  [<ffffffff811b10ce>] do_filp_open+0x7e/0xe0
[  146.646150]  [<ffffffff81083868>] ? preempt_count_sub+0x48/0x70
[  146.646154]  [<ffffffff816f07d6>] ? _raw_spin_unlock+0x16/0x30
[  146.646160]  [<ffffffff811bf009>] ? __alloc_fd+0xc9/0x180
[  146.646164]  [<ffffffff8119f4e3>] do_sys_open+0x123/0x200
[  146.646170]  [<ffffffff8119f5de>] SyS_open+0x1e/0x20
[  146.646174]  [<ffffffff816f0e2a>] entry_SYSCALL_64_fastpath+0x18/0xa8
[  146.646177] ---[ end trace 1a4f7b9817d19b04 ]---
[  146.646180] g_webcam gadget: UVC connect failed with -22
[  146.653808] usb 1-9: USB disconnect, device number 5
[  146.653986] g_webcam gadget: UVC disconnect failed with -110

And dwc3 trace:

# tracer: nop
#
# entries-in-buffer/entries-written: 445/445 #P:4
#
# _-----=> irqs-off
# / _----=> need-resched

#                            | / _---=> hardirq/softirq
#                            || / _--=> preempt-depth
#                            ||| /     delay
#           TASK-PID   CPU#  ||||    TIMESTAMP  FUNCTION
#              | |       |   ||||       |         |
        modprobe-2560  [001] ....    35.602546: dwc3_alloc_request: ep0out: req ffff88016c03ad80 length 0/0 zsI ==> 0
        modprobe-2560  [001] ....    35.602566: dwc3_alloc_request: ep0out: req ffff88016c03acc0 length 0/0 zsI ==> 0
        modprobe-2560  [001] d..1    35.602725: dwc3_gadget: Enabling ep0out
        modprobe-2560  [001] d..1    35.602730: dwc3_gadget_ep_cmd: ep0out: cmd 'Start New Configuration' [1033] params 00000000 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.602829: dwc3_gadget_ep_cmd: ep0out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.602920: dwc3_gadget_ep_cmd: ep0in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603012: dwc3_gadget_ep_cmd: ep1out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603104: dwc3_gadget_ep_cmd: ep1in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603195: dwc3_gadget_ep_cmd: ep2out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603286: dwc3_gadget_ep_cmd: ep2in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603378: dwc3_gadget_ep_cmd: ep3out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603470: dwc3_gadget_ep_cmd: ep3in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603561: dwc3_gadget_ep_cmd: ep4out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603652: dwc3_gadget_ep_cmd: ep4in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603744: dwc3_gadget_ep_cmd: ep5out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603836: dwc3_gadget_ep_cmd: ep5in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.603927: dwc3_gadget_ep_cmd: ep6out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604018: dwc3_gadget_ep_cmd: ep6in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604110: dwc3_gadget_ep_cmd: ep7out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604202: dwc3_gadget_ep_cmd: ep7in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604294: dwc3_gadget_ep_cmd: ep8out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604386: dwc3_gadget_ep_cmd: ep8in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604477: dwc3_gadget_ep_cmd: ep9out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604568: dwc3_gadget_ep_cmd: ep9in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604660: dwc3_gadget_ep_cmd: ep10out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604751: dwc3_gadget_ep_cmd: ep10in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604843: dwc3_gadget_ep_cmd: ep11out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.604934: dwc3_gadget_ep_cmd: ep11in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605026: dwc3_gadget_ep_cmd: ep12out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605117: dwc3_gadget_ep_cmd: ep12in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605209: dwc3_gadget_ep_cmd: ep13out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605301: dwc3_gadget_ep_cmd: ep13in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605392: dwc3_gadget_ep_cmd: ep14out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605483: dwc3_gadget_ep_cmd: ep14in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605575: dwc3_gadget_ep_cmd: ep15out: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605667: dwc3_gadget_ep_cmd: ep15in: cmd 'Set Endpoint Transfer Resource' [1026] params 00000001 00000000 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605941: dwc3_gadget_ep_cmd: ep0out: cmd 'Set Endpoint Configuration' [1025] params 00001000 00000500 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.605944: dwc3_gadget: Enabling ep0in
        modprobe-2560  [001] d..1    35.606216: dwc3_gadget_ep_cmd: ep0in: cmd 'Set Endpoint Configuration' [1025] params 00001000 02000500 00000000 --> status: Successful
        modprobe-2560  [001] d..1    35.606219: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
        modprobe-2560  [001] d..1    35.606222: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
          v4l_id-2582  [001] d..2    35.607976: dwc3_gadget: gadget g_webcam data soft-connect
          v4l_id-2582  [001] d..2    35.608042: dwc3_gadget: gadget g_webcam data soft-disconnect
      uvc-gadget-2598  [003] d..2    58.206076: dwc3_gadget: gadget g_webcam data soft-connect
     irq/17-dwc3-2578  [001] d..1    58.412564: dwc3_event: event (00000101): Reset [U0]
     irq/17-dwc3-2578  [001] d..1    58.469611: dwc3_event: event (00000201): Connection Done [U0]
     irq/17-dwc3-2578  [001] d..1    58.469622: dwc3_gadget: Enabling ep0out
     irq/17-dwc3-2578  [001] d..1    58.469626: dwc3_gadget_ep_cmd: ep0out: cmd 'Set Endpoint Configuration' [1025] params 80000200 00000500 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.469628: dwc3_gadget: Enabling ep0in
     irq/17-dwc3-2578  [001] d..1    58.469632: dwc3_gadget_ep_cmd: ep0in: cmd 'Set Endpoint Configuration' [1025] params 80000200 02000500 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.568509: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.568512: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.568513: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.568514: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0100 wIndex 0000 wLength 64
     irq/17-dwc3-2578  [001] d..1    58.568515: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.568518: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 18 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.568521: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 000000008025e000 size 18 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.568531: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.568534: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.568535: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.568536: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.568641: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.568642: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.568642: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.568643: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 000000008025e000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.568644: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 18/18 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.568645: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.568645: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.568646: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.568646: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.568653: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.568790: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.568792: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.568793: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.568793: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.568794: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.568798: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.572389: dwc3_event: event (00000101): Reset [U0]
     irq/17-dwc3-2578  [001] d..1    58.625961: dwc3_event: event (00000201): Connection Done [U0]
     irq/17-dwc3-2578  [001] d..1    58.625970: dwc3_gadget: Enabling ep0out
     irq/17-dwc3-2578  [001] d..1    58.625975: dwc3_gadget_ep_cmd: ep0out: cmd 'Set Endpoint Configuration' [1025] params 80000200 00000500 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.625976: dwc3_gadget: Enabling ep0in
     irq/17-dwc3-2578  [001] d..1    58.625980: dwc3_gadget_ep_cmd: ep0in: cmd 'Set Endpoint Configuration' [1025] params 80000200 02000500 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.724491: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.724495: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.724495: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.724496: dwc3_ctrl_req: bRequestType 00 bRequest 05 wValue 0004 wIndex 0000 wLength 0
     irq/17-dwc3-2578  [001] d..1    58.724497: dwc3_ep0: USB_REQ_SET_ADDRESS
     irq/17-dwc3-2578  [001] d..1    58.724501: dwc3_event: event (000020c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.724502: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.724503: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.724504: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c33 (HLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.724508: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.724622: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.724624: dwc3_ep0: ep0in: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.724625: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.724625: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c32 (hLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.724626: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.724630: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.736479: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.736481: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.736481: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.736482: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0100 wIndex 0000 wLength 18
     irq/17-dwc3-2578  [001] d..1    58.736482: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.736484: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 18 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.736487: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 000000008025e800 size 18 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.736498: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.736500: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.736501: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736502: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.736606: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.736608: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736608: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.736609: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 000000008025e800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.736610: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 18/18 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.736611: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.736611: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736612: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.736612: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.736620: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.736750: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.736751: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.736752: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.736752: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.736753: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.736757: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.736851: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.736853: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.736854: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.736854: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0f00 wIndex 0000 wLength 5
     irq/17-dwc3-2578  [001] d..1    58.736855: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.736856: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 5 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.736858: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 000000008025f000 size 5 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.736867: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.736870: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.736870: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736871: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.736979: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.736980: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736981: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.736981: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 000000008025f000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.736982: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 5/5 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.736983: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.736983: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.736984: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.736985: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.736992: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737131: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737133: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.737134: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.737134: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737135: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.737142: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737208: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737208: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737209: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.737209: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0f00 wIndex 0000 wLength 22
     irq/17-dwc3-2578  [001] d..1    58.737210: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.737211: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 22 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737213: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 000000008025f800 size 22 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737221: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737223: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737224: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737225: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.737299: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737299: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737300: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.737300: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 000000008025f800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737301: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 22/22 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.737301: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737302: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737302: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.737303: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737306: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737411: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737412: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.737412: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.737412: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737413: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.737416: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737501: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737502: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737502: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.737502: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0200 wIndex 0000 wLength 9
     irq/17-dwc3-2578  [001] d..1    58.737502: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.737504: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 9 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737505: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080260000 size 9 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737514: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737516: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737516: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737517: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.737589: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737589: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737590: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.737590: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080260000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737590: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 9/9 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.737591: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737591: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737591: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.737592: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737596: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737682: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737683: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.737683: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.737683: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737684: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.737688: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737750: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737751: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737751: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.737751: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0200 wIndex 0000 wLength 309
     irq/17-dwc3-2578  [001] d..1    58.737751: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.737752: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 309 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737753: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080260800 size 309 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737763: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737765: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737765: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737766: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.737846: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737846: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737846: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.737846: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080260800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737847: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 309/309 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.737847: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737848: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737848: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.737848: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737852: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737917: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737917: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.737917: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.737918: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.737918: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.737922: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737985: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.737985: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737986: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.737986: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0300 wIndex 0000 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.737986: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.737986: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 4 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.737987: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080261000 size 4 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.737991: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.737993: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.737994: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.737994: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.738069: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738069: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738069: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.738070: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080261000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.738070: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 4/4 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.738071: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.738071: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738071: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.738072: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738075: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738180: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738181: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.738181: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.738181: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738181: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.738185: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738248: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738248: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.738249: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.738249: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0302 wIndex 0409 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.738249: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.738250: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 28 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.738251: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080261800 size 28 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.738255: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738257: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.738257: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738258: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.738335: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738336: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738336: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.738336: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080261800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.738337: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 28/28 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.738337: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.738338: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738338: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.738338: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738342: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738423: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738423: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.738424: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.738424: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738425: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.738428: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738491: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738491: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.738492: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.738492: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0301 wIndex 0409 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.738492: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.738493: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 34 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.738494: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080262000 size 34 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.738498: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738500: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.738500: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738501: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.738561: dwc3_event: event (000090c2): ep0in: Transfer Not Ready (Active)
     irq/17-dwc3-2578  [001] d..1    58.738562: dwc3_ep0: ep0in: Transfer Not Ready (Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738562: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738563: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738563: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.738563: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080262000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.738564: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 34/34 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.738564: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.738564: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.738565: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.738565: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738569: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.738674: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.738675: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.738675: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.738676: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.738676: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.738680: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739128: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739129: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739130: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.739130: dwc3_ctrl_req: bRequestType 00 bRequest 09 wValue 0001 wIndex 0000 wLength 0
     irq/17-dwc3-2578  [001] d..1    58.739131: dwc3_ep0: USB_REQ_SET_CONFIGURATION
     irq/17-dwc3-2578  [001] d..2    58.739141: dwc3_gadget: Enabling ep1in
     irq/17-dwc3-2578  [001] d..2    58.739145: dwc3_gadget_ep_cmd: ep1in: cmd 'Set Endpoint Configuration' [1025] params 00020086 06070200 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739150: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 0 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739159: dwc3_event: event (000020c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.739160: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739160: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.739161: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c33 (HLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.739165: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739241: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739242: dwc3_ep0: ep0in: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.739242: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.739243: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c32 (hLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.739244: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 0/0 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.739245: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.739248: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739313: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739314: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739314: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.739315: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0304 wIndex 0409 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.739315: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.739316: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 12 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739318: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080262800 size 12 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.739326: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739329: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.739330: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739330: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.739400: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739401: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739402: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.739402: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080262800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.739403: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 12/12 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.739404: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.739405: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739405: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.739406: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.739412: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739509: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739510: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.739511: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.739512: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.739512: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.739516: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739660: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739661: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739661: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.739662: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0305 wIndex 0409 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.739662: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.739664: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 22 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739666: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080263000 size 22 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.739672: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739674: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.739675: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739676: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.739753: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739754: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739754: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.739755: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080263000 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.739756: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 22/22 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.739757: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.739757: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.739758: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.739758: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.739765: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739863: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739864: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.739864: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.739865: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.739866: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.739870: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.739976: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.739981: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739981: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.739984: dwc3_ctrl_req: bRequestType 80 bRequest 06 wValue 0306 wIndex 0409 wLength 255
     irq/17-dwc3-2578  [001] d..1    58.739986: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.739987: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 32 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.739989: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000080263800 size 32 ctrl 00000c53 (HLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.740000: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.740002: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.740003: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.740004: dwc3_ep0: Control Data
     irq/17-dwc3-2578  [001] d..1    58.740066: dwc3_event: event (000090c2): ep0in: Transfer Not Ready (Active)
     irq/17-dwc3-2578  [001] d..1    58.740067: dwc3_ep0: ep0in: Transfer Not Ready (Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.740068: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.740069: dwc3_ep0: ep0in: Transfer Complete: state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.740069: dwc3_ep0: Data Phase
     irq/17-dwc3-2578  [001] d..1    58.740070: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000080263800 size 0 ctrl 00000c52 (hLcs:SC:data)
     irq/17-dwc3-2578  [001] d..1    58.740071: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 32/32 ZsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.740071: dwc3_event: event (000020c0): ep0out: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.740072: dwc3_ep0: ep0out: Transfer Not Ready (Not Active): state 'Data Phase'
     irq/17-dwc3-2578  [001] d..1    58.740072: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.740073: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c43 (HLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.740077: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.740177: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.740178: dwc3_ep0: ep0out: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.740179: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.740180: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c42 (hLcs:SC:status3)
     irq/17-dwc3-2578  [001] d..1    58.740180: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.740189: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.804470: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.804473: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.804475: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.804475: dwc3_ctrl_req: bRequestType 01 bRequest 0b wValue 0000 wIndex 0001 wLength 0
     irq/17-dwc3-2578  [001] d..1    58.804476: dwc3_ep0: Forwarding to gadget driver
     irq/17-dwc3-2578  [001] d..1    58.804482: dwc3_ep0: queueing request ffff88016c03ad80 to ep0out length 0 state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.804486: dwc3_event: event (000020c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.804487: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.804488: dwc3_ep0: Control Status
     irq/17-dwc3-2578  [001] d..1    58.804489: dwc3_prepare_trb: ep0in: 0/0 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c33 (HLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.804495: dwc3_gadget_ep_cmd: ep0in: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.804583: dwc3_event: event (0000c042): ep0in: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.804584: dwc3_ep0: ep0in: Transfer Complete: state 'Status Phase'
     irq/17-dwc3-2578  [001] d..1    58.804584: dwc3_ep0: Status Phase
     irq/17-dwc3-2578  [001] d..1    58.804585: dwc3_complete_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 0 ctrl 00000c32 (hLcs:SC:status2)
     irq/17-dwc3-2578  [001] d..1    58.804586: dwc3_gadget_giveback: ep0out: req ffff88016c03ad80 length 0/0 zsI ==> 0
     irq/17-dwc3-2578  [001] d..1    58.804587: dwc3_prepare_trb: ep0out: 0/2 trb ffff880084c02000 buf 0000000084c01000 size 8 ctrl 00000c23 (HLcs:SC:setup)
     irq/17-dwc3-2578  [001] d..1    58.804596: dwc3_gadget_ep_cmd: ep0out: cmd 'Start Transfer' [1030] params 00000000 84c02000 00000000 --> status: Successful
     irq/17-dwc3-2578  [001] d..1    58.804660: dwc3_event: event (0000c040): ep0out: Transfer Complete
     irq/17-dwc3-2578  [001] d..1    58.804661: dwc3_ep0: ep0out: Transfer Complete: state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.804661: dwc3_ep0: Setup Phase
     irq/17-dwc3-2578  [001] d..1    58.804661: dwc3_ctrl_req: bRequestType a1 bRequest 87 wValue 0100 wIndex 0001 wLength 26
     irq/17-dwc3-2578  [001] d..1    58.804663: dwc3_event: event (000010c2): ep0in: Transfer Not Ready (Not Active)
     irq/17-dwc3-2578  [001] d..1    58.804663: dwc3_ep0: ep0in: Transfer Not Ready (Not Active): state 'Setup Phase'
     irq/17-dwc3-2578  [001] d..1    58.804664: dwc3_ep0: Control Data

uvc-gadget keeps printing this error message:

159 if ((ret = ioctl(dev->fd, VIDIOC_DQBUF, &buf)) < 0) {
160 printf("Unable to dequeue buffer: %s (%d).\n", strerror(errno),
161 errno);
162 return ret;
163 }

Any ideas?

Felipe Balbi

2016-09-23 08:27 UTC #13 lore raw

Hi,

Quoted 18 lines · yfw

Quoted 18 lines
Quoted 17 lines
Quoted 10 lines
Quoted 5 lines
Quoted 1 line
Here's one that actually compiles, sorry about that.
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.
meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)
On device side:
- first patch g_webcam as in my first email in this thread to enable 640x480@30fps;
- # modprobe g_webcam streaming_maxpacket=3072
- then run uvc-gadget to feed the YUV frames;
http://git.ideasonboard.org/uvc-gadget.git

Quoted 5 lines

Quoted 3 lines
as is, g_webcam never enumerates to the host. It's calls to usb_function_active() and usb_function_deactivate() are unbalanced. Do you have any other changes to g_webcam?
With uvc function gadget driver, user daemon uvc-gadget must be started before connect to host. Not sure whether g_webcam has same requirement.

f_uvc.c should be handling that by means for usb_function_deactivate().

I'll try keeping cable disconnected until uvc-gadget is running.

Quoted 22 lines

Also, uvc-gadget.git doesn't compile, had to modify it a bit:

(quoted diff) +1 -1

-#include "../drivers/usb/gadget/uvc.h"
+#include "../drivers/usb/gadget/function/uvc.h"

Also fixed a build warning:

(quoted diff) +2 -0

@@ -732,6 +732,8 @@ int main(int argc, char *argv[])
                fd_set wfds = fds;
 
                ret = select(dev->fd + 1, NULL, &wfds, &efds, NULL);
+               if (ret < 0)
+                       return ret;
                if (FD_ISSET(dev->fd, &efds))
                        uvc_events_process(dev);
                if (FD_ISSET(dev->fd, &wfds))

Laurent, have you tested g_webcam recently? What's the magic to get it working?

Here's what I get out of dmesg:

Quoted 14 lines

Quoted 4 lines

[   58.568380] usb 1-9: new high-speed USB device number 5 using xhci_hcd
[   58.738680] usb 1-9: New USB device found, idVendor=1d6b, idProduct=0102
[   58.738683] usb 1-9: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[   58.738685] usb 1-9: Product: Webcam gadget

Quoted 8 lines

[   58.738687] usb 1-9: Manufacturer: Linux Foundation
[   58.739133] g_webcam gadget: high-speed config #1: Video
[   58.739138] g_webcam gadget: uvc_function_set_alt(0, 0)
[   58.739139] g_webcam gadget: reset UVC Control
[   58.739149] g_webcam gadget: uvc_function_set_alt(1, 0)
[   58.804369] uvcvideo: Found UVC 1.00 device Webcam gadget (1d6b:0102)
[   58.804479] g_webcam gadget: uvc_function_set_alt(1, 0)
[   64.188459] uvcvideo: UVC non compliance - GET_DEF(PROBE) not supported. Enabling workaround.

Looks like you connect your usb device to your usb host port on same board. Nice.

yeah, that helps.

Quoted 1 line

The GET_DEF is handled by user daemon uvc-gadget. It may be related.

okay.

Signature · 1 line

balbi

Laurent Pinchart

2016-09-26 16:06 UTC #14 lore raw

Hi Felipe,

Quoted 23 lines · On Friday 23 Sep 2016 11:27:26 Felipe Balbi wrote:

Quoted 22 lines · yfw
Quoted 21 lines
Quoted 11 lines
Quoted 10 lines
Quoted 5 lines
Quoted 1 line
Here's one that actually compiles, sorry about that.
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.
meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)
Quoted 6 lines
On device side:
- first patch g_webcam as in my first email in this thread to enable 640x480@30fps;
- # modprobe g_webcam streaming_maxpacket=3072
- then run uvc-gadget to feed the YUV frames;
http://git.ideasonboard.org/uvc-gadget.git
as is, g_webcam never enumerates to the host. It's calls to usb_function_active() and usb_function_deactivate() are unbalanced. Do you have any other changes to g_webcam?

Quoted 6 lines

Quoted 2 lines
With uvc function gadget driver, user daemon uvc-gadget must be started before connect to host. Not sure whether g_webcam has same requirement.
f_uvc.c should be handling that by means for usb_function_deactivate().
I'll try keeping cable disconnected until uvc-gadget is running.

Things might have changed since we've discussed the issue several years ago, but back then at least the musb UDC started unconditionally connected.

Signature · 3 lines

Regards,

Laurent Pinchart

Felipe Balbi

2016-09-27 07:10 UTC #15 lore raw

Hi,

Quoted 29 lines · Laurent Pinchart

Hi Felipe,
Quoted 26 lines · On Friday 23 Sep 2016 11:27:26 Felipe Balbi wrote:
Quoted 24 lines · yfw
Quoted 18 lines
Quoted 11 lines
Quoted 10 lines
Quoted 5 lines
Quoted 1 line
Here's one that actually compiles, sorry about that.
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.
meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)
Quoted 6 lines
On device side:
- first patch g_webcam as in my first email in this thread to enable 640x480@30fps;
- # modprobe g_webcam streaming_maxpacket=3072
- then run uvc-gadget to feed the YUV frames;
http://git.ideasonboard.org/uvc-gadget.git
Quoted 3 lines
as is, g_webcam never enumerates to the host. It's calls to usb_function_active() and usb_function_deactivate() are unbalanced. Do you have any other changes to g_webcam?
With uvc function gadget driver, user daemon uvc-gadget must be started before connect to host. Not sure whether g_webcam has same requirement.

Quoted 6 lines

Quoted 3 lines
f_uvc.c should be handling that by means for usb_function_deactivate().
I'll try keeping cable disconnected until uvc-gadget is running.
Things might have changed since we've discussed the issue several years ago, but back then at least the musb UDC started unconditionally connected.

That's kinda of not the case anymore. We deactivate during function registration if a certain flag is set.

Signature · 1 line

balbi

Bin Liu

author

2016-09-26 14:02 UTC #16 lore raw

In reply to Felipe Balbi

Quoted 25 lines · Felipe Balbi

Hi,
Quoted 21 lines · Bin Liu
+Fengwei Yin per his request.
Quoted 17 lines · Felipe Balbi
Hi,
Bin Liu <[email protected]> writes:
[...]
Quoted 5 lines
Quoted 1 line
Here's one that actually compiles, sorry about that.
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.
meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)

Quoted 8 lines

Quoted 6 lines
On device side:
- first patch g_webcam as in my first email in this thread to enable 640x480@30fps;
- # modprobe g_webcam streaming_maxpacket=3072
- then run uvc-gadget to feed the YUV frames;
http://git.ideasonboard.org/uvc-gadget.git
as is, g_webcam never enumerates to the host. It's calls to

Right, on mainline kernel (I tested 4.8.0-rc7) g_webcam is broken with DWC3, g_webcam does not enumerate on the host. But it works on v4.4.21.

[snip]

Quoted 8 lines

uvc-gadget keeps printing this error message:
159 if ((ret = ioctl(dev->fd, VIDIOC_DQBUF, &buf)) < 0) {
160 printf("Unable to dequeue buffer: %s (%d).\n", strerror(errno),
161 errno);
162 return ret;
163 }

I removed this printf, since it floods the console if start uvc-gadget before connect to the host.

BTY, you don't have to start uvc-gadget first then connect usb cable. I keep the cable always connected.

Regards,
-Bin.

Felipe Balbi

2016-09-27 07:14 UTC #17 lore raw

Hi,

Quoted 33 lines · Bin Liu

Quoted 32 lines · Felipe Balbi
Hi,
Quoted 27 lines · Bin Liu
+Fengwei Yin per his request.
Quoted 9 lines · Felipe Balbi
Hi,
Bin Liu <[email protected]> writes:
[...]
Quoted 2 lines
Quoted 1 line
Here's one that actually compiles, sorry about that.
Quoted 8 lines
Quoted 3 lines
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.
meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)
On device side:
- first patch g_webcam as in my first email in this thread to enable 640x480@30fps;
- # modprobe g_webcam streaming_maxpacket=3072
- then run uvc-gadget to feed the YUV frames;
http://git.ideasonboard.org/uvc-gadget.git

Quoted 4 lines

Quoted 1 line
as is, g_webcam never enumerates to the host. It's calls to
Right, on mainline kernel (I tested 4.8.0-rc7) g_webcam is broken with DWC3, g_webcam does not enumerate on the host. But it works on v4.4.21.

There aren't that many changes related to g_webcam though.

$ git log --oneline --no-merges v4.4..HEAD -- drivers/usb/gadget/function/f_uvc.c drivers/usb/gadget/legacy/webcam.c drivers/usb/gadget/function/uvc*
4fbac5206afd usb: gadget: uvc: Add missing call for additional setup data
bd610c5aa9fc usb: gadget: uvc: Fix return value in case of error
36c0f8b32c4b [media] vb2: replace void *alloc_ctxs by struct device *alloc_devs
1ae1602de028 configfs: switch ->default groups to a linked list
d6dd645eae76 [media] media: videobuf2: Move timestamp to vb2_buffer
df9ecb0cad14 [media] vb2: drop v4l2_format argument from queue_setup
0aecfc1b359d usb: gadget: composite: remove redundant bcdUSB setting in legacy

Quoted 10 lines

Quoted 7 lines
uvc-gadget keeps printing this error message:
159 if ((ret = ioctl(dev->fd, VIDIOC_DQBUF, &buf)) < 0) {
160 printf("Unable to dequeue buffer: %s (%d).\n", strerror(errno),
161 errno);
162 return ret;
163 }
I removed this printf, since it floods the console if start uvc-gadget before connect to the host.

fair enough

Quoted 2 lines

BTY, you don't have to start uvc-gadget first then connect usb cable. I keep the cable always connected.

good to know. Thanks

Signature · 1 line

balbi

Laurent Pinchart

2016-09-26 16:04 UTC #18 lore raw

In reply to Felipe Balbi

Hi Felipe,

Quoted 50 lines · On Friday 23 Sep 2016 10:49:57 Felipe Balbi wrote:

Quoted 21 lines · Bin Liu
+Fengwei Yin per his request.
Quoted 6 lines · Felipe Balbi
Bin Liu <[email protected]> writes:
[...]
Quoted 2 lines
Quoted 1 line
Here's one that actually compiles, sorry about that.
Quoted 8 lines
Quoted 3 lines
No worries, I was sleeping ;-)
I will test it out early next week. Thanks.
meanwhile, how about some instructions on how to test this out myself? How are you using g_webcam and what are you running on host side? Got a nice list of commands there I can use? I think I can get to bottom of this much quicker if I can reproduce it locally ;-)
On device side:
- first patch g_webcam as in my first email in this thread to enable
Quoted 6 lines
640x480@30fps;
- # modprobe g_webcam streaming_maxpacket=3072
- then run uvc-gadget to feed the YUV frames;
http://git.ideasonboard.org/uvc-gadget.git
as is, g_webcam never enumerates to the host. It's calls to usb_function_active() and usb_function_deactivate() are unbalanced. Do you have any other changes to g_webcam?
Also, uvc-gadget.git doesn't compile, had to modify it a bit:
(quoted diff) +1 -1
-#include "../drivers/usb/gadget/uvc.h"
+#include "../drivers/usb/gadget/function/uvc.h"
Also fixed a build warning:
(quoted diff) +2 -0
@@ -732,6 +732,8 @@ int main(int argc, char *argv[])
                fd_set wfds = fds;
 
                ret = select(dev->fd + 1, NULL, &wfds, &efds, NULL);
+               if (ret < 0)
+                       return ret;
                if (FD_ISSET(dev->fd, &efds))
                        uvc_events_process(dev);
                if (FD_ISSET(dev->fd, &wfds))

Quoted 2 lines

Laurent, have you tested g_webcam recently? What's the magic to get it working?

I'm afraid not, I haven't had time to work on UVC gadget for a few years now.

Quoted 67 lines

Here's what I get out of dmesg:

[   58.568380] usb 1-9: new high-speed USB device number 5 using xhci_hcd
[   58.738680] usb 1-9: New USB device found, idVendor=1d6b, idProduct=0102
[   58.738683] usb 1-9: New USB device strings: Mfr=1, Product=2,
SerialNumber=0 [   58.738685] usb 1-9: Product: Webcam gadget
[   58.738687] usb 1-9: Manufacturer: Linux Foundation
[   58.739133] g_webcam gadget: high-speed config #1: Video
[   58.739138] g_webcam gadget: uvc_function_set_alt(0, 0)
[   58.739139] g_webcam gadget: reset UVC Control
[   58.739149] g_webcam gadget: uvc_function_set_alt(1, 0)
[   58.804369] uvcvideo: Found UVC 1.00 device Webcam gadget (1d6b:0102)
[   58.804479] g_webcam gadget: uvc_function_set_alt(1, 0)
[   64.188459] uvcvideo: UVC non compliance - GET_DEF(PROBE) not supported.
Enabling workaround. [   69.307458] uvcvideo: Failed to query (129) UVC
probe control : -110 (exp. 26). [   69.307459] uvcvideo: Failed to
initialize the device (-5).
[   69.307505] usbcore: registered new interface driver uvcvideo
[   69.307506] USB Video Class driver (1.1.1)
[  146.646012] ------------[ cut here ]------------
[  146.646023] WARNING: CPU: 0 PID: 2616 at

drivers/usb/gadget/composite.c:371 usb_function_activate+0x77/0x80 [libcomposite] [ 146.646024] Modules linked in: uvcvideo g_webcam usb_f_uvc videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_core videodev libcomposite kvm_intel kvm psmouse e1000e input_leds hid_generic usbhid atkbd irqbypass evdev [ 146.646054] CPU: 0 PID: 2616 Comm:
gst-launch-1.0 Not tainted 4.8.0-rc7-next-20160922-00004-gc71031593917-dirty #20 [ 146.646055]

Hardware name: Intel Corporation Skylake Client platform/Skylake Y LPDDR3
RVP3, BIOS SKLSE2R1.R00.B097.B02.1509020030 09/02/2015 [  146.646058] 
ffffc9000769bb70
[  146.646059]  ffffffff8132d415
[  146.646060]  0000000000000000
[  146.646061]  0000000000000000

[  146.646063]  ffffc9000769bbb0
[  146.646063]  ffffffff8105ec1b
[  146.646064]  000001730769bb90
[  146.646066]  00000000ffffffea
[  146.646070]  ffff88016c03a150
[  146.646072]  0000000000000282 ffff88016d793000 ffffc9000769befc
[  146.646077] Call Trace:
[  146.646086]  [<ffffffff8132d415>] dump_stack+0x68/0x93
[  146.646090]  [<ffffffff8105ec1b>] __warn+0xcb/0xf0
[  146.646095]  [<ffffffff8105ed0d>] warn_slowpath_null+0x1d/0x20
[  146.646099]  [<ffffffffa03f1ea7>] usb_function_activate+0x77/0x80
[libcomposite] [  146.646105]  [<ffffffffa044a6de>]
uvc_function_connect+0x1e/0x40 [usb_f_uvc] [  146.646110] 
[<ffffffffa044b2ae>] uvc_v4l2_open+0x6e/0x80 [usb_f_uvc] [  146.646116] 
[<ffffffffa04014f0>] v4l2_open+0xa0/0x100 [videodev] [  146.646121] 
[<ffffffff811a5431>] chrdev_open+0xa1/0x1d0
[  146.646125]  [<ffffffff811a5390>] ? cdev_put+0x30/0x30
[  146.646129]  [<ffffffff8119dd60>] do_dentry_open.isra.17+0x150/0x2e0
[  146.646133]  [<ffffffff8119f115>] vfs_open+0x45/0x60
[  146.646137]  [<ffffffff811af1ed>] path_openat+0x62d/0x1370
[  146.646141]  [<ffffffff811b0094>] ? putname+0x54/0x60
[  146.646146]  [<ffffffff811b10ce>] do_filp_open+0x7e/0xe0
[  146.646150]  [<ffffffff81083868>] ? preempt_count_sub+0x48/0x70
[  146.646154]  [<ffffffff816f07d6>] ? _raw_spin_unlock+0x16/0x30
[  146.646160]  [<ffffffff811bf009>] ? __alloc_fd+0xc9/0x180
[  146.646164]  [<ffffffff8119f4e3>] do_sys_open+0x123/0x200
[  146.646170]  [<ffffffff8119f5de>] SyS_open+0x1e/0x20
[  146.646174]  [<ffffffff816f0e2a>] entry_SYSCALL_64_fastpath+0x18/0xa8
[  146.646177] ---[ end trace 1a4f7b9817d19b04 ]---
[  146.646180] g_webcam gadget: UVC connect failed with -22
[  146.653808] usb 1-9: USB disconnect, device number 5
[  146.653986] g_webcam gadget: UVC disconnect failed with -110

[snip]

Quoted 9 lines

uvc-gadget keeps printing this error message:
159 if ((ret = ioctl(dev->fd, VIDIOC_DQBUF, &buf)) < 0) {
160 printf("Unable to dequeue buffer: %s (%d).\n",
strerror(errno), 161 errno);
162 return ret;
163 }
Any ideas?

The UVC gadget driver uses videobuf2 for buffer management. You can raise the debug level of the videobuf2-v4l2 and videobuf2-core modules to get verbose error messages in the kernel log.

Signature · 3 lines

Regards,

Laurent Pinchart