Re: USB device fails to attach
Valery Ushakov <[email protected]>
| Newsgroups | gmane.os.netbsd.devel.kernel |
|---|---|
| Message-ID | <[email protected]> |
When I plug in the reader I get this in dmesg: input context: 96 bytes @ 0xffff8d84b8f03000 00 00 00 00 03 00 00 00 00 00 00 00 00 00 00 00 | ................ 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ 00 00 30 08 00 00 01 00 00 00 00 00 00 00 00 00 | ..0............. 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ 00 00 00 00 26 00 40 00 01 00 78 1f 01 00 00 00 | ....&[email protected]..... 08 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ output context: 64 bytes @ 0xffff8d84b8f02000 00 00 30 08 00 00 01 00 00 00 00 00 09 00 00 10 | ..0............. 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ 01 00 00 00 26 00 40 00 01 00 78 1f 01 00 00 00 | ....&[email protected]..... 08 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ slot context: 32 bytes @ 0xffff8d84b8f02000 00 00 30 08 00 00 01 00 00 00 00 00 09 00 00 10 | ..0............. 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ input context: 128 bytes @ 0xffff8d84b8f03000 00 00 00 00 02 00 00 00 00 00 00 00 00 00 00 00 | ................ 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ 00 00 30 08 00 00 01 00 00 00 00 00 00 00 00 00 | ..0............. 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ 00 00 00 00 00 00 40 00 01 00 78 1f 01 00 00 00 | [email protected]..... 08 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 | ................ uhub1: port 1, set config at addr 9 failed uhub1: autoconfiguration error: device problem, disabling port 1 and this in usbhist: 1754445767.245809 xhci_intr#1@0: called! 1754445767.245822 xhci_intr1#1@0: called: USBSTS 0x00000018 1754445767.245825 usb_schedsoftintr#28@0: called: polling=0 1754445767.245827 xhci_softintr#1@0: called: er: xr_ep 62 xr_cs 0 1754445767.245827 xhci_handle_event#1@0: called: event: 0xffff8d84a8a0f3e0 0x0000000001000000 0x01000000 0x00008800 1754445767.245827 xhci_rhpsc#1@0: called: xhci0: port 1 status change 1754445767.245828 usb_transfer_complete#33@0: called: pipe = 0xffffd509d3c2f100 xfer = 0xffffd509d3c01138 status = 0 actlen = 2 1754445767.245828 usb_transfer_complete#33@0: xfer 0xffffd509d3c01138: repeat 1 new head = 0xffffd509d3c01138 1754445767.245829 usb_transfer_complete#33@0: xfer 0xffffd509d3c01138 doing done 0xffffffff806e1247 1754445767.245829 xhci_root_intr_done#1@0: called! 1754445767.245829 usb_transfer_complete#33@0: xfer 0xffffd509d3c01138 doing callback 0xffffffff8049a35b status 0 1754445767.245830 usb_needs_explore#4@0: called! 1754445767.245831 usbd_start_next#33@0: called: pipe = 0xffffd509d3c2f100, xfer = 0xffffd509d3c01138 1754445767.245831 xhci_root_intr_start#1@0: called! 1754445767.245832 usb_discover#10@1: called! 1754445767.245833 usbd_get_port_status#14@1: called: dev 0xffffd509d3b15f00 port 1 1754445767.245833 usbd_do_request_len#30@1: called: dev=0xffffd509d3b15f00 req=ffff8d84b8e2aea0 flags=0 len=4 1754445767.245834 xhci_allocx#1@1: called! 1754445767.245835 usbd_alloc_xfer#30@1: called: returns 0xffffd509d3c01750 1754445767.245835 usb_allocmem#27@1: called! 1754445767.245835 usbd_transfer#30@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3c2f000, running = 0 1754445767.245836 roothub_ctrl_start#11@1: called: type=0xa3 request=0 len=0x4 value=0 1754445767.245836 xhci_roothub_ctrl_locked#1@1: called: rhreq: 00a3 0000 0001 0004 1754445767.245837 roothub_ctrl_start#11@1: xfer 0xffffd509d3c01750 buflen -1 actlen 4 err 0 1754445767.245837 usb_transfer_complete#34@1: called: pipe = 0xffffd509d3c2f000 xfer = 0xffffd509d3c01750 status = 0 actlen = 4 1754445767.245838 usb_transfer_complete#34@1: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.245838 usb_transfer_complete#34@1: xfer 0xffffd509d3c01750 doing done 0xffffffff806b32ad 1754445767.245838 usb_transfer_complete#34@1: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.245838 usb_transfer_complete#34@1: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.245838 usbd_start_next#34@1: called: pipe = 0xffffd509d3c2f000, xfer = 0 1754445767.245839 usbd_transfer#30@1: <- done xfer 0xffffd509d3c01750, sync (err 0) 1754445767.245839 usbd_free_xfer#30@1: called: 0xffffd509d3c01750 1754445767.245839 usb_freemem#27@1: called! 1754445767.245840 usb_rem_task_wait#32@1: called! 1754445767.245840 xhci_freex#1@1: called! 1754445767.245841 usbd_clear_port_feature#6@1: called: dev 0xffffd509d3b15f00 port 1 sel 16 1754445767.245841 usbd_do_request_len#31@1: called: dev=0xffffd509d3b15f00 req=ffff8d84b8e2aea0 flags=0 len=0 1754445767.245841 xhci_allocx#2@1: called! 1754445767.245842 usbd_alloc_xfer#31@1: called: returns 0xffffd509d3c01750 1754445767.245842 usbd_transfer#31@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3c2f000, running = 0 1754445767.245842 roothub_ctrl_start#12@1: called: type=0x23 request=0x1 len=0 value=0x10 1754445767.245842 xhci_roothub_ctrl_locked#2@1: called: rhreq: 0123 0010 0001 0000 1754445767.245843 roothub_ctrl_start#12@1: xfer 0xffffd509d3c01750 buflen -1 actlen 0 err 0 1754445767.245843 usb_transfer_complete#35@1: called: pipe = 0xffffd509d3c2f000 xfer = 0xffffd509d3c01750 status = 0 actlen = 0 1754445767.245843 usb_transfer_complete#35@1: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.245843 usb_transfer_complete#35@1: xfer 0xffffd509d3c01750 doing done 0xffffffff806b32ad 1754445767.245843 usb_transfer_complete#35@1: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.245843 usb_transfer_complete#35@1: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.245844 usbd_start_next#35@1: called: pipe = 0xffffd509d3c2f000, xfer = 0 1754445767.245844 usbd_transfer#31@1: <- done xfer 0xffffd509d3c01750, sync (err 0) 1754445767.245844 usbd_free_xfer#31@1: called: 0xffffd509d3c01750 1754445767.245844 usb_rem_task_wait#33@1: called! 1754445767.245844 xhci_freex#2@1: called! 1754445767.552654 usbd_reset_port#2@1: called: port 1 1754445767.552654 usbd_do_request_len#32@1: called: dev=0xffffd509d3b15f00 req=ffff8d84b8e2aea0 flags=0 len=0 1754445767.552655 xhci_allocx#3@1: called! 1754445767.552655 usbd_alloc_xfer#32@1: called: returns 0xffffd509d3c01750 1754445767.552655 usbd_transfer#32@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3c2f000, running = 0 1754445767.552655 roothub_ctrl_start#13@1: called: type=0x23 request=0x3 len=0 value=0x4 1754445767.552655 xhci_roothub_ctrl_locked#3@1: called: rhreq: 0323 0004 0001 0000 1754445767.607664 xhci_intr#2@0: called! 1754445767.607665 xhci_intr1#2@0: called: USBSTS 0x00000018 1754445767.607680 usb_schedsoftintr#29@0: called: polling=0 1754445767.607681 xhci_softintr#2@0: called: er: xr_ep 63 xr_cs 0 1754445767.607681 xhci_handle_event#2@0: called: event: 0xffff8d84a8a0f3f0 0x0000000001000000 0x01000000 0x00008800 1754445767.607681 xhci_rhpsc#2@0: called: xhci0: port 1 status change 1754445767.607681 usb_transfer_complete#36@0: called: pipe = 0xffffd509d3c2f100 xfer = 0xffffd509d3c01138 status = 0 actlen = 2 1754445767.607681 usb_transfer_complete#36@0: xfer 0xffffd509d3c01138: repeat 1 new head = 0xffffd509d3c01138 1754445767.607681 usb_transfer_complete#36@0: xfer 0xffffd509d3c01138 doing done 0xffffffff806e1247 1754445767.607681 xhci_root_intr_done#2@0: called! 1754445767.607682 usb_transfer_complete#36@0: xfer 0xffffd509d3c01138 doing callback 0xffffffff8049a35b status 0 1754445767.607682 usbd_start_next#36@0: called: pipe = 0xffffd509d3c2f100, xfer = 0xffffd509d3c01138 1754445767.607682 xhci_root_intr_start#2@0: called! 1754445767.612655 roothub_ctrl_start#13@1: xfer 0xffffd509d3c01750 buflen -1 actlen 0 err 0 1754445767.612655 usb_transfer_complete#37@1: called: pipe = 0xffffd509d3c2f000 xfer = 0xffffd509d3c01750 status = 0 actlen = 0 1754445767.612655 usb_transfer_complete#37@1: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.612655 usb_transfer_complete#37@1: xfer 0xffffd509d3c01750 doing done 0xffffffff806b32ad 1754445767.612656 usb_transfer_complete#37@1: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.612656 usb_transfer_complete#37@1: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.612656 usbd_start_next#37@1: called: pipe = 0xffffd509d3c2f000, xfer = 0 1754445767.612656 usbd_transfer#32@1: <- done xfer 0xffffd509d3c01750, sync (err 0) 1754445767.612656 usbd_free_xfer#32@1: called: 0xffffd509d3c01750 1754445767.612656 usb_rem_task_wait#34@1: called! 1754445767.612657 xhci_freex#3@1: called! 1754445767.612657 usbd_reset_port#2@1: port 1 reset done, error=0 1754445767.672654 usbd_get_port_status#15@1: called: dev 0xffffd509d3b15f00 port 1 1754445767.672654 usbd_do_request_len#33@1: called: dev=0xffffd509d3b15f00 req=ffff8d84b8e2ae40 flags=0 len=4 1754445767.672654 xhci_allocx#4@1: called! 1754445767.672655 usbd_alloc_xfer#33@1: called: returns 0xffffd509d3c01750 1754445767.672655 usb_allocmem#28@1: called! 1754445767.672655 usbd_transfer#33@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3c2f000, running = 0 1754445767.672655 roothub_ctrl_start#14@1: called: type=0xa3 request=0 len=0x4 value=0 1754445767.672655 xhci_roothub_ctrl_locked#4@1: called: rhreq: 00a3 0000 0001 0004 1754445767.672656 roothub_ctrl_start#14@1: xfer 0xffffd509d3c01750 buflen -1 actlen 4 err 0 1754445767.672656 usb_transfer_complete#38@1: called: pipe = 0xffffd509d3c2f000 xfer = 0xffffd509d3c01750 status = 0 actlen = 4 1754445767.672656 usb_transfer_complete#38@1: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.672656 usb_transfer_complete#38@1: xfer 0xffffd509d3c01750 doing done 0xffffffff806b32ad 1754445767.672657 usb_transfer_complete#38@1: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.672657 usb_transfer_complete#38@1: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.672657 usbd_start_next#38@1: called: pipe = 0xffffd509d3c2f000, xfer = 0 1754445767.672657 usbd_transfer#33@1: <- done xfer 0xffffd509d3c01750, sync (err 0) 1754445767.672657 usbd_free_xfer#33@1: called: 0xffffd509d3c01750 1754445767.672657 usb_freemem#28@1: called! 1754445767.672658 usb_rem_task_wait#35@1: called! 1754445767.672658 xhci_freex#4@1: called! 1754445767.672658 usbd_clear_port_feature#7@1: called: dev 0xffffd509d3b15f00 port 1 sel 20 1754445767.672658 usbd_do_request_len#34@1: called: dev=0xffffd509d3b15f00 req=ffff8d84b8e2ae40 flags=0 len=0 1754445767.672658 xhci_allocx#5@1: called! 1754445767.672659 usbd_alloc_xfer#34@1: called: returns 0xffffd509d3c01750 1754445767.672659 usbd_transfer#34@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3c2f000, running = 0 1754445767.672659 roothub_ctrl_start#15@1: called: type=0x23 request=0x1 len=0 value=0x14 1754445767.672659 xhci_roothub_ctrl_locked#5@1: called: rhreq: 0123 0014 0001 0000 1754445767.672660 roothub_ctrl_start#15@1: xfer 0xffffd509d3c01750 buflen -1 actlen 0 err 0 1754445767.672660 usb_transfer_complete#39@1: called: pipe = 0xffffd509d3c2f000 xfer = 0xffffd509d3c01750 status = 0 actlen = 0 1754445767.672660 usb_transfer_complete#39@1: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.672660 usb_transfer_complete#39@1: xfer 0xffffd509d3c01750 doing done 0xffffffff806b32ad 1754445767.672660 usb_transfer_complete#39@1: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.672660 usb_transfer_complete#39@1: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.672661 usbd_start_next#39@1: called: pipe = 0xffffd509d3c2f000, xfer = 0 1754445767.672661 usbd_transfer#34@1: <- done xfer 0xffffd509d3c01750, sync (err 0) 1754445767.672661 usbd_free_xfer#34@1: called: 0xffffd509d3c01750 1754445767.672661 usb_rem_task_wait#36@1: called! 1754445767.672661 xhci_freex#5@1: called! 1754445767.702654 usbd_new_device#2@1: called: bus=0xffffd509d359d508 port=1 depth=1 speed=3 1754445767.702655 xhci_new_device#1@1: called: port 1 depth 1 speed 3 up 0xffffd50d1eb38af8 1754445767.702655 xhci_enable_slot#1@1: called! 1754445767.702656 xhci_do_command_locked#1@1: called: input: 0x0000000000000000 0x00000000 0x00002400 1754445767.702656 xhci_ring_put#1@1: called: 0xffffd509d35b0080 xr_ep 0x27 xr_cs 1 1754445767.702664 xhci_intr#3@0: called! 1754445767.702664 xhci_intr1#3@0: called: USBSTS 0x00000008 1754445767.702667 usb_schedsoftintr#30@0: called: polling=0 1754445767.702668 xhci_softintr#3@0: called: er: xr_ep 64 xr_cs 0 1754445767.702668 xhci_handle_event#3@0: called: event: 0xffff8d84a8a0f400 0x000000011efc1270 0x01000000 0x09008400 1754445767.702668 xhci_event_cmd#1@0: called! 1754445767.702670 xhci_init_slot#1@1: called: slot 9 1754445767.702670 usb_allocmem#29@1: called! 1754445767.702670 usb_allocmem#29@1: large alloc 4096 1754445767.702671 usb_block_allocmem#11@1: called: size=4096 align=4096 flags=0x6 1754445767.702671 usb_allocmem#30@1: called! 1754445767.702671 usb_allocmem#30@1: large alloc 4096 1754445767.702672 usb_block_allocmem#12@1: called: size=4096 align=4096 flags=0x6 1754445767.702672 usbd_setup_pipe_flags#2@1: called: dev=0xffffd509d48d4000 addr=0 iface=0 ep=0xffffd509d48d4038 1754445767.702672 usbd_setup_pipe_flags#2@1: pipe=0xffffd509d4d7f800 1754445767.702673 xhci_open#1@1: called: addr 0 depth 1 port 1 speed 3 1754445767.702673 xhci_open#1@1: dci 1 type 0x05 epaddr 0x00 attr 0x00 1754445767.702673 xhci_open#1@1: mps 64 ival 0 1754445767.702673 xhci_ring_init#1@1: called: xr 0 ntrb 0x100 align 0x10 1754445767.702673 xhci_ring_init#1@1: ring 0xffffd509d9208dc0 1754445767.702673 usb_allocmem#31@1: called! 1754445767.702674 usb_allocmem#31@1: large alloc 4096 1754445767.702674 usb_block_allocmem#13@1: called: size=4096 align=16 flags=0x6 1754445767.702675 usbd_setup_pipe_flags#2@1: pipe=0xffffd509d4d7f800 1754445767.702675 xhci_set_address#1@1: called: slot 9 bsr 0 1754445767.702675 xhci_setup_ctx#1@1: called: pipe 0xffffd509d4d7f800: slot 9 dci 1 speed 3 1754445767.702675 xhci_setup_route#1@1: called! 1754445767.702676 xhci_setup_tthub#1@1: called: myhsport 0xffffd50d1eb38af8 ttportnum=0 tthubslot=0 1754445767.702951 xhci_set_dcba#1@1: called: dcbaa 0xffff8d84a8a11048 dc 0x000000011f77b000 slot 9 1754445767.702951 xhci_address_device#1@1: called: icp 0x11f77f000 slot 0x9 nobsr 1754445767.702951 xhci_do_command_locked#2@1: called: input: 0x000000011f77f000 0x00000000 0x09002c00 1754445767.702951 xhci_ring_put#2@1: called: 0xffffd509d35b0080 xr_ep 0x28 xr_cs 1 1754445767.703002 xhci_intr#4@0: called! 1754445767.703003 xhci_intr1#4@0: called: USBSTS 0x00000008 1754445767.703006 usb_schedsoftintr#31@0: called: polling=0 1754445767.703006 xhci_softintr#4@0: called: er: xr_ep 65 xr_cs 0 1754445767.703007 xhci_handle_event#4@0: called: event: 0xffff8d84a8a0f410 0x000000011efc1280 0x01000000 0x09008400 1754445767.703007 xhci_event_cmd#2@0: called! 1754445767.722761 usbd_get_initial_ddesc#2@1: called: dev 0xffffd509d48d4000 1754445767.722761 usbd_do_request_len#35@1: called: dev=0xffffd509d48d4000 req=ffff8d84b8e2adc0 flags=4 len=8 1754445767.722761 xhci_allocx#6@1: called! 1754445767.722762 usbd_alloc_xfer#35@1: called: returns 0xffffd509d3c01750 1754445767.722762 usb_allocmem#32@1: called! 1754445767.722762 usbd_transfer#35@1: called: xfer = 0xffffd509d3c01750, flags = 0x6, pipe = 0xffffd509d4d7f800, running = 0 1754445767.722763 xhci_device_ctrl_transfer#1@1: called! 1754445767.722763 xhci_device_ctrl_start#1@1: called: req: 0680 0100 0000 0008 1754445767.722763 xhci_ring_put#3@1: called: 0xffffd509d9208dc0 xr_ep 0 xr_cs 1 1754445767.722764 usbd_xfer_schedule_timeout#20@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445767.722764 usbd_transfer#35@1: <- sleeping on xfer 0xffffd509d3c01750 1754445767.722854 xhci_intr#5@0: called! 1754445767.722855 xhci_intr1#5@0: called: USBSTS 0x00000008 1754445767.722858 usb_schedsoftintr#32@0: called: polling=0 1754445767.722858 xhci_softintr#5@0: called: er: xr_ep 66 xr_cs 0 1754445767.722858 xhci_handle_event#5@0: called: event: 0xffff8d84a8a0f420 0x000000011f780010 0x01000000 0x09018000 1754445767.722858 xhci_event_transfer#1@0: called! 1754445767.722859 xhci_handle_event#6@0: called: event: 0xffff8d84a8a0f430 0x000000011f780020 0x01000000 0x09018000 1754445767.722859 xhci_event_transfer#2@0: called! 1754445767.722860 usbd_xfer_trycomplete#19@0: called: xfer 0xffffd509d3c01750 status 1 1754445767.722860 usbd_xfer_cancel_timeout_async#19@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445767.722860 usbd_xfer_cancel_timeout_async#19@0: xfer 0xffffd509d3c01750 timer stopped 1754445767.722860 usb_transfer_complete#40@0: called: pipe = 0xffffd509d4d7f800 xfer = 0xffffd509d3c01750 status = 0 actlen = 8 1754445767.722860 usb_transfer_complete#40@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.722860 usb_transfer_complete#40@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445767.722861 xhci_device_ctrl_done#1@0: called! 1754445767.722861 usb_transfer_complete#40@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.722861 usb_transfer_complete#40@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.722861 usbd_start_next#40@0: called: pipe = 0xffffd509d4d7f800, xfer = 0 1754445767.722862 usbd_free_xfer#35@1: called: 0xffffd509d3c01750 1754445767.722863 usb_freemem#29@1: called! 1754445767.722863 usb_rem_task_wait#37@1: called! 1754445767.722863 xhci_freex#6@1: called! 1754445767.722864 xhci_update_ep0_mps#1@1: called: slot 9 mps 64 1754445767.723204 xhci_do_command_locked#3@1: called: input: 0x000000011f77f000 0x00000000 0x09003400 1754445767.723204 xhci_ring_put#4@1: called: 0xffffd509d35b0080 xr_ep 0x29 xr_cs 1 1754445767.723209 xhci_intr#6@0: called! 1754445767.723209 xhci_intr1#6@0: called: USBSTS 0x00000008 1754445767.723212 usb_schedsoftintr#33@0: called: polling=0 1754445767.723213 xhci_softintr#6@0: called: er: xr_ep 68 xr_cs 0 1754445767.723213 xhci_handle_event#7@0: called: event: 0xffff8d84a8a0f440 0x000000011efc1290 0x01000000 0x09008400 1754445767.723213 xhci_event_cmd#3@0: called! 1754445767.723215 usbd_reload_device_desc#2@1: called! 1754445767.723215 usbd_get_device_desc#2@1: called! 1754445767.723215 usbd_get_desc#3@1: called: type=1, index=0, len=18 1754445767.723215 usbd_do_request_len#36@1: called: dev=0xffffd509d48d4000 req=ffff8d84b8e2adb0 flags=0 len=12 1754445767.723215 xhci_allocx#7@1: called! 1754445767.723215 usbd_alloc_xfer#36@1: called: returns 0xffffd509d3c01750 1754445767.723216 usb_allocmem#33@1: called! 1754445767.723216 usbd_transfer#36@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d4d7f800, running = 0 1754445767.723216 xhci_device_ctrl_transfer#2@1: called! 1754445767.723216 xhci_device_ctrl_start#2@1: called: req: 0680 0100 0000 0012 1754445767.723216 xhci_ring_put#5@1: called: 0xffffd509d9208dc0 xr_ep 0x3 xr_cs 1 1754445767.723217 usbd_xfer_schedule_timeout#21@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445767.723217 usbd_transfer#36@1: <- sleeping on xfer 0xffffd509d3c01750 1754445767.723459 xhci_intr#7@0: called! 1754445767.723459 xhci_intr1#7@0: called: USBSTS 0x00000008 1754445767.723462 usb_schedsoftintr#34@0: called: polling=0 1754445767.723463 xhci_softintr#7@0: called: er: xr_ep 69 xr_cs 0 1754445767.723463 xhci_handle_event#8@0: called: event: 0xffff8d84a8a0f450 0x000000011f780040 0x01000000 0x09018000 1754445767.723463 xhci_event_transfer#3@0: called! 1754445767.723463 xhci_handle_event#9@0: called: event: 0xffff8d84a8a0f460 0x000000011f780050 0x01000000 0x09018000 1754445767.723463 xhci_event_transfer#4@0: called! 1754445767.723463 usbd_xfer_trycomplete#20@0: called: xfer 0xffffd509d3c01750 status 1 1754445767.723464 usbd_xfer_cancel_timeout_async#20@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445767.723464 usbd_xfer_cancel_timeout_async#20@0: xfer 0xffffd509d3c01750 timer stopped 1754445767.723464 usb_transfer_complete#41@0: called: pipe = 0xffffd509d4d7f800 xfer = 0xffffd509d3c01750 status = 0 actlen = 18 1754445767.723464 usb_transfer_complete#41@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.723464 usb_transfer_complete#41@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445767.723464 xhci_device_ctrl_done#2@0: called! 1754445767.723464 usb_transfer_complete#41@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.723465 usb_transfer_complete#41@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.723465 usbd_start_next#41@0: called: pipe = 0xffffd509d4d7f800, xfer = 0 1754445767.723466 usbd_free_xfer#36@1: called: 0xffffd509d3c01750 1754445767.723466 usb_freemem#30@1: called! 1754445767.723466 usb_rem_task_wait#38@1: called! 1754445767.723467 xhci_freex#7@1: called! 1754445767.723468 xhci_new_device#1@1: adding unit addr=9, rev=200, 1754445767.723468 xhci_new_device#1@1: class=0, subclass=0, protocol=0, 1754445767.723468 xhci_new_device#1@1: mps=64, len=18, noconf=1, speed=3 1754445767.723468 usbd_get_string0#4@1: called! 1754445767.723468 usbd_get_string_desc#5@1: called! 1754445767.723469 usbd_do_request_len#37@1: called: dev=0xffffd509d48d4000 req=ffff8d84b8e2ac50 flags=4 len=fe 1754445767.723469 xhci_allocx#8@1: called! 1754445767.723469 usbd_alloc_xfer#37@1: called: returns 0xffffd509d3c01750 1754445767.723469 usb_allocmem#34@1: called! 1754445767.723469 usb_allocmem#34@1: large alloc 254 1754445767.723470 usb_block_allocmem#14@1: called: size=4096 align=0 flags=0 1754445767.723470 usbd_transfer#37@1: called: xfer = 0xffffd509d3c01750, flags = 0x6, pipe = 0xffffd509d4d7f800, running = 0 1754445767.723470 xhci_device_ctrl_transfer#3@1: called! 1754445767.723470 xhci_device_ctrl_start#3@1: called: req: 0680 0300 0000 0002 1754445767.723470 xhci_ring_put#6@1: called: 0xffffd509d9208dc0 xr_ep 0x6 xr_cs 1 1754445767.723471 usbd_xfer_schedule_timeout#22@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445767.723471 usbd_transfer#37@1: <- sleeping on xfer 0xffffd509d3c01750 1754445767.723709 xhci_intr#8@0: called! 1754445767.723709 xhci_intr1#8@0: called: USBSTS 0x00000008 1754445767.723712 usb_schedsoftintr#35@0: called: polling=0 1754445767.723713 xhci_softintr#8@0: called: er: xr_ep 71 xr_cs 0 1754445767.723713 xhci_handle_event#10@0: called: event: 0xffff8d84a8a0f470 0x000000011f780070 0x01000000 0x09018000 1754445767.723713 xhci_event_transfer#5@0: called! 1754445767.723713 xhci_handle_event#11@0: called: event: 0xffff8d84a8a0f480 0x000000011f780080 0x01000000 0x09018000 1754445767.723713 xhci_event_transfer#6@0: called! 1754445767.723713 usbd_xfer_trycomplete#21@0: called: xfer 0xffffd509d3c01750 status 1 1754445767.723714 usbd_xfer_cancel_timeout_async#21@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445767.723714 usbd_xfer_cancel_timeout_async#21@0: xfer 0xffffd509d3c01750 timer stopped 1754445767.723714 usb_transfer_complete#42@0: called: pipe = 0xffffd509d4d7f800 xfer = 0xffffd509d3c01750 status = 0 actlen = 2 1754445767.723714 usb_transfer_complete#42@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.723714 usb_transfer_complete#42@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445767.723714 xhci_device_ctrl_done#3@0: called! 1754445767.723715 usb_transfer_complete#42@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.723715 usb_transfer_complete#42@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.723715 usbd_start_next#42@0: called: pipe = 0xffffd509d4d7f800, xfer = 0 1754445767.723716 usbd_free_xfer#37@1: called: 0xffffd509d3c01750 1754445767.723716 usb_freemem#31@1: called! 1754445767.723716 usb_freemem#31@1: large free 1754445767.723716 usb_block_freemem#11@1: called: size=4096 1754445767.723717 usb_rem_task_wait#39@1: called! 1754445767.723717 xhci_freex#8@1: called! 1754445767.723717 usbd_do_request_len#38@1: called: dev=0xffffd509d48d4000 req=ffff8d84b8e2ac50 flags=4 len=fe 1754445767.723717 xhci_allocx#9@1: called! 1754445767.723718 usbd_alloc_xfer#38@1: called: returns 0xffffd509d3c01750 1754445767.723718 usb_allocmem#35@1: called! 1754445767.723718 usb_allocmem#35@1: large alloc 254 1754445767.723718 usb_block_allocmem#15@1: called: size=4096 align=0 flags=0 1754445767.723718 usbd_transfer#38@1: called: xfer = 0xffffd509d3c01750, flags = 0x6, pipe = 0xffffd509d4d7f800, running = 0 1754445767.723718 xhci_device_ctrl_transfer#4@1: called! 1754445767.723719 xhci_device_ctrl_start#4@1: called: req: 0680 0300 0000 0004 1754445767.723719 xhci_ring_put#7@1: called: 0xffffd509d9208dc0 xr_ep 0x9 xr_cs 1 1754445767.723719 usbd_xfer_schedule_timeout#23@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445767.723719 usbd_transfer#38@1: <- sleeping on xfer 0xffffd509d3c01750 1754445767.723959 xhci_intr#9@0: called! 1754445767.723959 xhci_intr1#9@0: called: USBSTS 0x00000008 1754445767.723962 usb_schedsoftintr#36@0: called: polling=0 1754445767.723963 xhci_softintr#9@0: called: er: xr_ep 73 xr_cs 0 1754445767.723963 xhci_handle_event#12@0: called: event: 0xffff8d84a8a0f490 0x000000011f7800a0 0x01000000 0x09018000 1754445767.723963 xhci_event_transfer#7@0: called! 1754445767.723963 xhci_handle_event#13@0: called: event: 0xffff8d84a8a0f4a0 0x000000011f7800b0 0x01000000 0x09018000 1754445767.723963 xhci_event_transfer#8@0: called! 1754445767.723964 usbd_xfer_trycomplete#22@0: called: xfer 0xffffd509d3c01750 status 1 1754445767.723964 usbd_xfer_cancel_timeout_async#22@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445767.723964 usbd_xfer_cancel_timeout_async#22@0: xfer 0xffffd509d3c01750 timer stopped 1754445767.723964 usb_transfer_complete#43@0: called: pipe = 0xffffd509d4d7f800 xfer = 0xffffd509d3c01750 status = 0 actlen = 4 1754445767.723964 usb_transfer_complete#43@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.723964 usb_transfer_complete#43@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445767.723964 xhci_device_ctrl_done#4@0: called! 1754445767.723964 usb_transfer_complete#43@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.723965 usb_transfer_complete#43@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.723965 usbd_start_next#43@0: called: pipe = 0xffffd509d4d7f800, xfer = 0 1754445767.723966 usbd_free_xfer#38@1: called: 0xffffd509d3c01750 1754445767.723966 usb_freemem#32@1: called! 1754445767.723966 usb_freemem#32@1: large free 1754445767.723966 usb_block_freemem#12@1: called: size=4096 1754445767.723966 usb_rem_task_wait#40@1: called! 1754445767.723967 xhci_freex#9@1: called! 1754445767.723967 usbd_get_string_desc#6@1: called! 1754445767.723967 usbd_do_request_len#39@1: called: dev=0xffffd509d48d4000 req=ffff8d84b8e2ac50 flags=4 len=fe 1754445767.723967 xhci_allocx#10@1: called! 1754445767.723968 usbd_alloc_xfer#39@1: called: returns 0xffffd509d3c01750 1754445767.723968 usb_allocmem#36@1: called! 1754445767.723968 usb_allocmem#36@1: large alloc 254 1754445767.723968 usb_block_allocmem#16@1: called: size=4096 align=0 flags=0 1754445767.723968 usbd_transfer#39@1: called: xfer = 0xffffd509d3c01750, flags = 0x6, pipe = 0xffffd509d4d7f800, running = 0 1754445767.723968 xhci_device_ctrl_transfer#5@1: called! 1754445767.723969 xhci_device_ctrl_start#5@1: called: req: 0680 0301 0409 0002 1754445767.723969 xhci_ring_put#8@1: called: 0xffffd509d9208dc0 xr_ep 0xc xr_cs 1 1754445767.723969 usbd_xfer_schedule_timeout#24@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445767.723969 usbd_transfer#39@1: <- sleeping on xfer 0xffffd509d3c01750 1754445767.724209 xhci_intr#10@0: called! 1754445767.724209 xhci_intr1#10@0: called: USBSTS 0x00000008 1754445767.724212 usb_schedsoftintr#37@0: called: polling=0 1754445767.724213 xhci_softintr#10@0: called: er: xr_ep 75 xr_cs 0 1754445767.724213 xhci_handle_event#14@0: called: event: 0xffff8d84a8a0f4b0 0x000000011f7800d0 0x01000000 0x09018000 1754445767.724213 xhci_event_transfer#9@0: called! 1754445767.724213 xhci_handle_event#15@0: called: event: 0xffff8d84a8a0f4c0 0x000000011f7800e0 0x01000000 0x09018000 1754445767.724213 xhci_event_transfer#10@0: called! 1754445767.724213 usbd_xfer_trycomplete#23@0: called: xfer 0xffffd509d3c01750 status 1 1754445767.724214 usbd_xfer_cancel_timeout_async#23@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445767.724214 usbd_xfer_cancel_timeout_async#23@0: xfer 0xffffd509d3c01750 timer stopped 1754445767.724214 usb_transfer_complete#44@0: called: pipe = 0xffffd509d4d7f800 xfer = 0xffffd509d3c01750 status = 0 actlen = 2 1754445767.724214 usb_transfer_complete#44@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.724214 usb_transfer_complete#44@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445767.724214 xhci_device_ctrl_done#5@0: called! 1754445767.724214 usb_transfer_complete#44@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.724215 usb_transfer_complete#44@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.724215 usbd_start_next#44@0: called: pipe = 0xffffd509d4d7f800, xfer = 0 1754445767.724216 usbd_free_xfer#39@1: called: 0xffffd509d3c01750 1754445767.724216 usb_freemem#33@1: called! 1754445767.724216 usb_freemem#33@1: large free 1754445767.724216 usb_block_freemem#13@1: called: size=4096 1754445767.724216 usb_rem_task_wait#41@1: called! 1754445767.724217 xhci_freex#10@1: called! 1754445767.724217 usbd_do_request_len#40@1: called: dev=0xffffd509d48d4000 req=ffff8d84b8e2ac50 flags=4 len=fe 1754445767.724217 xhci_allocx#11@1: called! 1754445767.724217 usbd_alloc_xfer#40@1: called: returns 0xffffd509d3c01750 1754445767.724218 usb_allocmem#37@1: called! 1754445767.724218 usb_allocmem#37@1: large alloc 254 1754445767.724218 usb_block_allocmem#17@1: called: size=4096 align=0 flags=0 1754445767.724218 usbd_transfer#40@1: called: xfer = 0xffffd509d3c01750, flags = 0x6, pipe = 0xffffd509d4d7f800, running = 0 1754445767.724218 xhci_device_ctrl_transfer#6@1: called! 1754445767.724218 xhci_device_ctrl_start#6@1: called: req: 0680 0301 0409 0010 1754445767.724219 xhci_ring_put#9@1: called: 0xffffd509d9208dc0 xr_ep 0xf xr_cs 1 1754445767.724219 usbd_xfer_schedule_timeout#25@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445767.724219 usbd_transfer#40@1: <- sleeping on xfer 0xffffd509d3c01750 1754445767.724459 xhci_intr#11@0: called! 1754445767.724459 xhci_intr1#11@0: called: USBSTS 0x00000008 1754445767.724462 usb_schedsoftintr#38@0: called: polling=0 1754445767.724463 xhci_softintr#11@0: called: er: xr_ep 77 xr_cs 0 1754445767.724463 xhci_handle_event#16@0: called: event: 0xffff8d84a8a0f4d0 0x000000011f780100 0x01000000 0x09018000 1754445767.724463 xhci_event_transfer#11@0: called! 1754445767.724463 xhci_handle_event#17@0: called: event: 0xffff8d84a8a0f4e0 0x000000011f780110 0x01000000 0x09018000 1754445767.724463 xhci_event_transfer#12@0: called! 1754445767.724464 usbd_xfer_trycomplete#24@0: called: xfer 0xffffd509d3c01750 status 1 1754445767.724464 usbd_xfer_cancel_timeout_async#24@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445767.724464 usbd_xfer_cancel_timeout_async#24@0: xfer 0xffffd509d3c01750 timer stopped 1754445767.724464 usb_transfer_complete#45@0: called: pipe = 0xffffd509d4d7f800 xfer = 0xffffd509d3c01750 status = 0 actlen = 16 1754445767.724464 usb_transfer_complete#45@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.724464 usb_transfer_complete#45@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445767.724464 xhci_device_ctrl_done#6@0: called! 1754445767.724465 usb_transfer_complete#45@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.724465 usb_transfer_complete#45@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.724465 usbd_start_next#45@0: called: pipe = 0xffffd509d4d7f800, xfer = 0 1754445767.724466 usbd_free_xfer#40@1: called: 0xffffd509d3c01750 1754445767.724466 usb_freemem#34@1: called! 1754445767.724466 usb_freemem#34@1: large free 1754445767.724466 usb_block_freemem#14@1: called: size=4096 1754445767.724467 usb_rem_task_wait#42@1: called! 1754445767.724467 xhci_freex#11@1: called! 1754445767.724467 usbd_get_string0#5@1: called! 1754445767.724467 usbd_get_string_desc#7@1: called! 1754445767.724468 usbd_do_request_len#41@1: called: dev=0xffffd509d48d4000 req=ffff8d84b8e2ac50 flags=4 len=fe 1754445767.724468 xhci_allocx#12@1: called! 1754445767.724468 usbd_alloc_xfer#41@1: called: returns 0xffffd509d3c01750 1754445767.724468 usb_allocmem#38@1: called! 1754445767.724468 usb_allocmem#38@1: large alloc 254 1754445767.724468 usb_block_allocmem#18@1: called: size=4096 align=0 flags=0 1754445767.724469 usbd_transfer#41@1: called: xfer = 0xffffd509d3c01750, flags = 0x6, pipe = 0xffffd509d4d7f800, running = 0 1754445767.724469 xhci_device_ctrl_transfer#7@1: called! 1754445767.724469 xhci_device_ctrl_start#7@1: called: req: 0680 0302 0409 0002 1754445767.724469 xhci_ring_put#10@1: called: 0xffffd509d9208dc0 xr_ep 0x12 xr_cs 1 1754445767.724470 usbd_xfer_schedule_timeout#26@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445767.724470 usbd_transfer#41@1: <- sleeping on xfer 0xffffd509d3c01750 1754445767.724709 xhci_intr#12@0: called! 1754445767.724709 xhci_intr1#12@0: called: USBSTS 0x00000008 1754445767.724713 usb_schedsoftintr#39@0: called: polling=0 1754445767.724713 xhci_softintr#12@0: called: er: xr_ep 79 xr_cs 0 1754445767.724713 xhci_handle_event#18@0: called: event: 0xffff8d84a8a0f4f0 0x000000011f780130 0x01000000 0x09018000 1754445767.724713 xhci_event_transfer#13@0: called! 1754445767.724713 xhci_handle_event#19@0: called: event: 0xffff8d84a8a0f500 0x000000011f780140 0x01000000 0x09018000 1754445767.724714 xhci_event_transfer#14@0: called! 1754445767.724714 usbd_xfer_trycomplete#25@0: called: xfer 0xffffd509d3c01750 status 1 1754445767.724714 usbd_xfer_cancel_timeout_async#25@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445767.724714 usbd_xfer_cancel_timeout_async#25@0: xfer 0xffffd509d3c01750 timer stopped 1754445767.724714 usb_transfer_complete#46@0: called: pipe = 0xffffd509d4d7f800 xfer = 0xffffd509d3c01750 status = 0 actlen = 2 1754445767.724714 usb_transfer_complete#46@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.724714 usb_transfer_complete#46@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445767.724715 xhci_device_ctrl_done#7@0: called! 1754445767.724715 usb_transfer_complete#46@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.724715 usb_transfer_complete#46@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.724715 usbd_start_next#46@0: called: pipe = 0xffffd509d4d7f800, xfer = 0 1754445767.724716 usbd_free_xfer#41@1: called: 0xffffd509d3c01750 1754445767.724716 usb_freemem#35@1: called! 1754445767.724716 usb_freemem#35@1: large free 1754445767.724717 usb_block_freemem#15@1: called: size=4096 1754445767.724717 usb_rem_task_wait#43@1: called! 1754445767.724717 xhci_freex#12@1: called! 1754445767.724717 usbd_do_request_len#42@1: called: dev=0xffffd509d48d4000 req=ffff8d84b8e2ac50 flags=4 len=fe 1754445767.724717 xhci_allocx#13@1: called! 1754445767.724718 usbd_alloc_xfer#42@1: called: returns 0xffffd509d3c01750 1754445767.724718 usb_allocmem#39@1: called! 1754445767.724718 usb_allocmem#39@1: large alloc 254 1754445767.724718 usb_block_allocmem#19@1: called: size=4096 align=0 flags=0 1754445767.724718 usbd_transfer#42@1: called: xfer = 0xffffd509d3c01750, flags = 0x6, pipe = 0xffffd509d4d7f800, running = 0 1754445767.724718 xhci_device_ctrl_transfer#8@1: called! 1754445767.724719 xhci_device_ctrl_start#8@1: called: req: 0680 0302 0409 0028 1754445767.724719 xhci_ring_put#11@1: called: 0xffffd509d9208dc0 xr_ep 0x15 xr_cs 1 1754445767.724719 usbd_xfer_schedule_timeout#27@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445767.724719 usbd_transfer#42@1: <- sleeping on xfer 0xffffd509d3c01750 1754445767.725021 xhci_intr#13@0: called! 1754445767.725021 xhci_intr1#13@0: called: USBSTS 0x00000008 1754445767.725024 usb_schedsoftintr#40@0: called: polling=0 1754445767.725025 xhci_softintr#13@0: called: er: xr_ep 81 xr_cs 0 1754445767.725025 xhci_handle_event#20@0: called: event: 0xffff8d84a8a0f510 0x000000011f780160 0x01000000 0x09018000 1754445767.725025 xhci_event_transfer#15@0: called! 1754445767.725025 xhci_handle_event#21@0: called: event: 0xffff8d84a8a0f520 0x000000011f780170 0x01000000 0x09018000 1754445767.725025 xhci_event_transfer#16@0: called! 1754445767.725026 usbd_xfer_trycomplete#26@0: called: xfer 0xffffd509d3c01750 status 1 1754445767.725026 usbd_xfer_cancel_timeout_async#26@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445767.725026 usbd_xfer_cancel_timeout_async#26@0: xfer 0xffffd509d3c01750 timer stopped 1754445767.725026 usb_transfer_complete#47@0: called: pipe = 0xffffd509d4d7f800 xfer = 0xffffd509d3c01750 status = 0 actlen = 40 1754445767.725026 usb_transfer_complete#47@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.725026 usb_transfer_complete#47@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445767.725026 xhci_device_ctrl_done#8@0: called! 1754445767.725027 usb_transfer_complete#47@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445767.725027 usb_transfer_complete#47@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.725027 usbd_start_next#47@0: called: pipe = 0xffffd509d4d7f800, xfer = 0 1754445767.725028 usbd_free_xfer#42@1: called: 0xffffd509d3c01750 1754445767.725028 usb_freemem#36@1: called! 1754445767.725028 usb_freemem#36@1: large free 1754445767.725029 usb_block_freemem#16@1: called: size=4096 1754445767.725029 usb_rem_task_wait#44@1: called! 1754445767.725029 xhci_freex#13@1: called! 1754445767.725030 usbd_get_string0#6@1: called! 1754445767.725030 usbd_get_string_desc#8@1: called! 1754445767.725030 usbd_do_request_len#43@1: called: dev=0xffffd509d48d4000 req=ffff8d84b8e2ac50 flags=4 len=fe 1754445767.725030 xhci_allocx#14@1: called! 1754445767.725031 usbd_alloc_xfer#43@1: called: returns 0xffffd509d3c01750 1754445767.725031 usb_allocmem#40@1: called! 1754445767.725031 usb_allocmem#40@1: large alloc 254 1754445767.725031 usb_block_allocmem#20@1: called: size=4096 align=0 flags=0 1754445767.725031 usbd_transfer#43@1: called: xfer = 0xffffd509d3c01750, flags = 0x6, pipe = 0xffffd509d4d7f800, running = 0 1754445767.725031 xhci_device_ctrl_transfer#9@1: called! 1754445767.725032 xhci_device_ctrl_start#9@1: called: req: 0680 0303 0409 0002 1754445767.725032 xhci_ring_put#12@1: called: 0xffffd509d9208dc0 xr_ep 0x18 xr_cs 1 1754445767.725032 usbd_xfer_schedule_timeout#28@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445767.725032 usbd_transfer#43@1: <- sleeping on xfer 0xffffd509d3c01750 1754445767.725271 xhci_intr#14@0: called! 1754445767.725271 xhci_intr1#14@0: called: USBSTS 0x00000008 1754445767.725275 usb_schedsoftintr#41@0: called: polling=0 1754445767.725275 xhci_softintr#14@0: called: er: xr_ep 83 xr_cs 0 1754445767.725275 xhci_handle_event#22@0: called: event: 0xffff8d84a8a0f530 0x000000011f780180 0x04000008 0x09018000 1754445767.725275 xhci_event_transfer#17@0: called! 1754445767.725275 xhci_event_transfer#17@0: ERR 4 slot 9 dci 1 1754445767.725275 usbd_xfer_trycomplete#27@0: called: xfer 0xffffd509d3c01750 status 1 1754445767.725276 usbd_xfer_cancel_timeout_async#27@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445767.725276 usbd_xfer_cancel_timeout_async#27@0: xfer 0xffffd509d3c01750 timer stopped 1754445767.725276 usb_transfer_complete#48@0: called: pipe = 0xffffd509d4d7f800 xfer = 0xffffd509d3c01750 status = 13 actlen = 0 1754445767.725276 usb_transfer_complete#48@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445767.725276 usb_transfer_complete#48@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445767.725276 xhci_device_ctrl_done#9@0: called! 1754445767.725276 usb_transfer_complete#48@0: xfer 0xffffd509d3c01750 doing callback 0 status 13 1754445767.725277 usb_transfer_complete#48@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445767.725277 usbd_start_next#48@0: called: pipe = 0xffffd509d4d7f800, xfer = 0 1754445767.725278 usbd_free_xfer#43@1: called: 0xffffd509d3c01750 1754445767.725278 usb_freemem#37@1: called! 1754445767.725278 usb_freemem#37@1: large free 1754445767.725278 usb_block_freemem#17@1: called: size=4096 1754445767.725278 usb_rem_task_wait#45@1: called! 1754445767.725279 xhci_freex#14@1: called! 1754445767.725279 usbd_do_request_len#43@1: returning err = 13 1754445767.725281 usb_add_event#2@1: called! 1754445767.725282 usbd_probe_and_attach#2@1: called: trying device specific drivers 1754445767.725386 usbd_probe_and_attach#2@1: no device specific driver found 1754445767.725386 usbd_probe_and_attach#2@1: looping over 1 configurations 1754445767.725386 usbd_probe_and_attach#2@1: trying config idx=0 1754445767.725386 usbd_set_config_index#2@1: called: dev=0xffffd509d48d4000 index=0 1754445767.725386 usbd_get_config_desc#2@1: called: confidx=0 1754445767.725387 usbd_get_desc#4@1: called: type=2, index=0, len=9 1754445767.725387 usbd_do_request_len#44@1: called: dev=0xffffd509d48d4000 req=ffff8d84b8e2acc0 flags=0 len=9 1754445767.725387 xhci_allocx#15@1: called! 1754445767.725387 usbd_alloc_xfer#44@1: called: returns 0xffffd509d3c01750 1754445767.725387 usb_allocmem#41@1: called! 1754445767.725388 usbd_transfer#44@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d4d7f800, running = 0 1754445767.725388 xhci_device_ctrl_transfer#10@1: called! 1754445767.725388 xhci_device_ctrl_start#10@1: called: req: 0680 0200 0000 0009 1754445767.725388 xhci_ring_put#13@1: called: 0xffffd509d9208dc0 xr_ep 0x1b xr_cs 1 1754445767.725389 usbd_xfer_schedule_timeout#29@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445767.725389 usbd_transfer#44@1: <- sleeping on xfer 0xffffd509d3c01750 1754445772.722633 usbd_xfer_timeout#2@1: called: xfer 0xffffd509d3c01750 status 1 1754445772.722634 usbd_xfer_probe_timeout#3@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445772.722646 usbd_xfer_probe_timeout#3@1: xfer 0xffffd509d3c01750 timing out 1754445772.722647 usbd_xfer_timeout#2@1: xfer 0xffffd509d3c01750 schedule timeout task 1754445772.722647 usb_add_task#2@1: called! 1754445772.722649 usbd_xfer_timeout_task#2@0: called: xfer 0xffffd509d3c01750 status 1 1754445772.722649 usbd_xfer_probe_timeout#4@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445772.722650 usbd_xfer_probe_timeout#4@0: xfer 0xffffd509d3c01750 timing out 1754445772.722650 usbd_xfer_timeout_task#2@0: xfer 0xffffd509d3c01750 timed out 1754445772.722650 xhci_abortx#1@0: called: xfer 0xffffd509d3c01750 pipe 0xffffd509d4d7f800 1754445772.722650 xhci_pipe_restart#1@0: called: pipe 0xffffd509d4d7f800 slot 9 dci 1 1754445772.722651 xhci_reset_endpoint#1@0: called: slot 9 dci 1 1754445772.722651 xhci_do_command_locked#4@0: called: input: 0x0000000000000000 0x00000000 0x09013800 1754445772.722651 xhci_ring_put#14@0: called: 0xffffd509d35b0080 xr_ep 0x2a xr_cs 1 1754445772.722655 xhci_intr#15@0: called! 1754445772.722655 xhci_intr1#15@0: called: USBSTS 0x00000008 1754445772.722658 usb_schedsoftintr#42@0: called: polling=0 1754445772.722659 xhci_softintr#15@0: called: er: xr_ep 84 xr_cs 0 1754445772.722659 xhci_handle_event#23@0: called: event: 0xffff8d84a8a0f540 0x000000011efc12a0 0x01000000 0x09008400 1754445772.722659 xhci_event_cmd#4@0: called! 1754445772.722661 xhci_set_dequeue#1@0: called: slot 9 dci 1 1754445772.722661 xhci_do_command_locked#5@0: called: input: 0x000000011f780001 0x00000000 0x09014000 1754445772.722662 xhci_ring_put#15@0: called: 0xffffd509d35b0080 xr_ep 0x2b xr_cs 1 1754445772.722904 xhci_intr#16@0: called! 1754445772.722905 xhci_intr1#16@0: called: USBSTS 0x00000008 1754445772.722908 usb_schedsoftintr#43@0: called: polling=0 1754445772.722908 xhci_softintr#16@0: called: er: xr_ep 85 xr_cs 0 1754445772.722909 xhci_handle_event#24@0: called: event: 0xffff8d84a8a0f550 0x000000011efc12b0 0x01000000 0x09008400 1754445772.722909 xhci_event_cmd#5@0: called! 1754445772.722910 usb_transfer_complete#49@0: called: pipe = 0xffffd509d4d7f800 xfer = 0xffffd509d3c01750 status = 15 actlen = 0 1754445772.722910 usb_transfer_complete#49@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445772.722910 usb_transfer_complete#49@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445772.722910 xhci_device_ctrl_done#10@0: called! 1754445772.722911 usb_transfer_complete#49@0: xfer 0xffffd509d3c01750 doing callback 0 status 15 1754445772.722911 usb_transfer_complete#49@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445772.722911 usbd_start_next#49@0: called: pipe = 0xffffd509d4d7f800, xfer = 0 1754445772.722912 usbd_free_xfer#44@1: called: 0xffffd509d3c01750 1754445772.722913 usb_freemem#38@1: called! 1754445772.722913 usb_rem_task_wait#46@1: called! 1754445772.722913 xhci_freex#15@1: called! 1754445772.722913 usbd_do_request_len#44@1: returning err = 15 1754445772.722914 usbd_set_config_index#2@1: get_config_desc=15 1754445772.722914 usbd_probe_and_attach#2@1: port 1, set config at addr 9 failed, error=15 1754445772.722945 usbd_remove_device#2@1: called: dev 0xffffd509d48d4000 up 0xffffd50d1eb38af8 1754445772.722945 usbd_ar_pipe#2@1: called: pipe = 0xffffd509d4d7f800 1754445772.722946 usbd_close_pipe#2@1: called! 1754445772.722946 xhci_device_ctrl_close#1@1: called! 1754445772.722946 usb_rem_task_wait#47@1: called! 1754445772.722947 xhci_close_pipe#1@1: called: pipe 0xffffd509d4d7f800 slot 9 dci 1 1754445772.722947 xhci_disable_slot#1@1: called! 1754445772.722947 xhci_do_command_locked#6@1: called: input: 0x0000000000000000 0x00000000 0x09002800 1754445772.722947 xhci_ring_put#16@1: called: 0xffffd509d35b0080 xr_ep 0x2c xr_cs 1 1754445772.723154 xhci_intr#17@0: called! 1754445772.723155 xhci_intr1#17@0: called: USBSTS 0x00000008 1754445772.723158 usb_schedsoftintr#44@0: called: polling=0 1754445772.723158 xhci_softintr#17@0: called: er: xr_ep 86 xr_cs 0 1754445772.723159 xhci_handle_event#25@0: called: event: 0xffff8d84a8a0f560 0x000000011efc12c0 0x01000000 0x09008400 1754445772.723159 xhci_event_cmd#6@0: called! 1754445772.723160 xhci_free_slot#1@1: called: slot 9 1754445772.723160 usb_freemem#39@1: called! 1754445772.723161 usb_freemem#39@1: large free 1754445772.723161 usb_block_freemem#18@1: called: size=4096 1754445772.723161 usb_freemem#40@1: called! 1754445772.723161 usb_freemem#40@1: large free 1754445772.723161 usb_block_freemem#19@1: called: size=4096 1754445772.723162 usb_freemem#41@1: called! 1754445772.723162 usb_freemem#41@1: large free 1754445772.723162 usb_block_freemem#20@1: called: size=4096 1754445772.723162 xhci_set_dcba#2@1: called: dcbaa 0xffff8d84a8a11048 dc 0x0000000000000000 slot 9 1754445772.723162 usb_rem_task_wait#48@1: called! 1754445772.723195 usbd_clear_port_feature#8@1: called: dev 0xffffd509d3b15f00 port 1 sel 1 1754445772.723196 usbd_do_request_len#45@1: called: dev=0xffffd509d3b15f00 req=ffff8d84b8e2aea0 flags=0 len=0 1754445772.723196 xhci_allocx#16@1: called! 1754445772.723196 usbd_alloc_xfer#45@1: called: returns 0xffffd509d3c01750 1754445772.723196 usbd_transfer#45@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3c2f000, running = 0 1754445772.723197 roothub_ctrl_start#16@1: called: type=0x23 request=0x1 len=0 value=0x1 1754445772.723197 xhci_roothub_ctrl_locked#6@1: called: rhreq: 0123 0001 0001 0000 1754445772.723198 roothub_ctrl_start#16@1: xfer 0xffffd509d3c01750 buflen -1 actlen 0 err 0 1754445772.723198 usb_transfer_complete#50@1: called: pipe = 0xffffd509d3c2f000 xfer = 0xffffd509d3c01750 status = 0 actlen = 0 1754445772.723198 usb_transfer_complete#50@1: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445772.723198 usb_transfer_complete#50@1: xfer 0xffffd509d3c01750 doing done 0xffffffff806b32ad 1754445772.723198 usb_transfer_complete#50@1: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445772.723198 usb_transfer_complete#50@1: <- done xfer 0xffffd509d3c01750, wakeup 1754445772.723198 usbd_start_next#50@1: called: pipe = 0xffffd509d3c2f000, xfer = 0 1754445772.723199 usbd_transfer#45@1: <- done xfer 0xffffd509d3c01750, sync (err 0) 1754445772.723199 usbd_free_xfer#45@1: called: 0xffffd509d3c01750 1754445772.723199 usb_rem_task_wait#49@1: called! 1754445772.723199 xhci_freex#16@1: called! 1754445772.723200 usbd_get_port_status#16@1: called: dev 0xffffd509d3b15a00 port 1 1754445772.723200 usbd_do_request_len#46@1: called: dev=0xffffd509d3b15a00 req=ffff8d84b8e2ae00 flags=0 len=4 1754445772.723200 xhci_allocx#17@1: called! 1754445772.723201 usbd_alloc_xfer#46@1: called: returns 0xffffd509d3c01750 1754445772.723201 usb_allocmem#42@1: called! 1754445772.723201 usbd_transfer#46@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3b15300, running = 0 1754445772.723201 xhci_device_ctrl_transfer#11@1: called! 1754445772.723201 xhci_device_ctrl_start#11@1: called: req: 00a3 0000 0001 0004 1754445772.723202 xhci_ring_put#17@1: called: 0xffffd509d35b0f40 xr_ep 0x7d xr_cs 1 1754445772.723202 usbd_xfer_schedule_timeout#30@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445772.723202 usbd_transfer#46@1: <- sleeping on xfer 0xffffd509d3c01750 1754445772.723404 xhci_intr#18@0: called! 1754445772.723405 xhci_intr1#18@0: called: USBSTS 0x00000008 1754445772.723408 usb_schedsoftintr#45@0: called: polling=0 1754445772.723409 xhci_softintr#18@0: called: er: xr_ep 87 xr_cs 0 1754445772.723409 xhci_handle_event#26@0: called: event: 0xffff8d84a8a0f570 0x000000011f7397e0 0x01000000 0x01018000 1754445772.723409 xhci_event_transfer#18@0: called! 1754445772.723409 xhci_handle_event#27@0: called: event: 0xffff8d84a8a0f580 0x000000011f7397f0 0x01000000 0x01018000 1754445772.723410 xhci_event_transfer#19@0: called! 1754445772.723410 usbd_xfer_trycomplete#28@0: called: xfer 0xffffd509d3c01750 status 1 1754445772.723410 usbd_xfer_cancel_timeout_async#28@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445772.723410 usbd_xfer_cancel_timeout_async#28@0: xfer 0xffffd509d3c01750 timer stopped 1754445772.723410 usb_transfer_complete#51@0: called: pipe = 0xffffd509d3b15300 xfer = 0xffffd509d3c01750 status = 0 actlen = 4 1754445772.723410 usb_transfer_complete#51@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445772.723410 usb_transfer_complete#51@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445772.723411 xhci_device_ctrl_done#11@0: called! 1754445772.723411 usb_transfer_complete#51@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445772.723411 usb_transfer_complete#51@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445772.723411 usbd_start_next#51@0: called: pipe = 0xffffd509d3b15300, xfer = 0 1754445772.723412 usbd_free_xfer#46@1: called: 0xffffd509d3c01750 1754445772.723413 usb_freemem#42@1: called! 1754445772.723413 usb_rem_task_wait#50@1: called! 1754445772.723413 xhci_freex#17@1: called! 1754445772.723413 usbd_get_port_status#17@1: called: dev 0xffffd509d3b15a00 port 2 1754445772.723414 usbd_do_request_len#47@1: called: dev=0xffffd509d3b15a00 req=ffff8d84b8e2ae00 flags=0 len=4 1754445772.723414 xhci_allocx#18@1: called! 1754445772.723414 usbd_alloc_xfer#47@1: called: returns 0xffffd509d3c01750 1754445772.723414 usb_allocmem#43@1: called! 1754445772.723414 usbd_transfer#47@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3b15300, running = 0 1754445772.723415 xhci_device_ctrl_transfer#12@1: called! 1754445772.723415 xhci_device_ctrl_start#12@1: called: req: 00a3 0000 0002 0004 1754445772.723415 xhci_ring_put#18@1: called: 0xffffd509d35b0f40 xr_ep 0x80 xr_cs 1 1754445772.723415 usbd_xfer_schedule_timeout#31@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445772.723415 usbd_transfer#47@1: <- sleeping on xfer 0xffffd509d3c01750 1754445772.723654 xhci_intr#19@0: called! 1754445772.723655 xhci_intr1#19@0: called: USBSTS 0x00000008 1754445772.723658 usb_schedsoftintr#46@0: called: polling=0 1754445772.723658 xhci_softintr#19@0: called: er: xr_ep 89 xr_cs 0 1754445772.723659 xhci_handle_event#28@0: called: event: 0xffff8d84a8a0f590 0x000000011f739810 0x01000000 0x01018000 1754445772.723659 xhci_event_transfer#20@0: called! 1754445772.723659 xhci_handle_event#29@0: called: event: 0xffff8d84a8a0f5a0 0x000000011f739820 0x01000000 0x01018000 1754445772.723659 xhci_event_transfer#21@0: called! 1754445772.723659 usbd_xfer_trycomplete#29@0: called: xfer 0xffffd509d3c01750 status 1 1754445772.723659 usbd_xfer_cancel_timeout_async#29@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445772.723659 usbd_xfer_cancel_timeout_async#29@0: xfer 0xffffd509d3c01750 timer stopped 1754445772.723660 usb_transfer_complete#52@0: called: pipe = 0xffffd509d3b15300 xfer = 0xffffd509d3c01750 status = 0 actlen = 4 1754445772.723660 usb_transfer_complete#52@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445772.723660 usb_transfer_complete#52@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445772.723660 xhci_device_ctrl_done#12@0: called! 1754445772.723660 usb_transfer_complete#52@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445772.723660 usb_transfer_complete#52@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445772.723661 usbd_start_next#52@0: called: pipe = 0xffffd509d3b15300, xfer = 0 1754445772.723662 usbd_free_xfer#47@1: called: 0xffffd509d3c01750 1754445772.723662 usb_freemem#43@1: called! 1754445772.723662 usb_rem_task_wait#51@1: called! 1754445772.723662 xhci_freex#18@1: called! 1754445772.723663 usbd_get_port_status#18@1: called: dev 0xffffd509d3b15a00 port 3 1754445772.723663 usbd_do_request_len#48@1: called: dev=0xffffd509d3b15a00 req=ffff8d84b8e2ae00 flags=0 len=4 1754445772.723663 xhci_allocx#19@1: called! 1754445772.723663 usbd_alloc_xfer#48@1: called: returns 0xffffd509d3c01750 1754445772.723663 usb_allocmem#44@1: called! 1754445772.723664 usbd_transfer#48@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3b15300, running = 0 1754445772.723664 xhci_device_ctrl_transfer#13@1: called! 1754445772.723664 xhci_device_ctrl_start#13@1: called: req: 00a3 0000 0003 0004 1754445772.723664 xhci_ring_put#19@1: called: 0xffffd509d35b0f40 xr_ep 0x83 xr_cs 1 1754445772.723664 usbd_xfer_schedule_timeout#32@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445772.723665 usbd_transfer#48@1: <- sleeping on xfer 0xffffd509d3c01750 1754445772.723904 xhci_intr#20@0: called! 1754445772.723905 xhci_intr1#20@0: called: USBSTS 0x00000008 1754445772.723908 usb_schedsoftintr#47@0: called: polling=0 1754445772.723908 xhci_softintr#20@0: called: er: xr_ep 91 xr_cs 0 1754445772.723909 xhci_handle_event#30@0: called: event: 0xffff8d84a8a0f5b0 0x000000011f739840 0x01000000 0x01018000 1754445772.723909 xhci_event_transfer#22@0: called! 1754445772.723909 xhci_handle_event#31@0: called: event: 0xffff8d84a8a0f5c0 0x000000011f739850 0x01000000 0x01018000 1754445772.723909 xhci_event_transfer#23@0: called! 1754445772.723909 usbd_xfer_trycomplete#30@0: called: xfer 0xffffd509d3c01750 status 1 1754445772.723909 usbd_xfer_cancel_timeout_async#30@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445772.723909 usbd_xfer_cancel_timeout_async#30@0: xfer 0xffffd509d3c01750 timer stopped 1754445772.723910 usb_transfer_complete#53@0: called: pipe = 0xffffd509d3b15300 xfer = 0xffffd509d3c01750 status = 0 actlen = 4 1754445772.723910 usb_transfer_complete#53@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445772.723910 usb_transfer_complete#53@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445772.723910 xhci_device_ctrl_done#13@0: called! 1754445772.723910 usb_transfer_complete#53@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445772.723910 usb_transfer_complete#53@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445772.723911 usbd_start_next#53@0: called: pipe = 0xffffd509d3b15300, xfer = 0 1754445772.723912 usbd_free_xfer#48@1: called: 0xffffd509d3c01750 1754445772.723912 usb_freemem#44@1: called! 1754445772.723912 usb_rem_task_wait#52@1: called! 1754445772.723912 xhci_freex#19@1: called! 1754445772.723913 usb_needs_explore#5@1: called! 1754445772.723913 usbd_get_port_status#19@1: called: dev 0xffffd509d3b15f00 port 1 1754445772.723914 usbd_do_request_len#49@1: called: dev=0xffffd509d3b15f00 req=ffff8d84b8e2aea0 flags=0 len=4 1754445772.723914 xhci_allocx#20@1: called! 1754445772.723914 usbd_alloc_xfer#49@1: called: returns 0xffffd509d3c01750 1754445772.723914 usb_allocmem#45@1: called! 1754445772.723914 usbd_transfer#49@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3c2f000, running = 0 1754445772.723914 roothub_ctrl_start#17@1: called: type=0xa3 request=0 len=0x4 value=0 1754445772.723915 xhci_roothub_ctrl_locked#7@1: called: rhreq: 00a3 0000 0001 0004 1754445772.723915 roothub_ctrl_start#17@1: xfer 0xffffd509d3c01750 buflen -1 actlen 4 err 0 1754445772.723916 usb_transfer_complete#54@1: called: pipe = 0xffffd509d3c2f000 xfer = 0xffffd509d3c01750 status = 0 actlen = 4 1754445772.723916 usb_transfer_complete#54@1: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445772.723916 usb_transfer_complete#54@1: xfer 0xffffd509d3c01750 doing done 0xffffffff806b32ad 1754445772.723916 usb_transfer_complete#54@1: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445772.723916 usb_transfer_complete#54@1: <- done xfer 0xffffd509d3c01750, wakeup 1754445772.723916 usbd_start_next#54@1: called: pipe = 0xffffd509d3c2f000, xfer = 0 1754445772.723916 usbd_transfer#49@1: <- done xfer 0xffffd509d3c01750, sync (err 0) 1754445772.723917 usbd_free_xfer#49@1: called: 0xffffd509d3c01750 1754445772.723917 usb_freemem#45@1: called! 1754445772.723917 usb_rem_task_wait#53@1: called! 1754445772.723917 xhci_freex#20@1: called! 1754445772.723918 usbd_get_port_status#20@1: called: dev 0xffffd509d3b15a00 port 1 1754445772.723918 usbd_do_request_len#50@1: called: dev=0xffffd509d3b15a00 req=ffff8d84b8e2ae00 flags=0 len=4 1754445772.723918 xhci_allocx#21@1: called! 1754445772.723918 usbd_alloc_xfer#50@1: called: returns 0xffffd509d3c01750 1754445772.723918 usb_allocmem#46@1: called! 1754445772.723918 usbd_transfer#50@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3b15300, running = 0 1754445772.723919 xhci_device_ctrl_transfer#14@1: called! 1754445772.723919 xhci_device_ctrl_start#14@1: called: req: 00a3 0000 0001 0004 1754445772.723919 xhci_ring_put#20@1: called: 0xffffd509d35b0f40 xr_ep 0x86 xr_cs 1 1754445772.723919 usbd_xfer_schedule_timeout#33@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445772.723920 usbd_transfer#50@1: <- sleeping on xfer 0xffffd509d3c01750 1754445772.724154 xhci_intr#21@0: called! 1754445772.724155 xhci_intr1#21@0: called: USBSTS 0x00000008 1754445772.724158 usb_schedsoftintr#48@0: called: polling=0 1754445772.724158 xhci_softintr#21@0: called: er: xr_ep 93 xr_cs 0 1754445772.724159 xhci_handle_event#32@0: called: event: 0xffff8d84a8a0f5d0 0x000000011f739870 0x01000000 0x01018000 1754445772.724159 xhci_event_transfer#24@0: called! 1754445772.724159 xhci_handle_event#33@0: called: event: 0xffff8d84a8a0f5e0 0x000000011f739880 0x01000000 0x01018000 1754445772.724159 xhci_event_transfer#25@0: called! 1754445772.724159 usbd_xfer_trycomplete#31@0: called: xfer 0xffffd509d3c01750 status 1 1754445772.724159 usbd_xfer_cancel_timeout_async#31@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445772.724159 usbd_xfer_cancel_timeout_async#31@0: xfer 0xffffd509d3c01750 timer stopped 1754445772.724160 usb_transfer_complete#55@0: called: pipe = 0xffffd509d3b15300 xfer = 0xffffd509d3c01750 status = 0 actlen = 4 1754445772.724160 usb_transfer_complete#55@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445772.724160 usb_transfer_complete#55@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445772.724160 xhci_device_ctrl_done#14@0: called! 1754445772.724160 usb_transfer_complete#55@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445772.724160 usb_transfer_complete#55@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445772.724161 usbd_start_next#55@0: called: pipe = 0xffffd509d3b15300, xfer = 0 1754445772.724162 usbd_free_xfer#50@1: called: 0xffffd509d3c01750 1754445772.724162 usb_freemem#46@1: called! 1754445772.724162 usb_rem_task_wait#54@1: called! 1754445772.724162 xhci_freex#21@1: called! 1754445772.724163 usbd_get_port_status#21@1: called: dev 0xffffd509d3b15a00 port 2 1754445772.724163 usbd_do_request_len#51@1: called: dev=0xffffd509d3b15a00 req=ffff8d84b8e2ae00 flags=0 len=4 1754445772.724163 xhci_allocx#22@1: called! 1754445772.724163 usbd_alloc_xfer#51@1: called: returns 0xffffd509d3c01750 1754445772.724163 usb_allocmem#47@1: called! 1754445772.724163 usbd_transfer#51@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3b15300, running = 0 1754445772.724164 xhci_device_ctrl_transfer#15@1: called! 1754445772.724164 xhci_device_ctrl_start#15@1: called: req: 00a3 0000 0002 0004 1754445772.724164 xhci_ring_put#21@1: called: 0xffffd509d35b0f40 xr_ep 0x89 xr_cs 1 1754445772.724164 usbd_xfer_schedule_timeout#34@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445772.724165 usbd_transfer#51@1: <- sleeping on xfer 0xffffd509d3c01750 1754445772.724404 xhci_intr#22@0: called! 1754445772.724405 xhci_intr1#22@0: called: USBSTS 0x00000008 1754445772.724408 usb_schedsoftintr#49@0: called: polling=0 1754445772.724408 xhci_softintr#22@0: called: er: xr_ep 95 xr_cs 0 1754445772.724409 xhci_handle_event#34@0: called: event: 0xffff8d84a8a0f5f0 0x000000011f7398a0 0x01000000 0x01018000 1754445772.724409 xhci_event_transfer#26@0: called! 1754445772.724409 xhci_handle_event#35@0: called: event: 0xffff8d84a8a0f600 0x000000011f7398b0 0x01000000 0x01018000 1754445772.724409 xhci_event_transfer#27@0: called! 1754445772.724409 usbd_xfer_trycomplete#32@0: called: xfer 0xffffd509d3c01750 status 1 1754445772.724409 usbd_xfer_cancel_timeout_async#32@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445772.724409 usbd_xfer_cancel_timeout_async#32@0: xfer 0xffffd509d3c01750 timer stopped 1754445772.724410 usb_transfer_complete#56@0: called: pipe = 0xffffd509d3b15300 xfer = 0xffffd509d3c01750 status = 0 actlen = 4 1754445772.724410 usb_transfer_complete#56@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445772.724410 usb_transfer_complete#56@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445772.724410 xhci_device_ctrl_done#15@0: called! 1754445772.724410 usb_transfer_complete#56@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445772.724410 usb_transfer_complete#56@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445772.724411 usbd_start_next#56@0: called: pipe = 0xffffd509d3b15300, xfer = 0 1754445772.724412 usbd_free_xfer#51@1: called: 0xffffd509d3c01750 1754445772.724412 usb_freemem#47@1: called! 1754445772.724412 usb_rem_task_wait#55@1: called! 1754445772.724412 xhci_freex#22@1: called! 1754445772.724412 usbd_get_port_status#22@1: called: dev 0xffffd509d3b15a00 port 3 1754445772.724413 usbd_do_request_len#52@1: called: dev=0xffffd509d3b15a00 req=ffff8d84b8e2ae00 flags=0 len=4 1754445772.724413 xhci_allocx#23@1: called! 1754445772.724413 usbd_alloc_xfer#52@1: called: returns 0xffffd509d3c01750 1754445772.724413 usb_allocmem#48@1: called! 1754445772.724413 usbd_transfer#52@1: called: xfer = 0xffffd509d3c01750, flags = 0x2, pipe = 0xffffd509d3b15300, running = 0 1754445772.724414 xhci_device_ctrl_transfer#16@1: called! 1754445772.724414 xhci_device_ctrl_start#16@1: called: req: 00a3 0000 0003 0004 1754445772.724414 xhci_ring_put#22@1: called: 0xffffd509d35b0f40 xr_ep 0x8c xr_cs 1 1754445772.724414 usbd_xfer_schedule_timeout#35@1: called: xfer 0xffffd509d3c01750 timeout 5000ms set 0 reset 0 1754445772.724415 usbd_transfer#52@1: <- sleeping on xfer 0xffffd509d3c01750 1754445772.724654 xhci_intr#23@0: called! 1754445772.724655 xhci_intr1#23@0: called: USBSTS 0x00000008 1754445772.724658 usb_schedsoftintr#50@0: called: polling=0 1754445772.724658 xhci_softintr#23@0: called: er: xr_ep 97 xr_cs 0 1754445772.724659 xhci_handle_event#36@0: called: event: 0xffff8d84a8a0f610 0x000000011f7398d0 0x01000000 0x01018000 1754445772.724659 xhci_event_transfer#28@0: called! 1754445772.724659 xhci_handle_event#37@0: called: event: 0xffff8d84a8a0f620 0x000000011f7398e0 0x01000000 0x01018000 1754445772.724659 xhci_event_transfer#29@0: called! 1754445772.724659 usbd_xfer_trycomplete#33@0: called: xfer 0xffffd509d3c01750 status 1 1754445772.724659 usbd_xfer_cancel_timeout_async#33@0: called: xfer 0xffffd509d3c01750 timeout 5000ms set 1 reset 0 1754445772.724660 usbd_xfer_cancel_timeout_async#33@0: xfer 0xffffd509d3c01750 timer stopped 1754445772.724660 usb_transfer_complete#57@0: called: pipe = 0xffffd509d3b15300 xfer = 0xffffd509d3c01750 status = 0 actlen = 4 1754445772.724660 usb_transfer_complete#57@0: xfer 0xffffd509d3c01750: repeat 0 new head = 0 1754445772.724660 usb_transfer_complete#57@0: xfer 0xffffd509d3c01750 doing done 0xffffffff806e1665 1754445772.724660 xhci_device_ctrl_done#16@0: called! 1754445772.724660 usb_transfer_complete#57@0: xfer 0xffffd509d3c01750 doing callback 0 status 0 1754445772.724660 usb_transfer_complete#57@0: <- done xfer 0xffffd509d3c01750, wakeup 1754445772.724661 usbd_start_next#57@0: called: pipe = 0xffffd509d3b15300, xfer = 0 1754445772.724662 usbd_free_xfer#52@1: called: 0xffffd509d3c01750 1754445772.724662 usb_freemem#48@1: called! 1754445772.724662 usb_rem_task_wait#56@1: called! 1754445772.724662 xhci_freex#23@1: called! -uwe