Timeouts on Marvell EHCI (FreeBSD-arm)

Mon May 2 11:51:24 UTC 2011

On May 2, 2011, at 1:33 PM, Hans Petter Selasky wrote:

> On Monday 02 May 2011 12:58:02 Damjan Marion wrote:
>> Hi,
>> 
>> I'm trying to bring up FreeBSD on Marvell 88F5181L based ARM board and I
>> have problem with EHCI driver. EHCI controller is detected but it fails to
>> activate connected device.
>> 
>> I will appreciate any hints how to fix this. Output follows....
>> 
>> Thanks,
>> 
>> Damjan
> 
> Can you set:
> 
> hw.usb.uhub.debug=15
> 
> and
> 
> hw.usb.debug=15
> 
> --HPS

Here it is, thanks....

ehci0: <Marvell Integrated USB 2.0 controller> mem 0xf1050000-0xf1050fff irq 17,16 on simplebus0
usbus0: EHCI version 1.0
usbus0: set host controller mode
usbus0: <Marvell Integrated USB 2.0 controller> on ehci0
usb_needs_explore: 
usb_needs_explore: No root HUB
pcib0: <Marvell Integrated PCI/PCI-E Controller> mem 0xf1030000-0xf1031fff irq 11 on fdtbus0
pci0: <PCI bus> on pcib0
Timecounters tick every 1.000 msec
usbus0: 480Mbps High Speed USB v2.0
usb_alloc_device: parent_dev=0xc13b1300, bus=0xc1393c78, parent_hub=0, depth=0, port_index=0, port_no=1, speed=3, usb_mode=0
usb_set_device_state: udev 0xc13ba800 state DETACHED -> POWERED
usbd_req_set_address: setting device address=1
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x00 bRequest=0x05 wValue=0x0001 wIndex=0x0000 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
Root mount waiting for: usbus0
usb_set_device_state: udev 0xc13ba800 state POWERED -> ADDRESSED
usbd_setup_device_desc: Minimum MaxPacketSize is large enough to hold the complete device descriptor
usbd_req_get_device_desc: 
usbd_req_get_desc: id=0, type=1, index=0, max_len=18
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usbd_do_request_flags: Handle Request function is set
usbd_setup_device_desc: adding unit addr=1, rev=200, class=9, subclass=0, protocol=1, maxpacket=64, len=18, speed=3
usbd_req_get_desc: id=0, type=3, index=0, max_len=4
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x80 bRequest=0x06 wValue=0x0300 wIndex=0x0000 wLength=0x0002
usbd_do_request_flags: Handle Request function is set
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x80 bRequest=0x06 wValue=0x0300 wIndex=0x0000 wLength=0x0004
usbd_do_request_flags: Handle Request function is set
usb_alloc_device: Using first language
usb_alloc_device: Language selected: 0x0001
usbd_req_get_desc: id=1, type=3, index=1, max_len=255
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x80 bRequest=0x06 wValue=0x0301 wIndex=0x0001 wLength=0x0002
usbd_do_request_flags: Handle Request function is set
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x80 bRequest=0x06 wValue=0x0301 wIndex=0x0001 wLength=0x0010
usbd_do_request_flags: Handle Request function is set
usbd_req_get_desc: id=1, type=3, index=2, max_len=255
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x80 bRequest=0x06 wValue=0x0302 wIndex=0x0001 wLength=0x0002
usbd_do_request_flags: Handle Request function is set
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x80 bRequest=0x06 wValue=0x0302 wIndex=0x0001 wLength=0x001c
usbd_do_request_flags: Handle Request function is set
usb_alloc_device: setting config 0
usbd_set_config_index: udev=0xc13ba800 index=0
usb_detach_device: udev=0xc13ba800
usb_cdev_free: Freeing device nodes
usb_config_parse: iface_index=255 cmd=1
usbd_req_get_config_desc_full: index=0
usbd_req_get_config_desc: confidx=0
usbd_req_get_desc: id=0, type=2, index=0, max_len=9
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x80 bRequest=0x06 wValue=0x0200 wIndex=0x0000 wLength=0x0009
usbd_do_request_flags: Handle Request function is set
usbd_req_get_desc: id=0, type=2, index=0, max_len=25
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x80 bRequest=0x06 wValue=0x0200 wIndex=0x0000 wLength=0x0019
usbd_do_request_flags: Handle Request function is set
usbd_set_config_index: udev=0xc13ba800 cdesc=0xc1423000 (addr 1) cno=1 attr=0x40, selfpowered=1, power=0
usb_set_device_state: udev 0xc13ba800 state ADDRESSED -> CONFIGURED
usbd_req_set_config: setting config 1
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x00 bRequest=0x09 wValue=0x0001 wIndex=0x0000 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
usb_config_parse: iface_index=255 cmd=0
usb_config_parse: found idesc nendpt=1
usb_config_parse: iface_index=255 cmd=2
usb_config_parse: found idesc nendpt=1
usb_cdev_create: Creating device nodes
usbd_set_config_index: error=USB_ERR_NORMAL_COMPLETION
usb_alloc_device: new dev (addr 1), udev=0xc13ba800, parent_hub=0
usb_bus_port_set_device: bus 0xc1393c78 devices[1] = 0xc13ba800
ugen0.1: <Marvell> at usbus0
usb_probe_and_attach: iclass=9/0/0 iindex=0/0
uhub0: <Marvell EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus0
uhub_attach: depth=0 selfpowered=1, parent=0, parent->selfpowered=0
uhub_attach: Getting HUB descriptor
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0xa0 bRequest=0x06 wValue=0x2900 wIndex=0x0000 wLength=0x0009
usbd_do_request_flags: Handle Request function is set
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x23 bRequest=0x03 wValue=0x0008 wIndex=0x0001 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
uhub_attach: turn on port 1 power
Root mount waiting for: usbus0
uhub0: 1 port with 1 removable, self powered
usb_needs_explore: 
usb_probe_and_attach: end of interfaces at 1
usb_needs_explore: 
usb_bus_powerd: bus=0xc1393c78
usb_bus_powerd: Recomputing power masks
uhub_explore: udev=0xc13ba800 addr=1
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0001 wLength=0x0004
usbd_do_request_flags: Handle Request function is set
uhub_read_port_status: port 1, wPortStatus=0x0101, wPortChange=0x0001, err=USB_ERR_NORMAL_COMPLETION
uhub_reattach_port: reattaching port 1
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x23 bRequest=0x01 wValue=0x0010 wIndex=0x0001 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0001 wLength=0x0004
usbd_do_request_flags: Handle Request function is set
uhub_read_port_status: port 1, wPortStatus=0x0101, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
uhub_reattach_port: Port 1 is in Host Mode
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x23 bRequest=0x03 wValue=0x0004 wIndex=0x0001 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0001 wLength=0x0004
usbd_do_request_flags: Handle Request function is set
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x23 bRequest=0x01 wValue=0x0014 wIndex=0x0001 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
usbd_req_reset_port: port 1 reset returning error=USB_ERR_NORMAL_COMPLETION
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0001 wLength=0x0004
usbd_do_request_flags: Handle Request function is set
uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
usb_alloc_device: parent_dev=0xc1420480, bus=0xc1393c78, parent_hub=0xc13ba800, depth=1, port_index=0, port_no=1, speed=3, u0
usb_set_device_state: udev 0xc1436400 state DETACHED -> POWERED
usbd_req_set_address: setting device address=2
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x00 bRequest=0x05 wValue=0x0002 wIndex=0x0000 wLength=0x0000
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=1, dir=write
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usbd_transfer_power_ref: Adding type 0 to power state
usbd_transfer_power_ref: needs power
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=0 alen=8, slen=8, afrm=1, nfrm=1
usbd_callback_wrapper_sub: xfer=0xc14250b0: Control transfer active on endpoint=0xc143648c
usbd_do_request_callback: st=1
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usb_needs_explore: 
usb_alloc_device: set address 2 failed (USB_ERR_TIMEOUT, ignored)
usb_set_device_state: udev 0xc1436400 state POWERED -> ADDRESSED
usbd_setup_device_desc: Minimum MaxPacketSize is large enough to hold the complete device descriptor
usbd_req_get_device_desc: 
usbd_req_get_desc: id=0, type=1, index=0, max_len=18
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=write
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_STALLED
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=22 alen=0, slen=26, afrm=1, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_STALLED
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=22 alen=0, slen=26, afrm=1, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_STALLED
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=22 alen=0, slen=26, afrm=1, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usb_needs_explore: 
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_STALLED
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=22 alen=0, slen=26, afrm=1, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usbd_req_get_device_desc: 
usbd_req_get_desc: id=0, type=1, index=0, max_len=18
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_STALLED
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=22 alen=0, slen=26, afrm=1, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_STALLED
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=22 alen=0, slen=26, afrm=1, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_STALLED
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=22 alen=0, slen=26, afrm=1, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usb_needs_explore: 
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_STALLED
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=22 alen=0, slen=26, afrm=1, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usbd_setup_device_desc: addr=2, getting full desc failed
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x23 bRequest=0x03 wValue=0x0004 wIndex=0x0001 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0001 wLength=0x0004
usbd_do_request_flags: Handle Request function is set
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x23 bRequest=0x01 wValue=0x0014 wIndex=0x0001 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
usbd_req_reset_port: port 1 reset returning error=USB_ERR_NORMAL_COMPLETION
usb_set_device_state: udev 0xc1436400 state ADDRESSED -> POWERED
usbd_req_set_address: setting device address=2
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x00 bRequest=0x05 wValue=0x0002 wIndex=0x0000 wLength=0x0000
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=1, dir=write
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=0 alen=8, slen=8, afrm=1, nfrm=1
usbd_callback_wrapper_sub: xfer=0xc14240b0: Control transfer active on endpoint=0xc143648c
usbd_do_request_callback: st=1
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=1, dir=write
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=0 alen=0, slen=0, afrm=1, nfrm=1
usbd_do_request_callback: st=1
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usb_needs_explore: 
usbd_req_re_enumerate: addr=2, set address failed! (USB_ERR_TIMEOUT, ignored)
usbd_setup_device_desc: Minimum MaxPacketSize is large enough to hold the complete device descriptor
usbd_req_get_device_desc: 
usbd_req_get_desc: id=0, type=1, index=0, max_len=18
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=2, dir=write
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14250b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14250b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
Root mount waiting for: usbus0
usb_needs_explore: 
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14250b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14250b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14250b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14250b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_needs_explore: 
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14250b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14250b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usbd_req_get_device_desc: 
usbd_req_get_desc: id=0, type=1, index=0, max_len=18
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14250b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14250b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_needs_explore: 
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14250b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14250b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14250b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14250b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_needs_explore: 
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14250b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14250b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usbd_setup_device_desc: addr=2, getting full desc failed
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x23 bRequest=0x03 wValue=0x0004 wIndex=0x0001 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0001 wLength=0x0004
usbd_do_request_flags: Handle Request function is set
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x23 bRequest=0x01 wValue=0x0014 wIndex=0x0001 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
Root mount waiting for: usbus0
usbd_req_reset_port: port 1 reset returning error=USB_ERR_NORMAL_COMPLETION
usb_set_device_state: udev 0xc1436400 state POWERED -> POWERED
usbd_req_set_address: setting device address=2
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x00 bRequest=0x05 wValue=0x0002 wIndex=0x0000 wLength=0x0000
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=1, dir=write
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=0 alen=8, slen=8, afrm=1, nfrm=1
usbd_callback_wrapper_sub: xfer=0xc14250b0: Control transfer active on endpoint=0xc143648c
usbd_do_request_callback: st=1
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14250b0, endpoint=0xc143648c, nframes=1, dir=write
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usb_command_wrapper: cb 0xc14250b0 (enter)
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_pipe_start: start
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14250b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0xc14250b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14250b0 endpoint=0xc143648c sts=0 alen=0, slen=0, afrm=1, nfrm=1
usbd_do_request_callback: st=1
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usb_needs_explore: 
usbd_req_re_enumerate: addr=2, set address failed! (USB_ERR_TIMEOUT, ignored)
usbd_setup_device_desc: Minimum MaxPacketSize is large enough to hold the complete device descriptor
usbd_req_get_device_desc: 
usbd_req_get_desc: id=0, type=1, index=0, max_len=18
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usbd_get_endpoint: udev=0xc1436400 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=write
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=1 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14240b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14240b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
Root mount waiting for: usbus0
usb_needs_explore: 
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14240b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14240b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14240b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14240b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_needs_explore: 
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14240b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14240b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usbd_req_get_device_desc: 
usbd_req_get_desc: id=0, type=1, index=0, max_len=18
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14240b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14240b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_needs_explore: 
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14240b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14240b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14240b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14240b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
Root mount waiting for: usbus0
usbd_do_request_flags: udev=0xc1436400 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_do_request_callback: st=0
usbd_transfer_submit: xfer=0xc14240b0, endpoint=0xc143648c, nframes=2, dir=read
usb_dump_endpoint: endpoint=0xc143648c edesc=0xc143674c isoc_next=0 toggle_next=0 bEndpointAddress=0x00
usb_dump_queue: endpoint=0xc143648c xfer: 
usbd_transfer_submit: open
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_enter: enter
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_pipe_start: start
usb_command_wrapper: cb 0xc14240b0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_command_wrapper: cb 0 (leave)
usb_needs_explore: 
Root mount waiting for: usbus0
usbd_transfer_done: err=USB_ERR_TIMEOUT
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: DMA delay, 1 ms, on 0xc14240b0
usb_command_wrapper: cb 0 (leave)
usb_dma_delay_done_cb: Completed 0xc14240b0
usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
usb_command_wrapper: cb 0xc14240b0 (enter)
usbd_callback_wrapper: case 1-4
usbd_callback_wrapper_sub: xfer=0xc14240b0 endpoint=0xc143648c sts=20 alen=0, slen=26, afrm=0, nfrm=2
usbd_do_request_callback: st=2
usb_command_wrapper: cb 0 (leave)
usbd_transfer_stop: close
usbd_transfer_done: err=USB_ERR_CANCELLED
usbd_transfer_done: not transferring
usbd_setup_device_desc: addr=2, getting full desc failed
usb_free_device: udev=0xc1436400 port=1
usb_set_device_state: udev 0xc1436400 state POWERED -> DETACHED
ugen0.2: <Unknown> at usbus0 (disconnected)
usb_bus_port_set_device: bus 0xc1393c78 devices[0] = 0
usb_detach_device: udev=0xc1436400
usb_cdev_free: Freeing device nodes
usb_config_parse: iface_index=255 cmd=1
uhub_reattach_port: could not allocate new device
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0x23 bRequest=0x01 wValue=0x0001 wIndex=0x0001 wLength=0x0000
usbd_do_request_flags: Handle Request function is set
usb_bus_powerd: bus=0xc1393c78
usb_bus_powerd: Recomputing power masks
uhub_explore: udev=0xc13ba800 addr=1
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0001 wLength=0x0004
usbd_do_request_flags: Handle Request function is set
uhub_read_port_status: port 1, wPortStatus=0x0101, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Trying to mount root from ufs:da0p1 []...
mountroot: waiting for device da0p1 ...
usb_needs_explore: 
usb_bus_powerd: bus=0xc1393c78
usb_bus_powerd: Recomputing power masks
uhub_explore: udev=0xc13ba800 addr=1
usbd_do_request_flags: udev=0xc13ba800 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0001 wLength=0x0004
usbd_do_request_flags: Handle Request function is set
uhub_read_port_status: port 1, wPortStatus=0x0101, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mounting from ufs:da0p1 failed with error 19.