Attachment 173901 Details for Bug 212021 – Log from ppc64 QEMU with an xhci controller. usb_debug and xhcidebug have been set to 10 and QEMU's usb tracing has also been enabled.

Log from ppc64 QEMU with an xhci controller. usb_debug and xhcidebug have been set to 10 and QEMU's usb tracing has also been enabled.

bootlog (text/plain), 186.94 KB, created by Nathan Whitehorn on 2016-08-21 03:21:03 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Nathan Whitehorn

Created: 2016-08-21 03:21:03 UTC

Size: 186.94 KB

patch

obsolete

>13236@1471749316.369787:usb_port_claim bus 0, port 1
>13236@1471749316.369816:usb_port_attach bus 0, port 1, devspeed full+high, portspeed full+high+super
>13236@1471749316.369825:usb_xhci_port_link port 5, pls 7
>13236@1471749316.369828:usb_xhci_port_notify port 5, bits 20000
>13236@1471749316.369870:usb_port_claim bus 0, port 2
>13236@1471749316.369878:usb_port_attach bus 0, port 2, devspeed full+high, portspeed full+high+super
>13236@1471749316.369883:usb_xhci_port_link port 6, pls 7
>13236@1471749316.369885:usb_xhci_port_notify port 6, bits 20000
>13236@1471749316.384881:usb_port_claim bus 0, port 3
>13236@1471749316.384900:usb_port_attach bus 0, port 3, devspeed full+high, portspeed full+high+super
>13236@1471749316.384904:usb_xhci_port_link port 7, pls 7
>13236@1471749316.384906:usb_xhci_port_notify port 7, bits 20000
>13236@1471749316.387350:usb_xhci_reset === RESET ===
>13236@1471749316.387357:usb_xhci_slot_disable slotid 1
>13236@1471749316.387360:usb_xhci_slot_disable slotid 2
>13236@1471749316.387362:usb_xhci_slot_disable slotid 3
>13236@1471749316.387364:usb_xhci_slot_disable slotid 4
>13236@1471749316.387365:usb_xhci_slot_disable slotid 5
>13236@1471749316.387367:usb_xhci_slot_disable slotid 6
>13236@1471749316.387369:usb_xhci_slot_disable slotid 7
>13236@1471749316.387370:usb_xhci_slot_disable slotid 8
>13236@1471749316.387372:usb_xhci_slot_disable slotid 9
>13236@1471749316.387374:usb_xhci_slot_disable slotid 10
>13236@1471749316.387376:usb_xhci_slot_disable slotid 11
>13236@1471749316.387379:usb_xhci_slot_disable slotid 12
>13236@1471749316.387380:usb_xhci_slot_disable slotid 13
>13236@1471749316.387382:usb_xhci_slot_disable slotid 14
>13236@1471749316.387384:usb_xhci_slot_disable slotid 15
>13236@1471749316.387386:usb_xhci_slot_disable slotid 16
>13236@1471749316.387388:usb_xhci_slot_disable slotid 17
>13236@1471749316.387390:usb_xhci_slot_disable slotid 18
>13236@1471749316.387391:usb_xhci_slot_disable slotid 19
>13236@1471749316.387393:usb_xhci_slot_disable slotid 20
>13236@1471749316.387395:usb_xhci_slot_disable slotid 21
>13236@1471749316.387397:usb_xhci_slot_disable slotid 22
>13236@1471749316.387398:usb_xhci_slot_disable slotid 23
>13236@1471749316.387400:usb_xhci_slot_disable slotid 24
>13236@1471749316.387402:usb_xhci_slot_disable slotid 25
>13236@1471749316.387404:usb_xhci_slot_disable slotid 26
>13236@1471749316.387406:usb_xhci_slot_disable slotid 27
>13236@1471749316.387408:usb_xhci_slot_disable slotid 28
>13236@1471749316.387410:usb_xhci_slot_disable slotid 29
>13236@1471749316.387412:usb_xhci_slot_disable slotid 30
>13236@1471749316.387413:usb_xhci_slot_disable slotid 31
>13236@1471749316.387415:usb_xhci_slot_disable slotid 32
>13236@1471749316.387418:usb_xhci_slot_disable slotid 33
>13236@1471749316.387420:usb_xhci_slot_disable slotid 34
>13236@1471749316.387422:usb_xhci_slot_disable slotid 35
>13236@1471749316.387423:usb_xhci_slot_disable slotid 36
>13236@1471749316.387425:usb_xhci_slot_disable slotid 37
>13236@1471749316.387427:usb_xhci_slot_disable slotid 38
>13236@1471749316.387429:usb_xhci_slot_disable slotid 39
>13236@1471749316.387431:usb_xhci_slot_disable slotid 40
>13236@1471749316.387433:usb_xhci_slot_disable slotid 41
>13236@1471749316.387435:usb_xhci_slot_disable slotid 42
>13236@1471749316.387437:usb_xhci_slot_disable slotid 43
>13236@1471749316.387439:usb_xhci_slot_disable slotid 44
>13236@1471749316.387441:usb_xhci_slot_disable slotid 45
>13236@1471749316.387443:usb_xhci_slot_disable slotid 46
>13236@1471749316.387445:usb_xhci_slot_disable slotid 47
>13236@1471749316.387447:usb_xhci_slot_disable slotid 48
>13236@1471749316.387448:usb_xhci_slot_disable slotid 49
>13236@1471749316.387450:usb_xhci_slot_disable slotid 50
>13236@1471749316.387452:usb_xhci_slot_disable slotid 51
>13236@1471749316.387454:usb_xhci_slot_disable slotid 52
>13236@1471749316.387456:usb_xhci_slot_disable slotid 53
>13236@1471749316.387458:usb_xhci_slot_disable slotid 54
>13236@1471749316.387460:usb_xhci_slot_disable slotid 55
>13236@1471749316.387461:usb_xhci_slot_disable slotid 56
>13236@1471749316.387463:usb_xhci_slot_disable slotid 57
>13236@1471749316.387465:usb_xhci_slot_disable slotid 58
>13236@1471749316.387467:usb_xhci_slot_disable slotid 59
>13236@1471749316.387469:usb_xhci_slot_disable slotid 60
>13236@1471749316.387471:usb_xhci_slot_disable slotid 61
>13236@1471749316.387473:usb_xhci_slot_disable slotid 62
>13236@1471749316.387475:usb_xhci_slot_disable slotid 63
>13236@1471749316.387476:usb_xhci_slot_disable slotid 64
>13236@1471749316.387478:usb_xhci_port_link port 1, pls 5
>13236@1471749316.387481:usb_xhci_port_notify port 1, bits 20000
>13236@1471749316.387483:usb_xhci_port_link port 2, pls 5
>13236@1471749316.387486:usb_xhci_port_notify port 2, bits 20000
>13236@1471749316.387488:usb_xhci_port_link port 3, pls 5
>13236@1471749316.387490:usb_xhci_port_notify port 3, bits 20000
>13236@1471749316.387491:usb_xhci_port_link port 4, pls 5
>13236@1471749316.387493:usb_xhci_port_notify port 4, bits 20000
>13236@1471749316.387495:usb_xhci_port_link port 5, pls 7
>13236@1471749316.387497:usb_xhci_port_notify port 5, bits 20000
>13236@1471749316.387499:usb_xhci_port_link port 6, pls 7
>13236@1471749316.387501:usb_xhci_port_notify port 6, bits 20000
>13236@1471749316.387503:usb_xhci_port_link port 7, pls 7
>13236@1471749316.387504:usb_xhci_port_notify port 7, bits 20000
>13236@1471749316.387506:usb_xhci_port_link port 8, pls 5
>13236@1471749316.387508:usb_xhci_port_notify port 8, bits 20000
>[8665460,5] SkiBoot skiboot-5.1.5-abd73f9 starting...
>[9308509,5] initial console log level: memory 7, driver 5
>[9374706,6] CPU: P8 generation processor(max 8 threads/core)
>[9387125,7] CPU: Boot CPU PIR is 0x0000 PVR is 0x004d0200
>[9485933,7] CPU: Initial max PIR set to 0x1fff
>[26811861,5] OPAL table: 0x3009f840 .. 0x3009fca0, branch table: 0x30002000
>[27053114,5] FDT: Parsing fdt @0x1000000
>[28663175,5] CHIP: Detected Qemu simulator
>[29157307,5] XSCOM: chip 0x0 at 0x3fc0000000000 [P8E DD2.1]
>[29191222,6] XSTOP: ibm,sw-checkstop-fir prop not found
>[29278097,6] MFSI 0:0: Initialized
>[29291713,6] MFSI 0:2: Initialized
>[29306737,6] MFSI 0:1: Initialized
>[29828586,5] LPC: Bus on chip 0 PCB_Addr=0xb0020
>[30009643,5] LPC: Default bus on chip 0
>[30533334,6] MEM: parsing reserved memory from reserved-names/-ranges properties
>[36973429,5] PLAT: Detected Qemu platform
>[38988975,5] PSI[0x000]: Found PSI bridge [working=1, active=0]
>[40415827,3] SLW: No image found !
>[40722679,5] SLW: Sleep not enabled by HB on this platform
>[41437910,5] CAPI: Preloading ucode 201ef
>[41753991,3] CAPI: Failed to preload resource -7
>[42113567,5] INIT: platform start load kernel failed
>[42779459,5] Chip 0 Found PBCQ0 at /xscom@3fc0000000000/pbcq@2012000
>[44355040,3] PHB0: Base location code not found !
>[103173918,3] PHB0: CAPP: ucode header invalid
>[103535759,5] PCI: Resetting PHBs...
>[719881080,5] PCI: Probing slots...
>[1242309450,5] PHB0:00:00.0 [ROOT] 1014 03dc R:00 C:060400 B:01..02 
>[1242977296,5] PHB0:01:00.0 [PCIB] 1b36 0001 R:00 C:060400 B:02..02 
>[1243542929,5] PHB0:02:01.0 [PCID] 1234 1111 R:02 C:030000 (           vga) 
>[1244188769,5] PHB0:02:02.0 [PCID] 1033 0194 R:03 C:0c0330 (      usb-xhci) 
>[1244777293,5] PHB0:02:03.0 [PCID] 8086 2922 R:02 C:010601 (          sata) 
>[1245349979,5] PHB0:02:04.0 [PCID] 10ec 8139 R:20 C:020000 (      ethernet) 
>[1246169464,5] Releasing unused memory:
>[1246506221,5]     ibm,firmware-heap: 330552/13631488 used
>[1246906016,5]     ibm,firmware-allocs-memory@0000000000000000: 1835520/1178599424 used
>[1247619191,5] Reserved regions:
>[1247875825,5]   0x000031c00000..000031c1ffff : ibm,firmware-stacks
>[1248397997,5]   0x000031000000..000031bfffff : ibm,firmware-data
>[1248852647,5]   0x000030300000..000030ffffff : ibm,firmware-heap
>[1249295760,5]   0x000030000000..0000302fffff : ibm,firmware-code
>[1249735899,5]   0x000039c00000..000039dc01ff : ibm,firmware-allocs-memory@0000000000000000
>[1250250787,5] INIT: Waiting for kernel...
>[1250489478,5] Assuming kernel at 0x20000000
>[1250708810,5] INIT: Kernel loaded, size: 0 bytes (0 = unknown preload)
>[1251152428,5] INIT: 64-bit kernel entry at 0x20002530
>[1251520110,3] OCC: No HOMER detected, assuming no pstates
>[1251831766,3] ELOG: Error getting buffer to log error
>[1260774046,5] Free space in HEAP memory regions:
>[1261136193,5] Region ibm,firmware-heap free: 12776368
>[1261374041,5] Region ibm,firmware-allocs-memory@0000000000000000 free: 376992
>[1261712876,5] Total free: 13153360
>[1261873681,5] INIT: Starting kernel at 0x20002530, fdt at 0x30351028 (size 0x2f4e)
>powerpc_init: no loader metadata.
>KDB: debugger backends: ddb
>KDB: current backend: ddb
>[1274438197,3] OPAL: Trying a CPU re-init with flags: 0x1
>Copyright (c) 1992-2016 The FreeBSD Project.
>Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
>	The Regents of the University of California. All rights reserved.
>FreeBSD is a registered trademark of The FreeBSD Foundation.
>FreeBSD 12.0-CURRENT #4 r304518:304551M: Sun Aug 21 00:52:53 UTC 2016
>    nwhitehorn@bifrost-freebsd:/usr/home/nwhitehorn/powernv/powerpc/compile/GENERIC64 powerpc
>gcc version 4.2.1 20070831 patched [FreeBSD]
>WARNING: WITNESS option enabled, expect reduced performance.
>VT: init without driver.
>cpu0: IBM POWER8 revision 2.0, 1000.00 MHz
>cpu0: Features dc005180<PPC32,PPC64,ALTIVEC,FPU,MMU,SMT,ARCH205,ARCH206,VSX>
>cpu0: Features2 c2000000<ARCH207,HTM,VCRYPTO>
>real memory  = 2116157440 (2018 MB)
>avail memory = 1965207552 (1874 MB)
>random: entropy device external interface
>kbd0 at kbdmux0
>ofwbus0: <Open Firmware Device Tree> on nexus0
>pcib0: <OPAL Host-PCI bridge> mem 0x3fffe40000000-0x3fffe40000fff on ofwbus0
>xicp0: <External Interrupt Presentation Controller> mem 0x3ffff80000000-0x3ffff80000fff on ofwbus0
>xicp0: Handling CPUs 0-0
>xics0: <External Interrupt Source Controller> on ofwbus0
>opal0: <OPAL Abstraction Firmware> irq 16,17,18,19,20,21,4094,4095 on ofwbus0
>opalcons0: <OPAL Consoles> on opal0
>uart0: <OPAL Serial Port> on opalcons0
>uart0: console
>pci0: <OFW PCI bus> on pcib0
>pcib1: <OFW PCI-PCI bridge> at device 0.0 on pci0
>pci1: <OFW PCI bus> on pcib1
>pcib2: <OFW PCI-PCI bridge> irq 4088 at device 0.0 on pci1
>pci2: <OFW PCI bus> on pcib2
>vgapci0: <VGA-compatible display> at device 1.0 on pci2
>xhci0: <NEC uPD720200 USB 3.0 controller> irq 4090 at device 2.0 on pci2
>xhci_init: 
>13236@1471749320.935939:usb_xhci_cap_read off 0x0000, ret 0x01000040
>13236@1471749320.935976:usb_xhci_cap_read off 0x0018, ret 0x00001000
>13236@1471749320.935996:usb_xhci_cap_read off 0x0014, ret 0x00002000
>xhci_init: CAPLENGTH=0x40
>xhci_init: RUNTIMEOFFSET=0x1000
>xhci_init: DOOROFFSET=0x2000
>13236@1471749320.938659:usb_xhci_unimplemented cap read (0x2)
>13236@1471749320.938663:usb_xhci_cap_read off 0x0002, ret 0x00000000
>xhci_init: xHCI version = 0x0000
>13236@1471749320.939756:usb_xhci_oper_read off 0x0008, ret 0x00000001
>13236@1471749320.939790:usb_xhci_cap_read off 0x0010, ret 0x00087001
>xhci_init: HCS0 = 0x00087001
>xhci0: 32 bytes context size, 32-bit DMA
>13236@1471749320.941979:usb_xhci_cap_read off 0x0004, ret 0x08001040
>xhci_init: Max slots: 64
>13236@1471749320.942800:usb_xhci_cap_read off 0x0008, ret 0x0000000f
>xhci_init: HCS2=0x0000000f
>xhci_init: Max scratch: 0
>13236@1471749320.944479:usb_xhci_cap_read off 0x000c, ret 0x00000000
>xhci0: Unable to map MSI-X table 
>13236@1471749320.953445:usb_xhci_cap_read off 0x0010, ret 0x00087001
>13236@1471749320.953480:usb_xhci_cap_read off 0x0020, ret 0x02000402
>13236@1471749320.953525:usb_xhci_cap_read off 0x0030, ret 0x03000002
>xhci_halt_controller: 
>13236@1471749320.954308:usb_xhci_cap_read off 0x0000, ret 0x01000040
>13236@1471749320.954329:usb_xhci_cap_read off 0x0018, ret 0x00001000
>13236@1471749320.954349:usb_xhci_cap_read off 0x0014, ret 0x00002000
>13236@1471749320.954383:usb_xhci_oper_write off 0x0000, val 0x00000000
>13236@1471749320.954389:usb_xhci_irq_intx level 0
>13236@1471749320.976061:usb_xhci_oper_read off 0x0004, ret 0x00000001
>xhci_start_controller: 
>13236@1471749320.976949:usb_xhci_oper_write off 0x0000, val 0x00000002
>13236@1471749320.976954:usb_xhci_reset === RESET ===
>13236@1471749320.976955:usb_xhci_slot_disable slotid 1
>13236@1471749320.976957:usb_xhci_slot_disable slotid 2
>13236@1471749320.976958:usb_xhci_slot_disable slotid 3
>13236@1471749320.976959:usb_xhci_slot_disable slotid 4
>13236@1471749320.976960:usb_xhci_slot_disable slotid 5
>13236@1471749320.976961:usb_xhci_slot_disable slotid 6
>13236@1471749320.976962:usb_xhci_slot_disable slotid 7
>13236@1471749320.976963:usb_xhci_slot_disable slotid 8
>13236@1471749320.976964:usb_xhci_slot_disable slotid 9
>13236@1471749320.976965:usb_xhci_slot_disable slotid 10
>13236@1471749320.976966:usb_xhci_slot_disable slotid 11
>13236@1471749320.976966:usb_xhci_slot_disable slotid 12
>13236@1471749320.976967:usb_xhci_slot_disable slotid 13
>13236@1471749320.976968:usb_xhci_slot_disable slotid 14
>13236@1471749320.976969:usb_xhci_slot_disable slotid 15
>13236@1471749320.976970:usb_xhci_slot_disable slotid 16
>13236@1471749320.976971:usb_xhci_slot_disable slotid 17
>13236@1471749320.976972:usb_xhci_slot_disable slotid 18
>13236@1471749320.976973:usb_xhci_slot_disable slotid 19
>13236@1471749320.976974:usb_xhci_slot_disable slotid 20
>13236@1471749320.976975:usb_xhci_slot_disable slotid 21
>13236@1471749320.976976:usb_xhci_slot_disable slotid 22
>13236@1471749320.976977:usb_xhci_slot_disable slotid 23
>13236@1471749320.976978:usb_xhci_slot_disable slotid 24
>13236@1471749320.976979:usb_xhci_slot_disable slotid 25
>13236@1471749320.976980:usb_xhci_slot_disable slotid 26
>13236@1471749320.976981:usb_xhci_slot_disable slotid 27
>13236@1471749320.976982:usb_xhci_slot_disable slotid 28
>13236@1471749320.976983:usb_xhci_slot_disable slotid 29
>13236@1471749320.976983:usb_xhci_slot_disable slotid 30
>13236@1471749320.976984:usb_xhci_slot_disable slotid 31
>13236@1471749320.976985:usb_xhci_slot_disable slotid 32
>13236@1471749320.976986:usb_xhci_slot_disable slotid 33
>13236@1471749320.976987:usb_xhci_slot_disable slotid 34
>13236@1471749320.976988:usb_xhci_slot_disable slotid 35
>13236@1471749320.976989:usb_xhci_slot_disable slotid 36
>13236@1471749320.976990:usb_xhci_slot_disable slotid 37
>13236@1471749320.976991:usb_xhci_slot_disable slotid 38
>13236@1471749320.976992:usb_xhci_slot_disable slotid 39
>13236@1471749320.976993:usb_xhci_slot_disable slotid 40
>13236@1471749320.976994:usb_xhci_slot_disable slotid 41
>13236@1471749320.976995:usb_xhci_slot_disable slotid 42
>13236@1471749320.976995:usb_xhci_slot_disable slotid 43
>13236@1471749320.976996:usb_xhci_slot_disable slotid 44
>13236@1471749320.976997:usb_xhci_slot_disable slotid 45
>13236@1471749320.976998:usb_xhci_slot_disable slotid 46
>13236@1471749320.976999:usb_xhci_slot_disable slotid 47
>13236@1471749320.977000:usb_xhci_slot_disable slotid 48
>13236@1471749320.977001:usb_xhci_slot_disable slotid 49
>13236@1471749320.977002:usb_xhci_slot_disable slotid 50
>13236@1471749320.977003:usb_xhci_slot_disable slotid 51
>13236@1471749320.977003:usb_xhci_slot_disable slotid 52
>13236@1471749320.977004:usb_xhci_slot_disable slotid 53
>13236@1471749320.977005:usb_xhci_slot_disable slotid 54
>13236@1471749320.977006:usb_xhci_slot_disable slotid 55
>13236@1471749320.977007:usb_xhci_slot_disable slotid 56
>13236@1471749320.977008:usb_xhci_slot_disable slotid 57
>13236@1471749320.977009:usb_xhci_slot_disable slotid 58
>13236@1471749320.977010:usb_xhci_slot_disable slotid 59
>13236@1471749320.977011:usb_xhci_slot_disable slotid 60
>13236@1471749320.977012:usb_xhci_slot_disable slotid 61
>13236@1471749320.977013:usb_xhci_slot_disable slotid 62
>13236@1471749320.977013:usb_xhci_slot_disable slotid 63
>13236@1471749320.977014:usb_xhci_slot_disable slotid 64
>13236@1471749320.977016:usb_xhci_port_link port 1, pls 5
>13236@1471749320.977017:usb_xhci_port_notify port 1, bits 20000
>13236@1471749320.977018:usb_xhci_port_link port 2, pls 5
>13236@1471749320.977020:usb_xhci_port_notify port 2, bits 20000
>13236@1471749320.977021:usb_xhci_port_link port 3, pls 5
>13236@1471749320.977022:usb_xhci_port_notify port 3, bits 20000
>13236@1471749320.977023:usb_xhci_port_link port 4, pls 5
>13236@1471749320.977024:usb_xhci_port_notify port 4, bits 20000
>13236@1471749320.977025:usb_xhci_port_link port 5, pls 7
>13236@1471749320.977026:usb_xhci_port_notify port 5, bits 20000
>13236@1471749320.977027:usb_xhci_port_link port 6, pls 7
>13236@1471749320.977027:usb_xhci_port_notify port 6, bits 20000
>13236@1471749320.977028:usb_xhci_port_link port 7, pls 7
>13236@1471749320.977029:usb_xhci_port_notify port 7, bits 20000
>13236@1471749320.977030:usb_xhci_port_link port 8, pls 5
>13236@1471749320.977031:usb_xhci_port_notify port 8, bits 20000
>13236@1471749320.977034:usb_xhci_irq_intx level 0
>13236@1471749320.998565:usb_xhci_oper_read off 0x0000, ret 0x00000000
>13236@1471749320.998585:usb_xhci_oper_read off 0x0004, ret 0x00000001
>13236@1471749320.998623:usb_xhci_oper_read off 0x0038, ret 0x00000000
>xhci_start_controller: CONFIG=0x00000000 -> 0x00000040
>13236@1471749321.000276:usb_xhci_oper_write off 0x0038, val 0x00000040
>13236@1471749321.000294:usb_xhci_oper_read off 0x0004, ret 0x00000001
>13236@1471749321.000311:usb_xhci_oper_write off 0x0004, val 0x00000001
>13236@1471749321.000314:usb_xhci_irq_intx level 0
>13236@1471749321.000328:usb_xhci_oper_write off 0x0014, val 0x00000000
>13236@1471749321.000449:usb_xhci_oper_write off 0x0030, val 0x05307000
>13236@1471749321.000467:usb_xhci_oper_write off 0x0034, val 0x00000000
>13236@1471749321.000483:usb_xhci_oper_write off 0x0030, val 0x05307000
>13236@1471749321.000498:usb_xhci_oper_write off 0x0034, val 0x00000000
>13236@1471749321.000524:usb_xhci_runtime_read off 0x0028, ret 0x00000000
>xhci_start_controller: ERSTSZ=0x00000000 -> 0x00000001
>13236@1471749321.002179:usb_xhci_runtime_write off 0x0028, val 0x00000001
>13236@1471749321.002198:usb_xhci_runtime_write off 0x0024, val 0x000001f4
>xhci_start_controller: ERDP(0)=0x000000000499f080
>13236@1471749321.003799:usb_xhci_runtime_write off 0x0038, val 0x0499f080
>13236@1471749321.003818:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_start_controller: ERSTBA(0)=0x000000000499f000
>13236@1471749321.005375:usb_xhci_runtime_write off 0x0030, val 0x0499f000
>13236@1471749321.005393:usb_xhci_runtime_write off 0x0034, val 0x00000000
>xhci: event ring[0]:499f080 [208]
>13236@1471749321.005413:usb_xhci_runtime_read off 0x0020, ret 0x00000000
>13236@1471749321.005429:usb_xhci_runtime_write off 0x0020, val 0x00000002
>13236@1471749321.005432:usb_xhci_irq_intx level 0
>xhci_start_controller: CRCR=0x000000000499fd80
>13236@1471749321.006852:usb_xhci_oper_write off 0x0018, val 0x0499fd81
>13236@1471749321.006869:usb_xhci_oper_write off 0x001c, val 0x00000000
>13236@1471749321.006960:usb_xhci_oper_write off 0x0000, val 0x0000000d
>13236@1471749321.006963:usb_xhci_run 
>13236@1471749321.006965:usb_xhci_irq_intx level 0
>13236@1471749321.028492:usb_xhci_oper_read off 0x0004, ret 0x00000000
>13236@1471749321.028695:usb_xhci_runtime_write off 0x0038, val 0x0499f088
>13236@1471749321.028715:usb_xhci_runtime_write off 0x003c, val 0x00000000
>usbus0 on xhci0
>ahci0: <Intel ICH9 AHCI SATA controller> irq 4091 at device 3.0 on pci2
>ahci0: Failed to allocate MSI/MSI-x, falling back to INTx
>ahci0: AHCI v1.00 with 6 1.5Gbps ports, Port Multiplier not supported
>ahcich0: <AHCI channel> at channel 0 on ahci0
>ahcich1: <AHCI channel> at channel 1 on ahci0
>ahcich2: <AHCI channel> at channel 2 on ahci0
>ahcich3: <AHCI channel> at channel 3 on ahci0
>ahcich4: <AHCI channel> at channel 4 on ahci0
>ahcich5: <AHCI channel> at channel 5 on ahci0
>re0: <RealTek 8139C+ 10/100BaseTX> irq 4088 at device 4.0 on pci2
>re0: Chip rev. 0x74800000
>re0: MAC rev. 0x00000000
>miibus0: <MII bus> on re0
>rlphy0: <RealTek internal media interface> PHY 0 on miibus0
>rlphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto, auto-flow
>re0: Using defaults for TSO: 65518/35/2048
>re0: Ethernet address: 52:54:00:12:34:56
>Timecounter "timebase" frequency 512000000 Hz quality 0
>Event timer "decrementer" frequency 512000000 Hz quality 1000
>Timecounters tick every 1.000 msec
>usbus0: 5.0Gbps Super Speed USB v3.0
>xhci_set_hw_power: 
>usb_alloc_device: parent_dev=0x486ec00, bus=0xc000000001276428, parent_hub=0, depth=0, port_index=0, port_no=1, speed=4, usb_mode=0
>xhci_ep_init: endpoint=0x7b150d8, addr=0, endpt=0, mode=0
>usb_set_device_state: udev 0x7b15000 state DETACHED -> POWERED
>usbd_req_set_address: setting device address=1
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x00 bRequest=0x05 wValue=0x0001 wIndex=0x0000 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x00 request=0x05 wLen=0x0000 wValue=0x0001 wIndex=0x0000
>usb_set_device_state: udev 0x7b15000 state POWERED -> ADDRESSED
>usbd_setup_device_desc: Minimum bMaxPacketSize is large enough to hold the complete device descriptor or only one bMaxPacketSize choice
>usbd_req_get_device_desc: 
>usbd_req_get_desc: id=0, type=1, index=0, max_len=18
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x80 request=0x06 wLen=0x0012 wValue=0x0100 wIndex=0x0000
>usbd_setup_device_desc: adding unit addr=1, rev=300, class=9, subclass=0, protocol=3, maxpacket=9, len=18, speed=4
>usbd_req_get_desc: id=0, type=3, index=0, max_len=4
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x80 bRequest=0x06 wValue=0x0300 wIndex=0x0000 wLength=0x0002
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x80 request=0x06 wLen=0x0002 wValue=0x0300 wIndex=0x0000
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x80 bRequest=0x06 wValue=0x0300 wIndex=0x0000 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x80 request=0x06 wLen=0x0004 wValue=0x0300 wIndex=0x0000
>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=0x7b15000 bmRequestType=0x80 bRequest=0x06 wValue=0x0301 wIndex=0x0001 wLength=0x0002
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x80 request=0x06 wLen=0x0002 wValue=0x0301 wIndex=0x0001
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x80 bRequest=0x06 wValue=0x0301 wIndex=0x0001 wLength=0x000e
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x80 request=0x06 wLen=0x000e wValue=0x0301 wIndex=0x0001
>usbd_req_get_desc: id=1, type=3, index=2, max_len=255
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x80 bRequest=0x06 wValue=0x0302 wIndex=0x0001 wLength=0x0002
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x80 request=0x06 wLen=0x0002 wValue=0x0302 wIndex=0x0001
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x80 bRequest=0x06 wValue=0x0302 wIndex=0x0001 wLength=0x001c
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x80 request=0x06 wLen=0x001c wValue=0x0302 wIndex=0x0001
>usb_alloc_device: setting config 0
>usbd_set_config_index: udev=0x7b15000 index=0
>usb_detach_device: udev=0x7b15000
>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=0x7b15000 bmRequestType=0x80 bRequest=0x06 wValue=0x0200 wIndex=0x0000 wLength=0x0009
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x80 request=0x06 wLen=0x0009 wValue=0x0200 wIndex=0x0000
>usbd_req_get_desc: id=0, type=2, index=0, max_len=31
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x80 bRequest=0x06 wValue=0x0200 wIndex=0x0000 wLength=0x001f
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x80 request=0x06 wLen=0x001f wValue=0x0200 wIndex=0x0000
>usbd_set_config_index: udev=0x7b15000 cdesc=0x482de00 (addr 1) cno=1 attr=0x40, selfpowered=1, power=0
>usb_set_device_state: udev 0x7b15000 state ADDRESSED -> CONFIGURED
>usbd_req_set_config: setting config 1
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x00 bRequest=0x09 wValue=0x0001 wIndex=0x0000 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x00 request=0x09 wLen=0x0000 wValue=0x0001 wIndex=0x0000
>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_config_parse: Found endpoint companion descriptor
>xhci_ep_init: endpoint=0x7b1c000, addr=1, endpt=129, mode=0
>usb_cdev_create: Creating device nodes
>usbd_set_config_index: error=USB_ERR_NORMAL_COMPLETION
>usb_detach_device: udev=0x7b15000
>usb_alloc_device: new dev (addr 1), udev=0x7b15000, parent_hub=0
>ugen0.1: <0x1033> at usbus0
>usb_probe_and_attach: iclass=9/0/0 iindex=0/0
>uhub0: <0x1033 XHCI root HUB, class 9/0, rev 3.00/1.00, addr 1> on usbus0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa0 bRequest=0x06 wValue=0x2a00 wIndex=0x0000 wLength=0x000c
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa0 request=0x06 wLen=0x000c wValue=0x2a00 wIndex=0x0000
>13236@1471749322.378412:usb_xhci_cap_read off 0x0010, ret 0x00087001
>13236@1471749322.378496:usb_xhci_port_read port 1, off 0x0000, ret 0x000202a0
>13236@1471749322.378623:usb_xhci_port_read port 2, off 0x0000, ret 0x000202a0
>13236@1471749322.378628:usb_xhci_port_read port 3, off 0x0000, ret 0x000202a0
>13236@1471749322.378630:usb_xhci_port_read port 4, off 0x0000, ret 0x000202a0
>13236@1471749322.378632:usb_xhci_port_read port 5, off 0x0000, ret 0x00020ee1
>13236@1471749322.378634:usb_xhci_port_read port 6, off 0x0000, ret 0x00020ee1
>13236@1471749322.378635:usb_xhci_port_read port 7, off 0x0000, ret 0x00020ee1
>13236@1471749322.378637:usb_xhci_port_read port 8, off 0x0000, ret 0x000202a0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa0 bRequest=0x06 wValue=0x2a00 wIndex=0x0000 wLength=0x000c
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa0 request=0x06 wLen=0x000c wValue=0x2a00 wIndex=0x0000
>13236@1471749322.383759:usb_xhci_cap_read off 0x0010, ret 0x00087001
>13236@1471749322.383765:usb_xhci_port_read port 1, off 0x0000, ret 0x000202a0
>13236@1471749322.383767:usb_xhci_port_read port 2, off 0x0000, ret 0x000202a0
>13236@1471749322.383769:usb_xhci_port_read port 3, off 0x0000, ret 0x000202a0
>13236@1471749322.383770:usb_xhci_port_read port 4, off 0x0000, ret 0x000202a0
>13236@1471749322.383772:usb_xhci_port_read port 5, off 0x0000, ret 0x00020ee1
>13236@1471749322.383773:usb_xhci_port_read port 6, off 0x0000, ret 0x00020ee1
>13236@1471749322.383775:usb_xhci_port_read port 7, off 0x0000, ret 0x00020ee1
>13236@1471749322.383777:usb_xhci_port_read port 8, off 0x0000, ret 0x000202a0
>cd0 at ahcich2 bus 0 scbus2 target 0 lun 0
>cd0: <QEMU QEMU DVD-ROM 2.5+> Removable CD-ROM SCSI device
>cd0: Serial Number QM00005
>cd0: 150.000MB/s transfers (SATA 1.x, UDMA5, ATAPI 12bytes, PIO 8192bytes)
taskqgroup_adjust failed cnt: 1 stride: 1 mp_ncpus: 1 smp_started: 0
>taskqgroup_adjust failed cnt: 1 stride: 1 mp_ncpus: 1 smp_started: 0
>WARNING: WITNESS option enabled, expect reduced performance.
>
>cd0: 487MB (249512 2048 byte sectors)
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x03 wValue=0x0008 wIndex=0x0001 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x03 wLen=0x0000 wValue=0x0008 wIndex=0x0001
>13236@1471749322.589917:usb_xhci_port_read port 1, off 0x0000, ret 0x000202a0
>xhci_roothub_exec: set port power 1
>13236@1471749322.590668:usb_xhci_port_write port 1, off 0x0000, val 0x00000200
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x03 wValue=0x0008 wIndex=0x0002 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x03 wLen=0x0000 wValue=0x0008 wIndex=0x0002
>13236@1471749322.636154:usb_xhci_port_read port 2, off 0x0000, ret 0x000202a0
>xhci_roothub_exec: set port power 2
>13236@1471749322.636920:usb_xhci_port_write port 2, off 0x0000, val 0x00000200
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x03 wValue=0x0008 wIndex=0x0003 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x03 wLen=0x0000 wValue=0x0008 wIndex=0x0003
>13236@1471749322.683119:usb_xhci_port_read port 3, off 0x0000, ret 0x000202a0
>xhci_roothub_exec: set port power 3
>13236@1471749322.683878:usb_xhci_port_write port 3, off 0x0000, val 0x00000200
>Root mount waiting for: usbus0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x03 wValue=0x0008 wIndex=0x0004 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x03 wLen=0x0000 wValue=0x0008 wIndex=0x0004
>13236@1471749322.730264:usb_xhci_port_read port 4, off 0x0000, ret 0x000202a0
>xhci_roothub_exec: set port power 4
>13236@1471749322.731024:usb_xhci_port_write port 4, off 0x0000, val 0x00000200
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x03 wValue=0x0008 wIndex=0x0005 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x03 wLen=0x0000 wValue=0x0008 wIndex=0x0005
>13236@1471749322.777224:usb_xhci_port_read port 5, off 0x0000, ret 0x00020ee1
>xhci_roothub_exec: set port power 5
>13236@1471749322.778017:usb_xhci_port_write port 5, off 0x0000, val 0x00000e00
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x03 wValue=0x0008 wIndex=0x0006 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x03 wLen=0x0000 wValue=0x0008 wIndex=0x0006
>13236@1471749322.825491:usb_xhci_port_read port 6, off 0x0000, ret 0x00020ee1
>xhci_roothub_exec: set port power 6
>13236@1471749322.826442:usb_xhci_port_write port 6, off 0x0000, val 0x00000e00
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x03 wValue=0x0008 wIndex=0x0007 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x03 wLen=0x0000 wValue=0x0008 wIndex=0x0007
>13236@1471749322.873400:usb_xhci_port_read port 7, off 0x0000, ret 0x00020ee1
>xhci_roothub_exec: set port power 7
>13236@1471749322.874333:usb_xhci_port_write port 7, off 0x0000, val 0x00000e00
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x03 wValue=0x0008 wIndex=0x0008 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x03 wLen=0x0000 wValue=0x0008 wIndex=0x0008
>13236@1471749322.921392:usb_xhci_port_read port 8, off 0x0000, ret 0x000202a0
>xhci_roothub_exec: set port power 8
>13236@1471749322.922358:usb_xhci_port_write port 8, off 0x0000, val 0x00000200
>uhub0: 8 ports with 8 removable, self powered
>usb_probe_and_attach: end of interfaces at 1
>xhci_set_hw_power: 
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0001 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
>xhci_roothub_exec: UR_GET_STATUS i=1
>13236@1471749322.975391:usb_xhci_port_read port 1, off 0x0000, ret 0x000202a0
>xhci_roothub_exec: port status=0x000202a0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x01 wValue=0x0010 wIndex=0x0001 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x01 wLen=0x0000 wValue=0x0010 wIndex=0x0001
>xhci_roothub_exec: UR_CLEAR_PORT_FEATURE
>13236@1471749322.984355:usb_xhci_port_read port 1, off 0x0000, ret 0x000202a0
>13236@1471749322.984404:usb_xhci_port_write port 1, off 0x0000, val 0x00020200
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0001 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
>xhci_roothub_exec: UR_GET_STATUS i=1
>13236@1471749322.991656:usb_xhci_port_read port 1, off 0x0000, ret 0x000002a0
>xhci_roothub_exec: port status=0x000002a0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0002 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
>xhci_roothub_exec: UR_GET_STATUS i=2
>13236@1471749323.000146:usb_xhci_port_read port 2, off 0x0000, ret 0x000202a0
>xhci_roothub_exec: port status=0x000202a0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x01 wValue=0x0010 wIndex=0x0002 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x01 wLen=0x0000 wValue=0x0010 wIndex=0x0002
>xhci_roothub_exec: UR_CLEAR_PORT_FEATURE
>13236@1471749323.008560:usb_xhci_port_read port 2, off 0x0000, ret 0x000202a0
>13236@1471749323.008566:usb_xhci_port_write port 2, off 0x0000, val 0x00020200
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0002 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
>xhci_roothub_exec: UR_GET_STATUS i=2
>13236@1471749323.015687:usb_xhci_port_read port 2, off 0x0000, ret 0x000002a0
>xhci_roothub_exec: port status=0x000002a0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0003 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0003
>xhci_roothub_exec: UR_GET_STATUS i=3
>13236@1471749323.024063:usb_xhci_port_read port 3, off 0x0000, ret 0x000202a0
>xhci_roothub_exec: port status=0x000202a0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x01 wValue=0x0010 wIndex=0x0003 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x01 wLen=0x0000 wValue=0x0010 wIndex=0x0003
>xhci_roothub_exec: UR_CLEAR_PORT_FEATURE
>13236@1471749323.032562:usb_xhci_port_read port 3, off 0x0000, ret 0x000202a0
>13236@1471749323.032567:usb_xhci_port_write port 3, off 0x0000, val 0x00020200
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0003 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0003
>xhci_roothub_exec: UR_GET_STATUS i=3
>13236@1471749323.038477:usb_xhci_port_read port 3, off 0x0000, ret 0x000002a0
>xhci_roothub_exec: port status=0x000002a0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0004 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0004
>xhci_roothub_exec: UR_GET_STATUS i=4
>13236@1471749323.045050:usb_xhci_port_read port 4, off 0x0000, ret 0x000202a0
>xhci_roothub_exec: port status=0x000202a0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x01 wValue=0x0010 wIndex=0x0004 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x01 wLen=0x0000 wValue=0x0010 wIndex=0x0004
>xhci_roothub_exec: UR_CLEAR_PORT_FEATURE
>13236@1471749323.051454:usb_xhci_port_read port 4, off 0x0000, ret 0x000202a0
>13236@1471749323.051459:usb_xhci_port_write port 4, off 0x0000, val 0x00020200
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0004 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0004
>xhci_roothub_exec: UR_GET_STATUS i=4
>13236@1471749323.057174:usb_xhci_port_read port 4, off 0x0000, ret 0x000002a0
>xhci_roothub_exec: port status=0x000002a0
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0005 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0005
>xhci_roothub_exec: UR_GET_STATUS i=5
>13236@1471749323.063553:usb_xhci_port_read port 5, off 0x0000, ret 0x00020ee1
>xhci_roothub_exec: port status=0x00020ee1
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x01 wValue=0x0010 wIndex=0x0005 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x01 wLen=0x0000 wValue=0x0010 wIndex=0x0005
>xhci_roothub_exec: UR_CLEAR_PORT_FEATURE
>13236@1471749323.070220:usb_xhci_port_read port 5, off 0x0000, ret 0x00020ee1
>13236@1471749323.070226:usb_xhci_port_write port 5, off 0x0000, val 0x00020e00
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0005 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0005
>xhci_roothub_exec: UR_GET_STATUS i=5
>13236@1471749323.075893:usb_xhci_port_read port 5, off 0x0000, ret 0x00000ee1
>xhci_roothub_exec: port status=0x00000ee1
>usbd_req_reset_port: 
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x01 wValue=0x0014 wIndex=0x0005 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x01 wLen=0x0000 wValue=0x0014 wIndex=0x0005
>xhci_roothub_exec: UR_CLEAR_PORT_FEATURE
>13236@1471749323.383344:usb_xhci_port_read port 5, off 0x0000, ret 0x00000ee1
>13236@1471749323.383378:usb_xhci_port_write port 5, off 0x0000, val 0x00200e00
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x03 wValue=0x0004 wIndex=0x0005 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x03 wLen=0x0000 wValue=0x0004 wIndex=0x0005
>13236@1471749323.388347:usb_xhci_port_read port 5, off 0x0000, ret 0x00000ee1
>xhci_roothub_exec: reset port 5
>13236@1471749323.389059:usb_xhci_port_write port 5, off 0x0000, val 0x00000e10
>13236@1471749323.389063:usb_xhci_port_reset port 5, warm 0
>13236@1471749323.389066:usb_xhci_port_link port 5, pls 0
>13236@1471749323.389068:usb_xhci_port_notify port 5, bits 200000
>13236@1471749323.389070:usb_xhci_queue_event v 0, idx 0, ER_PORT_STATUS_CHANGE, CC_SUCCESS, p 0000000005000000, s 01000000, c 0x00008801
>13236@1471749323.389075:usb_xhci_irq_intx level 1
>13236@1471749323.389430:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749323.389465:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749323.389469:usb_xhci_irq_intx level 1
>13236@1471749323.389505:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749323.389533:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749323.389537:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[0] = 34 (0x0000000005000000 0x01000000 0x00008801)
>xhci_interrupt_poll: Unhandled event = 34
>13236@1471749323.392121:usb_xhci_runtime_write off 0x0038, val 0x0499f098
>13236@1471749323.392127:usb_xhci_runtime_write off 0x003c, val 0x00000000
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0005 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0005
>xhci_roothub_exec: UR_GET_STATUS i=5
>13236@1471749323.447521:usb_xhci_port_read port 5, off 0x0000, ret 0x00200e03
>xhci_roothub_exec: port status=0x00200e03
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x01 wValue=0x0014 wIndex=0x0005 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x01 wLen=0x0000 wValue=0x0014 wIndex=0x0005
>xhci_roothub_exec: UR_CLEAR_PORT_FEATURE
>13236@1471749323.454215:usb_xhci_port_read port 5, off 0x0000, ret 0x00200e03
>13236@1471749323.454222:usb_xhci_port_write port 5, off 0x0000, val 0x00200e00
>Root mount waiting for: usbus0
>usbd_req_reset_port: port 5 reset returning error=USB_ERR_NORMAL_COMPLETION
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0005 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0005
>xhci_roothub_exec: UR_GET_STATUS i=5
>13236@1471749323.707359:usb_xhci_port_read port 5, off 0x0000, ret 0x00000e03
>xhci_roothub_exec: port status=0x00000e03
>usb_alloc_device: parent_dev=0x486d800, bus=0xc000000001276428, parent_hub=0x7b15000, depth=1, port_index=4, port_no=5, speed=3, usb_mode=0
>xhci_ep_init: endpoint=0x7b730d8, addr=0, endpt=0, mode=0
>xhci_ep_clear_stall: 
>xhci_cmd_enable_slot: 
>xhci_do_command: command[0] = 9 (0x0000000000000000, 0x00000000, 0x00002400)
>13236@1471749323.716489:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749323.716497:usb_xhci_fetch_trb addr 000000000499fd80, CR_ENABLE_SLOT, p 0000000000000000, s 00000000, c 0x00002401
>13236@1471749323.716504:usb_xhci_slot_enable slotid 1
>13236@1471749323.716506:usb_xhci_queue_event v 0, idx 1, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fd80, s 01000000, c 0x01008401
>13236@1471749323.716509:usb_xhci_irq_intx level 1
>13236@1471749323.716512:usb_xhci_fetch_trb addr 000000000499fd90, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749323.717029:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749323.717036:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749323.717038:usb_xhci_irq_intx level 1
>13236@1471749323.717041:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749323.717044:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749323.717045:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[1] = 33 (0x000000000499fd80 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749323.719717:usb_xhci_runtime_write off 0x0038, val 0x0499f0a8
>13236@1471749323.719723:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_set_slot_pointer: addr[1]=0x000000000831a000
>xhci_configure_device: index=1
>xhci_configure_device: Route=0x00000000
>xhci_dump_device: psl = 0xc0000000015e3020
>xhci_dump_device: dwSctx0=0x08300000
>xhci_dump_device: dwSctx1=0x00050000
>xhci_dump_device: dwSctx2=0x00000000
>xhci_dump_device: dwSctx3=0x00000000
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_set_address: 
>xhci_do_command: command[1] = 11 (0x000000000831c000, 0x00000000, 0x01002e00)
>13236@1471749323.738962:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749323.738968:usb_xhci_fetch_trb addr 000000000499fd90, CR_ADDRESS_DEVICE, p 000000000831c000, s 00000000, c 0x01002e01
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>13236@1471749323.738978:usb_xhci_slot_address slotid 1, port 1
>13236@1471749323.738981:usb_xhci_ep_enable slotid 1, epid 1
>xhci: endpoint 0.1 type is 4, max transaction (burst) size is 64
>xhci: output slot context: 08300000 00050000 00000000 08000000
>xhci: output ep0 context: 00000001 00400026 07b2d001 00000000 00000008
>13236@1471749323.738994:usb_xhci_queue_event v 0, idx 2, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fd90, s 01000000, c 0x01008401
>13236@1471749323.738997:usb_xhci_irq_intx level 1
>13236@1471749323.738999:usb_xhci_fetch_trb addr 000000000499fda0, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749323.739310:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749323.739318:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749323.739321:usb_xhci_irq_intx level 1
>13236@1471749323.739324:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749323.739327:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749323.739329:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[2] = 33 (0x000000000499fd90 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749323.741744:usb_xhci_runtime_write off 0x0038, val 0x0499f0b8
>13236@1471749323.741749:usb_xhci_runtime_write off 0x003c, val 0x00000000
>usb_set_device_state: udev 0x7b73000 state DETACHED -> POWERED
>xhci_device_state_change: 
>usbd_req_set_address: setting device address=2
>xhci_configure_device: index=1
>xhci_configure_device: Route=0x00000000
>xhci_dump_device: psl = 0xc0000000015e3020
>xhci_dump_device: dwSctx0=0x08300000
>xhci_dump_device: dwSctx1=0x00050000
>xhci_dump_device: dwSctx2=0x00000000
>xhci_dump_device: dwSctx3=0x00000000
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_set_address: 
>xhci_do_command: command[2] = 11 (0x000000000831c000, 0x00000000, 0x01002c00)
>13236@1471749323.758908:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749323.758914:usb_xhci_fetch_trb addr 000000000499fda0, CR_ADDRESS_DEVICE, p 000000000831c000, s 00000000, c 0x01002c01
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>13236@1471749323.758923:usb_xhci_slot_address slotid 1, port 1
>13236@1471749323.758926:usb_packet_state_change bus 0, port 1, ep 0, packet 0x7fd45d994e10, state undef -> setup
>13236@1471749323.758931:usb_set_addr dev 1
>13236@1471749323.758933:usb_xhci_ep_enable slotid 1, epid 1
>13236@1471749323.758934:usb_xhci_ep_disable slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=0 dequeue=0000000007b2d001
>13236@1471749323.758941:usb_xhci_ep_state slotid 1, epid 1, running -> disabled
>xhci: endpoint 0.1 type is 4, max transaction (burst) size is 64
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: output ep0 context: 00000001 00400026 07b2d001 00000000 00000008
>13236@1471749323.758952:usb_xhci_queue_event v 0, idx 3, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fda0, s 01000000, c 0x01008401
>13236@1471749323.758954:usb_xhci_irq_intx level 1
>13236@1471749323.758956:usb_xhci_fetch_trb addr 000000000499fdb0, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749323.759262:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749323.759271:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749323.759274:usb_xhci_irq_intx level 1
>13236@1471749323.759277:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749323.759280:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749323.759282:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[3] = 33 (0x000000000499fda0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749323.761683:usb_xhci_runtime_write off 0x0038, val 0x0499f0c8
>13236@1471749323.761688:usb_xhci_runtime_write off 0x003c, val 0x00000000
>usb_set_device_state: udev 0x7b73000 state POWERED -> ADDRESSED
>xhci_device_state_change: 
>usbd_setup_device_desc: Minimum bMaxPacketSize is large enough to hold the complete device descriptor or only one bMaxPacketSize choice
>usbd_req_get_device_desc: 
>usbd_req_get_desc: id=0, type=1, index=0, max_len=18
>usbd_do_request_flags: udev=0x7b73000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usbd_get_endpoint: udev=0x7b73000 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
>usbd_get_endpoint: udev=0x7b73000 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
>usbd_get_endpoint: udev=0x7b73000 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
>usbd_get_endpoint: udev=0x7b73000 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_do_request_callback: st=0
>usbd_transfer_submit: xfer=0x7b87160, endpoint=0x7b730d8, nframes=2, dir=write
>usb_dump_endpoint: endpoint=0x7b730d8 edesc=0x7b73720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
>usb_dump_queue: endpoint=0x7b730d8 xfer: 
>usbd_transfer_submit: open
>xhci_set_hw_power: 
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_enter: enter
>xhci_device_generic_enter: 
>xhci_dump_trb: trb = 0x7b8c600
>xhci_dump_trb: qwTrb0 = 0x0012000001000680
>xhci_dump_trb: dwTrb2 = 0x00000008
>xhci_dump_trb: dwTrb3 = 0x00030841
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c480
>xhci_dump_trb: trb = 0x7b8c610
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c480
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8c480
>xhci_dump_trb: qwTrb0 = 0x0000000007b85808
>xhci_dump_trb: dwTrb2 = 0x00020012
>xhci_dump_trb: dwTrb3 = 0x00010c15
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c300
>xhci_dump_trb: trb = 0x7b8c490
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c300
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8c300
>xhci_dump_trb: qwTrb0 = 0x0000000000000000
>xhci_dump_trb: dwTrb2 = 0x00020000
>xhci_dump_trb: dwTrb3 = 0x00001011
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c180
>xhci_dump_trb: trb = 0x7b8c310
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c180
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_setup_generic_chain: first=0x7b8c600 last=0x7b8c300
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_start: start
>xhci_device_generic_start: 
>xhci_transfer_insert: 
>xhci_transfer_insert: Not running
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0 (leave)
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[3] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749323.849086:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749323.849093:usb_xhci_fetch_trb addr 000000000499fdb0, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749323.849096:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749323.849102:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749323.849104:usb_xhci_queue_event v 0, idx 4, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdb0, s 01000000, c 0x01008401
>13236@1471749323.849106:usb_xhci_irq_intx level 1
>13236@1471749323.849109:usb_xhci_fetch_trb addr 000000000499fdc0, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749323.849428:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749323.849436:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749323.849439:usb_xhci_irq_intx level 1
>13236@1471749323.849442:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749323.849445:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749323.849447:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[4] = 33 (0x000000000499fdb0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749323.852050:usb_xhci_runtime_write off 0x0038, val 0x0499f0d8
>13236@1471749323.852056:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[4] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749323.854235:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749323.854241:usb_xhci_fetch_trb addr 000000000499fdc0, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749323.854244:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749323.854246:usb_xhci_queue_event v 0, idx 5, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fdc0, s 13000000, c 0x01008401
>13236@1471749323.854249:usb_xhci_irq_intx level 1
>13236@1471749323.854252:usb_xhci_fetch_trb addr 000000000499fdd0, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749323.854574:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749323.854582:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749323.854584:usb_xhci_irq_intx level 1
>13236@1471749323.854587:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749323.854590:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749323.854591:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[5] = 33 (0x000000000499fdc0 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749323.857062:usb_xhci_runtime_write off 0x0038, val 0x0499f0e8
>13236@1471749323.857067:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[5] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749323.860630:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749323.860636:usb_xhci_fetch_trb addr 000000000499fdd0, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749323.860639:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749323.860643:usb_xhci_queue_event v 0, idx 6, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdd0, s 01000000, c 0x01008401
>13236@1471749323.860646:usb_xhci_irq_intx level 1
>13236@1471749323.860648:usb_xhci_fetch_trb addr 000000000499fde0, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749323.861063:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749323.861071:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749323.861073:usb_xhci_irq_intx level 1
>13236@1471749323.861076:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749323.861078:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749323.861080:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[6] = 33 (0x000000000499fdd0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749323.863571:usb_xhci_runtime_write off 0x0038, val 0x0499f0f8
>13236@1471749323.863577:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[6] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749323.865941:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749323.865947:usb_xhci_fetch_trb addr 000000000499fde0, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749323.865950:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749323.865959:usb_xhci_queue_event v 0, idx 7, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fde0, s 01000000, c 0x01008401
>13236@1471749323.865962:usb_xhci_irq_intx level 1
>13236@1471749323.865964:usb_xhci_fetch_trb addr 000000000499fdf0, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749323.866282:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749323.866290:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749323.866293:usb_xhci_irq_intx level 1
>13236@1471749323.866296:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749323.866299:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749323.866300:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[7] = 33 (0x000000000499fde0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749323.868719:usb_xhci_runtime_write off 0x0038, val 0x0499f108
>13236@1471749323.868724:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[7] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749323.871064:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749323.871070:usb_xhci_fetch_trb addr 000000000499fdf0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749323.871072:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749323.871077:usb_xhci_queue_event v 0, idx 8, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fdf0, s 05000000, c 0x01008401
>13236@1471749323.871080:usb_xhci_irq_intx level 1
>13236@1471749323.871082:usb_xhci_fetch_trb addr 000000000499fe00, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749323.871400:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749323.871408:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749323.871411:usb_xhci_irq_intx level 1
>13236@1471749323.871414:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749323.871417:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749323.871418:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[8] = 33 (0x000000000499fdf0 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749323.873888:usb_xhci_runtime_write off 0x0038, val 0x0499f118
>13236@1471749323.873893:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>xhci_transfer_insert: 
>xhci_dump_trb: trb = 0x7b8c310
>xhci_dump_trb: qwTrb0 = 0x0000000007b2d010
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001821
>xhci_dump_trb: trb = 0x7b2d000
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c600
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00000000
>xhci_transfer_insert: qh_pos = 0
>13236@1471749323.882652:usb_xhci_doorbell_write off 0x0004, val 0x00000001
>13236@1471749323.882657:usb_xhci_ep_kick slotid 1, epid 1, streamid 0
>xhci: set epctx: 831a020 state=1 dequeue=0000000007b2d001
>13236@1471749323.882662:usb_xhci_ep_state slotid 1, epid 1, stopped -> running
>xhci_timeout: xfer=0x7b87160
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=20
>usbd_transfer_done: err=USB_ERR_TIMEOUT
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: DMA delay, 2 ms, on 0x7b87160
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[8] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749324.348622:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749324.348629:usb_xhci_fetch_trb addr 000000000499fe00, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749324.348632:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749324.348637:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749324.348639:usb_xhci_queue_event v 0, idx 9, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe00, s 01000000, c 0x01008401
>13236@1471749324.348641:usb_xhci_irq_intx level 1
>13236@1471749324.348644:usb_xhci_fetch_trb addr 000000000499fe10, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749324.349056:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749324.349064:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749324.349066:usb_xhci_irq_intx level 1
>13236@1471749324.349069:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749324.349072:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749324.349073:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[9] = 33 (0x000000000499fe00 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749324.351490:usb_xhci_runtime_write off 0x0038, val 0x0499f128
>13236@1471749324.351496:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[9] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749324.353614:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749324.353619:usb_xhci_fetch_trb addr 000000000499fe10, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749324.353622:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749324.353624:usb_xhci_queue_event v 0, idx 10, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fe10, s 13000000, c 0x01008401
>13236@1471749324.353627:usb_xhci_irq_intx level 1
>13236@1471749324.353629:usb_xhci_fetch_trb addr 000000000499fe20, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749324.354032:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749324.354043:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749324.354045:usb_xhci_irq_intx level 1
>13236@1471749324.354048:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749324.354051:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749324.354052:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[10] = 33 (0x000000000499fe10 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749324.356562:usb_xhci_runtime_write off 0x0038, val 0x0499f138
>13236@1471749324.356567:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[10] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749324.360122:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749324.360128:usb_xhci_fetch_trb addr 000000000499fe20, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749324.360130:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749324.360134:usb_xhci_queue_event v 0, idx 11, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe20, s 01000000, c 0x01008401
>13236@1471749324.360136:usb_xhci_irq_intx level 1
>13236@1471749324.360138:usb_xhci_fetch_trb addr 000000000499fe30, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749324.360453:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749324.360461:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749324.360463:usb_xhci_irq_intx level 1
>13236@1471749324.360466:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749324.360469:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749324.360470:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[11] = 33 (0x000000000499fe20 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749324.362993:usb_xhci_runtime_write off 0x0038, val 0x0499f148
>13236@1471749324.362998:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[11] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749324.365205:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749324.365211:usb_xhci_fetch_trb addr 000000000499fe30, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749324.365213:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749324.365222:usb_xhci_queue_event v 0, idx 12, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe30, s 01000000, c 0x01008401
>13236@1471749324.365224:usb_xhci_irq_intx level 1
>13236@1471749324.365227:usb_xhci_fetch_trb addr 000000000499fe40, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749324.365546:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749324.365554:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749324.365555:usb_xhci_irq_intx level 1
>13236@1471749324.365558:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749324.365561:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749324.365562:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[12] = 33 (0x000000000499fe30 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749324.368071:usb_xhci_runtime_write off 0x0038, val 0x0499f158
>13236@1471749324.368076:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[12] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749324.370266:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749324.370272:usb_xhci_fetch_trb addr 000000000499fe40, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749324.370274:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749324.370279:usb_xhci_queue_event v 0, idx 13, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fe40, s 05000000, c 0x01008401
>13236@1471749324.370281:usb_xhci_irq_intx level 1
>13236@1471749324.370284:usb_xhci_fetch_trb addr 000000000499fe50, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749324.370602:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749324.370610:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749324.370612:usb_xhci_irq_intx level 1
>13236@1471749324.370615:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749324.370617:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749324.370619:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[13] = 33 (0x000000000499fe40 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749324.373221:usb_xhci_runtime_write off 0x0038, val 0x0499f168
>13236@1471749324.373226:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>usb_dma_delay_done_cb: Completed 0x7b87160
>usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: xfer=0x7b87160 endpoint=0x7b730d8 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
>xhci_device_generic_close: 
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=5
>usbd_transfer_done: err=USB_ERR_CANCELLED
>usbd_transfer_done: not transferring
>usbd_do_request_flags: I/O error - waiting a bit for TT cleanup
>usbd_do_request_flags: udev=0x7b73000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_do_request_callback: st=0
>usbd_transfer_submit: xfer=0x7b87160, endpoint=0x7b730d8, nframes=2, dir=read
>usb_dump_endpoint: endpoint=0x7b730d8 edesc=0x7b73720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
>usb_dump_queue: endpoint=0x7b730d8 xfer: 
>usbd_transfer_submit: open
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_enter: enter
>xhci_device_generic_enter: 
>xhci_dump_trb: trb = 0x7b8d600
>xhci_dump_trb: qwTrb0 = 0x0012000001000680
>xhci_dump_trb: dwTrb2 = 0x00000008
>xhci_dump_trb: dwTrb3 = 0x00030841
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d480
>xhci_dump_trb: trb = 0x7b8d610
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d480
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8d480
>xhci_dump_trb: qwTrb0 = 0x0000000007b85808
>xhci_dump_trb: dwTrb2 = 0x00020012
>xhci_dump_trb: dwTrb3 = 0x00010c15
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d300
>xhci_dump_trb: trb = 0x7b8d490
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d300
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8d300
>xhci_dump_trb: qwTrb0 = 0x0000000000000000
>xhci_dump_trb: dwTrb2 = 0x00020000
>xhci_dump_trb: dwTrb3 = 0x00001011
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d180
>xhci_dump_trb: trb = 0x7b8d310
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d180
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_setup_generic_chain: first=0x7b8d600 last=0x7b8d300
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_start: start
>xhci_device_generic_start: 
>xhci_transfer_insert: 
>xhci_dump_trb: trb = 0x7b8d310
>xhci_dump_trb: qwTrb0 = 0x0000000007b2d010
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001821
>xhci_dump_trb: trb = 0x7b2d000
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d600
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00000000
>xhci_transfer_insert: qh_pos = 0
>13236@1471749324.701735:usb_xhci_doorbell_write off 0x0004, val 0x00000001
>13236@1471749324.701739:usb_xhci_ep_kick slotid 1, epid 1, streamid 0
>xhci: set epctx: 831a020 state=1 dequeue=0000000007b2d001
>13236@1471749324.701744:usb_xhci_ep_state slotid 1, epid 1, stopped -> running
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0 (leave)
>usb_command_wrapper: cb 0 (leave)
>Root mount waiting for: usbus0
>xhci_timeout: xfer=0x7b87160
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=20
>usbd_transfer_done: err=USB_ERR_TIMEOUT
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: DMA delay, 2 ms, on 0x7b87160
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[13] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749325.205997:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749325.206003:usb_xhci_fetch_trb addr 000000000499fe50, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749325.206006:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749325.206012:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749325.206014:usb_xhci_queue_event v 0, idx 14, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe50, s 01000000, c 0x01008401
>13236@1471749325.206016:usb_xhci_irq_intx level 1
>13236@1471749325.206019:usb_xhci_fetch_trb addr 000000000499fe60, TRB_RESERVED, p 0000000000000000, s 00000000, c 0x00000000
>13236@1471749325.206341:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749325.206350:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749325.206352:usb_xhci_irq_intx level 1
>13236@1471749325.206356:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749325.206358:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749325.206360:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[14] = 33 (0x000000000499fe50 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749325.208879:usb_xhci_runtime_write off 0x0038, val 0x0499f178
>13236@1471749325.208885:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[14] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749325.211056:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749325.211063:usb_xhci_fetch_trb addr 000000000499fe60, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749325.211066:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749325.211068:usb_xhci_queue_event v 0, idx 15, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fe60, s 13000000, c 0x01008401
>13236@1471749325.211071:usb_xhci_irq_intx level 1
>13236@1471749325.211073:usb_xhci_fetch_trb addr 000000000499fe70, TR_LINK, p 000000000499fd80, s 00000000, c 0x00001803
>13236@1471749325.211075:usb_xhci_fetch_trb addr 000000000499fd80, CR_ENABLE_SLOT, p 0000000000000000, s 00000000, c 0x00002401
>13236@1471749325.211391:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749325.211399:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749325.211402:usb_xhci_irq_intx level 1
>13236@1471749325.211405:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749325.211407:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749325.211409:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[15] = 33 (0x000000000499fe60 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749325.214050:usb_xhci_runtime_write off 0x0038, val 0x0499f188
>13236@1471749325.214055:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[0] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749325.217571:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749325.217577:usb_xhci_fetch_trb addr 000000000499fd80, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014000
>13236@1471749325.217579:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749325.217584:usb_xhci_queue_event v 0, idx 16, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fd80, s 01000000, c 0x01008401
>13236@1471749325.217586:usb_xhci_irq_intx level 1
>13236@1471749325.217588:usb_xhci_fetch_trb addr 000000000499fd90, CR_ADDRESS_DEVICE, p 000000000831c000, s 00000000, c 0x01002e01
>13236@1471749325.217982:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749325.217990:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749325.217992:usb_xhci_irq_intx level 1
>13236@1471749325.217995:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749325.217997:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749325.217999:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[16] = 33 (0x000000000499fd80 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749325.220442:usb_xhci_runtime_write off 0x0038, val 0x0499f198
>13236@1471749325.220447:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[1] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749325.222711:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749325.222717:usb_xhci_fetch_trb addr 000000000499fd90, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003400
>13236@1471749325.222719:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749325.222728:usb_xhci_queue_event v 0, idx 17, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fd90, s 01000000, c 0x01008401
>13236@1471749325.222730:usb_xhci_irq_intx level 1
>13236@1471749325.222733:usb_xhci_fetch_trb addr 000000000499fda0, CR_ADDRESS_DEVICE, p 000000000831c000, s 00000000, c 0x01002c01
>13236@1471749325.223138:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749325.223146:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749325.223148:usb_xhci_irq_intx level 1
>13236@1471749325.223151:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749325.223153:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749325.223155:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[17] = 33 (0x000000000499fd90 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749325.225605:usb_xhci_runtime_write off 0x0038, val 0x0499f1a8
>13236@1471749325.225610:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[2] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749325.227768:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749325.227774:usb_xhci_fetch_trb addr 000000000499fda0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003000
>13236@1471749325.227776:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749325.227780:usb_xhci_queue_event v 0, idx 18, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fda0, s 05000000, c 0x01008401
>13236@1471749325.227783:usb_xhci_irq_intx level 1
>13236@1471749325.227785:usb_xhci_fetch_trb addr 000000000499fdb0, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749325.228206:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749325.228214:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749325.228216:usb_xhci_irq_intx level 1
>13236@1471749325.228219:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749325.228221:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749325.228223:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[18] = 33 (0x000000000499fda0 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749325.230747:usb_xhci_runtime_write off 0x0038, val 0x0499f1b8
>13236@1471749325.230752:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>usb_dma_delay_done_cb: Completed 0x7b87160
>usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: xfer=0x7b87160 endpoint=0x7b730d8 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
>xhci_device_generic_close: 
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=5
>usbd_transfer_done: err=USB_ERR_CANCELLED
>usbd_transfer_done: not transferring
>usbd_do_request_flags: I/O error - waiting a bit for TT cleanup
>usbd_do_request_flags: udev=0x7b73000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_do_request_callback: st=0
>usbd_transfer_submit: xfer=0x7b87160, endpoint=0x7b730d8, nframes=2, dir=read
>usb_dump_endpoint: endpoint=0x7b730d8 edesc=0x7b73720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
>usb_dump_queue: endpoint=0x7b730d8 xfer: 
>usbd_transfer_submit: open
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_enter: enter
>xhci_device_generic_enter: 
>xhci_dump_trb: trb = 0x7b8c600
>xhci_dump_trb: qwTrb0 = 0x0012000001000680
>xhci_dump_trb: dwTrb2 = 0x00000008
>xhci_dump_trb: dwTrb3 = 0x00030841
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c480
>xhci_dump_trb: trb = 0x7b8c610
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c480
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8c480
>xhci_dump_trb: qwTrb0 = 0x0000000007b85808
>xhci_dump_trb: dwTrb2 = 0x00020012
>xhci_dump_trb: dwTrb3 = 0x00010c15
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c300
>xhci_dump_trb: trb = 0x7b8c490
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c300
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8c300
>xhci_dump_trb: qwTrb0 = 0x0000000000000000
>xhci_dump_trb: dwTrb2 = 0x00020000
>xhci_dump_trb: dwTrb3 = 0x00001011
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c180
>xhci_dump_trb: trb = 0x7b8c310
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c180
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_setup_generic_chain: first=0x7b8c600 last=0x7b8c300
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_start: start
>xhci_device_generic_start: 
>xhci_transfer_insert: 
>xhci_dump_trb: trb = 0x7b8c310
>xhci_dump_trb: qwTrb0 = 0x0000000007b2d010
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001821
>xhci_dump_trb: trb = 0x7b2d000
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c600
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00000000
>xhci_transfer_insert: qh_pos = 0
>13236@1471749325.554614:usb_xhci_doorbell_write off 0x0004, val 0x00000001
>13236@1471749325.554618:usb_xhci_ep_kick slotid 1, epid 1, streamid 0
>xhci: set epctx: 831a020 state=1 dequeue=0000000007b2d001
>13236@1471749325.554624:usb_xhci_ep_state slotid 1, epid 1, stopped -> running
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0 (leave)
>usb_command_wrapper: cb 0 (leave)
>Root mount waiting for: usbus0
>xhci_timeout: xfer=0x7b87160
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=20
>usbd_transfer_done: err=USB_ERR_TIMEOUT
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: DMA delay, 2 ms, on 0x7b87160
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[3] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749326.073022:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749326.073031:usb_xhci_fetch_trb addr 000000000499fdb0, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c00
>13236@1471749326.073034:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749326.073039:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749326.073041:usb_xhci_queue_event v 0, idx 19, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdb0, s 01000000, c 0x01008401
>13236@1471749326.073044:usb_xhci_irq_intx level 1
>13236@1471749326.073046:usb_xhci_fetch_trb addr 000000000499fdc0, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749326.073364:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749326.073373:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749326.073375:usb_xhci_irq_intx level 1
>13236@1471749326.073379:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749326.073381:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749326.073383:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[19] = 33 (0x000000000499fdb0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749326.075911:usb_xhci_runtime_write off 0x0038, val 0x0499f1c8
>13236@1471749326.075917:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[4] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749326.078008:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749326.078014:usb_xhci_fetch_trb addr 000000000499fdc0, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013800
>13236@1471749326.078017:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749326.078019:usb_xhci_queue_event v 0, idx 20, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fdc0, s 13000000, c 0x01008401
>13236@1471749326.078022:usb_xhci_irq_intx level 1
>13236@1471749326.078024:usb_xhci_fetch_trb addr 000000000499fdd0, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749326.078337:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749326.078346:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749326.078348:usb_xhci_irq_intx level 1
>13236@1471749326.078351:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749326.078353:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749326.078355:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[20] = 33 (0x000000000499fdc0 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749326.080976:usb_xhci_runtime_write off 0x0038, val 0x0499f1d8
>13236@1471749326.080982:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[5] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749326.084467:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749326.084473:usb_xhci_fetch_trb addr 000000000499fdd0, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014000
>13236@1471749326.084476:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749326.084482:usb_xhci_queue_event v 0, idx 21, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdd0, s 01000000, c 0x01008401
>13236@1471749326.084485:usb_xhci_irq_intx level 1
>13236@1471749326.084487:usb_xhci_fetch_trb addr 000000000499fde0, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749326.084883:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749326.084890:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749326.084892:usb_xhci_irq_intx level 1
>13236@1471749326.084895:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749326.084898:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749326.084899:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[21] = 33 (0x000000000499fdd0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749326.087402:usb_xhci_runtime_write off 0x0038, val 0x0499f1e8
>13236@1471749326.087407:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[6] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749326.089595:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749326.089600:usb_xhci_fetch_trb addr 000000000499fde0, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003400
>13236@1471749326.089603:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749326.089612:usb_xhci_queue_event v 0, idx 22, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fde0, s 01000000, c 0x01008401
>13236@1471749326.089614:usb_xhci_irq_intx level 1
>13236@1471749326.089616:usb_xhci_fetch_trb addr 000000000499fdf0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749326.090011:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749326.090019:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749326.090021:usb_xhci_irq_intx level 1
>13236@1471749326.090024:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749326.090027:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749326.090028:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[22] = 33 (0x000000000499fde0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749326.092496:usb_xhci_runtime_write off 0x0038, val 0x0499f1f8
>13236@1471749326.092501:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[7] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749326.094725:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749326.094731:usb_xhci_fetch_trb addr 000000000499fdf0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003000
>13236@1471749326.094733:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749326.094737:usb_xhci_queue_event v 0, idx 23, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fdf0, s 05000000, c 0x01008401
>13236@1471749326.094739:usb_xhci_irq_intx level 1
>13236@1471749326.094741:usb_xhci_fetch_trb addr 000000000499fe00, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749326.095253:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749326.095260:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749326.095262:usb_xhci_irq_intx level 1
>13236@1471749326.095265:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749326.095268:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749326.095269:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[23] = 33 (0x000000000499fdf0 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749326.097737:usb_xhci_runtime_write off 0x0038, val 0x0499f208
>13236@1471749326.097742:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>usb_dma_delay_done_cb: Completed 0x7b87160
>usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: xfer=0x7b87160 endpoint=0x7b730d8 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
>xhci_device_generic_close: 
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=5
>usbd_transfer_done: err=USB_ERR_CANCELLED
>usbd_transfer_done: not transferring
>usbd_do_request_flags: I/O error - waiting a bit for TT cleanup
>usbd_do_request_flags: udev=0x7b73000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_do_request_callback: st=0
>usbd_transfer_submit: xfer=0x7b87160, endpoint=0x7b730d8, nframes=2, dir=read
>usb_dump_endpoint: endpoint=0x7b730d8 edesc=0x7b73720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
>usb_dump_queue: endpoint=0x7b730d8 xfer: 
>usbd_transfer_submit: open
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_enter: enter
>xhci_device_generic_enter: 
>xhci_dump_trb: trb = 0x7b8d600
>xhci_dump_trb: qwTrb0 = 0x0012000001000680
>xhci_dump_trb: dwTrb2 = 0x00000008
>xhci_dump_trb: dwTrb3 = 0x00030841
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d480
>xhci_dump_trb: trb = 0x7b8d610
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d480
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8d480
>xhci_dump_trb: qwTrb0 = 0x0000000007b85808
>xhci_dump_trb: dwTrb2 = 0x00020012
>xhci_dump_trb: dwTrb3 = 0x00010c15
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d300
>xhci_dump_trb: trb = 0x7b8d490
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d300
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8d300
>xhci_dump_trb: qwTrb0 = 0x0000000000000000
>xhci_dump_trb: dwTrb2 = 0x00020000
>xhci_dump_trb: dwTrb3 = 0x00001011
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d180
>xhci_dump_trb: trb = 0x7b8d310
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d180
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_setup_generic_chain: first=0x7b8d600 last=0x7b8d300
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_start: start
>xhci_device_generic_start: 
>xhci_transfer_insert: 
>xhci_dump_trb: trb = 0x7b8d310
>xhci_dump_trb: qwTrb0 = 0x0000000007b2d010
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001821
>xhci_dump_trb: trb = 0x7b2d000
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d600
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00000000
>xhci_transfer_insert: qh_pos = 0
>13236@1471749326.422228:usb_xhci_doorbell_write off 0x0004, val 0x00000001
>13236@1471749326.422233:usb_xhci_ep_kick slotid 1, epid 1, streamid 0
>xhci: set epctx: 831a020 state=1 dequeue=0000000007b2d001
>13236@1471749326.422238:usb_xhci_ep_state slotid 1, epid 1, stopped -> running
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0 (leave)
>usb_command_wrapper: cb 0 (leave)
>Root mount waiting for: usbus0
>xhci_timeout: xfer=0x7b87160
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=20
>usbd_transfer_done: err=USB_ERR_TIMEOUT
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: DMA delay, 2 ms, on 0x7b87160
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[8] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749326.927141:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749326.927147:usb_xhci_fetch_trb addr 000000000499fe00, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c00
>13236@1471749326.927150:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749326.927155:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749326.927157:usb_xhci_queue_event v 0, idx 24, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe00, s 01000000, c 0x01008401
>13236@1471749326.927159:usb_xhci_irq_intx level 1
>13236@1471749326.927162:usb_xhci_fetch_trb addr 000000000499fe10, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749326.927483:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749326.927491:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749326.927493:usb_xhci_irq_intx level 1
>13236@1471749326.927496:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749326.927499:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749326.927500:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[24] = 33 (0x000000000499fe00 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749326.930102:usb_xhci_runtime_write off 0x0038, val 0x0499f218
>13236@1471749326.930107:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[9] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749326.932245:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749326.932250:usb_xhci_fetch_trb addr 000000000499fe10, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013800
>13236@1471749326.932252:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749326.932255:usb_xhci_queue_event v 0, idx 25, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fe10, s 13000000, c 0x01008401
>13236@1471749326.932257:usb_xhci_irq_intx level 1
>13236@1471749326.932259:usb_xhci_fetch_trb addr 000000000499fe20, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749326.932577:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749326.932585:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749326.932587:usb_xhci_irq_intx level 1
>13236@1471749326.932590:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749326.932593:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749326.932594:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[25] = 33 (0x000000000499fe10 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749326.935125:usb_xhci_runtime_write off 0x0038, val 0x0499f228
>13236@1471749326.935130:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[10] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749326.938789:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749326.938795:usb_xhci_fetch_trb addr 000000000499fe20, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014000
>13236@1471749326.938797:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749326.938801:usb_xhci_queue_event v 0, idx 26, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe20, s 01000000, c 0x01008401
>13236@1471749326.938803:usb_xhci_irq_intx level 1
>13236@1471749326.938805:usb_xhci_fetch_trb addr 000000000499fe30, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749326.939196:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749326.939207:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749326.939208:usb_xhci_irq_intx level 1
>13236@1471749326.939211:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749326.939214:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749326.939215:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[26] = 33 (0x000000000499fe20 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749326.941660:usb_xhci_runtime_write off 0x0038, val 0x0499f238
>13236@1471749326.941665:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[11] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749326.943944:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749326.943950:usb_xhci_fetch_trb addr 000000000499fe30, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003400
>13236@1471749326.943952:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749326.943961:usb_xhci_queue_event v 0, idx 27, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe30, s 01000000, c 0x01008401
>13236@1471749326.943964:usb_xhci_irq_intx level 1
>13236@1471749326.943966:usb_xhci_fetch_trb addr 000000000499fe40, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749326.944279:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749326.944287:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749326.944290:usb_xhci_irq_intx level 1
>13236@1471749326.944293:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749326.944296:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749326.944298:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[27] = 33 (0x000000000499fe30 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749326.946903:usb_xhci_runtime_write off 0x0038, val 0x0499f248
>13236@1471749326.946932:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[12] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749326.949159:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749326.949164:usb_xhci_fetch_trb addr 000000000499fe40, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003000
>13236@1471749326.949167:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749326.949171:usb_xhci_queue_event v 0, idx 28, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fe40, s 05000000, c 0x01008401
>13236@1471749326.949173:usb_xhci_irq_intx level 1
>13236@1471749326.949175:usb_xhci_fetch_trb addr 000000000499fe50, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749326.949492:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749326.949499:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749326.949501:usb_xhci_irq_intx level 1
>13236@1471749326.949504:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749326.949506:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749326.949508:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[28] = 33 (0x000000000499fe40 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749326.952057:usb_xhci_runtime_write off 0x0038, val 0x0499f258
>13236@1471749326.952062:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>usb_dma_delay_done_cb: Completed 0x7b87160
>usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: xfer=0x7b87160 endpoint=0x7b730d8 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
>xhci_device_generic_close: 
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=5
>usbd_transfer_done: err=USB_ERR_CANCELLED
>usbd_transfer_done: not transferring
>usbd_do_request_flags: I/O error - waiting a bit for TT cleanup
>usbd_req_get_device_desc: 
>usbd_req_get_desc: id=0, type=1, index=0, max_len=18
>usbd_do_request_flags: udev=0x7b73000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_do_request_callback: st=0
>usbd_transfer_submit: xfer=0x7b87160, endpoint=0x7b730d8, nframes=2, dir=read
>usb_dump_endpoint: endpoint=0x7b730d8 edesc=0x7b73720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
>usb_dump_queue: endpoint=0x7b730d8 xfer: 
>usbd_transfer_submit: open
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_enter: enter
>xhci_device_generic_enter: 
>xhci_dump_trb: trb = 0x7b8c600
>xhci_dump_trb: qwTrb0 = 0x0012000001000680
>xhci_dump_trb: dwTrb2 = 0x00000008
>xhci_dump_trb: dwTrb3 = 0x00030841
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c480
>xhci_dump_trb: trb = 0x7b8c610
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c480
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8c480
>xhci_dump_trb: qwTrb0 = 0x0000000007b85808
>xhci_dump_trb: dwTrb2 = 0x00020012
>xhci_dump_trb: dwTrb3 = 0x00010c15
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c300
>xhci_dump_trb: trb = 0x7b8c490
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c300
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8c300
>xhci_dump_trb: qwTrb0 = 0x0000000000000000
>xhci_dump_trb: dwTrb2 = 0x00020000
>xhci_dump_trb: dwTrb3 = 0x00001011
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c180
>xhci_dump_trb: trb = 0x7b8c310
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c180
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_setup_generic_chain: first=0x7b8c600 last=0x7b8c300
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_start: start
>xhci_device_generic_start: 
>xhci_transfer_insert: 
>xhci_dump_trb: trb = 0x7b8c310
>xhci_dump_trb: qwTrb0 = 0x0000000007b2d010
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001821
>xhci_dump_trb: trb = 0x7b2d000
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c600
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00000000
>xhci_transfer_insert: qh_pos = 0
>13236@1471749327.076198:usb_xhci_doorbell_write off 0x0004, val 0x00000001
>13236@1471749327.076202:usb_xhci_ep_kick slotid 1, epid 1, streamid 0
>xhci: set epctx: 831a020 state=1 dequeue=0000000007b2d001
>13236@1471749327.076207:usb_xhci_ep_state slotid 1, epid 1, stopped -> running
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0 (leave)
>usb_command_wrapper: cb 0 (leave)
>xhci_timeout: xfer=0x7b87160
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=20
>usbd_transfer_done: err=USB_ERR_TIMEOUT
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: DMA delay, 2 ms, on 0x7b87160
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[13] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749327.581105:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749327.581111:usb_xhci_fetch_trb addr 000000000499fe50, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c00
>13236@1471749327.581114:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749327.581119:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749327.581121:usb_xhci_queue_event v 0, idx 29, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe50, s 01000000, c 0x01008401
>13236@1471749327.581124:usb_xhci_irq_intx level 1
>13236@1471749327.581126:usb_xhci_fetch_trb addr 000000000499fe60, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749327.581442:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749327.581450:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749327.581453:usb_xhci_irq_intx level 1
>13236@1471749327.581456:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749327.581459:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749327.581460:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[29] = 33 (0x000000000499fe50 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749327.584087:usb_xhci_runtime_write off 0x0038, val 0x0499f268
>13236@1471749327.584093:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[14] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749327.586195:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749327.586200:usb_xhci_fetch_trb addr 000000000499fe60, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013800
>13236@1471749327.586203:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749327.586205:usb_xhci_queue_event v 0, idx 30, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fe60, s 13000000, c 0x01008401
>13236@1471749327.586208:usb_xhci_irq_intx level 1
>13236@1471749327.586210:usb_xhci_fetch_trb addr 000000000499fe70, TR_LINK, p 000000000499fd80, s 00000000, c 0x00001802
>13236@1471749327.586212:usb_xhci_fetch_trb addr 000000000499fd80, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014000
>13236@1471749327.586530:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749327.586537:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749327.586539:usb_xhci_irq_intx level 1
>13236@1471749327.586542:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749327.586545:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749327.586546:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[30] = 33 (0x000000000499fe60 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749327.589128:usb_xhci_runtime_write off 0x0038, val 0x0499f278
>13236@1471749327.589133:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[0] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749327.592762:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749327.592767:usb_xhci_fetch_trb addr 000000000499fd80, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749327.592770:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749327.592773:usb_xhci_queue_event v 0, idx 31, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fd80, s 01000000, c 0x01008401
>13236@1471749327.592776:usb_xhci_irq_intx level 1
>13236@1471749327.592778:usb_xhci_fetch_trb addr 000000000499fd90, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003400
>13236@1471749327.593199:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749327.593208:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749327.593209:usb_xhci_irq_intx level 1
>13236@1471749327.593212:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749327.593215:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749327.593216:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[31] = 33 (0x000000000499fd80 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749327.595693:usb_xhci_runtime_write off 0x0038, val 0x0499f288
>13236@1471749327.595698:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[1] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749327.597945:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749327.597951:usb_xhci_fetch_trb addr 000000000499fd90, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749327.597953:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749327.597962:usb_xhci_queue_event v 0, idx 32, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fd90, s 01000000, c 0x01008401
>13236@1471749327.597964:usb_xhci_irq_intx level 1
>13236@1471749327.597966:usb_xhci_fetch_trb addr 000000000499fda0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003000
>13236@1471749327.598280:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749327.598288:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749327.598291:usb_xhci_irq_intx level 1
>13236@1471749327.598294:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749327.598297:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749327.598298:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[32] = 33 (0x000000000499fd90 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749327.600940:usb_xhci_runtime_write off 0x0038, val 0x0499f298
>13236@1471749327.600948:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[2] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749327.603092:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749327.603098:usb_xhci_fetch_trb addr 000000000499fda0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749327.603100:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749327.603104:usb_xhci_queue_event v 0, idx 33, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fda0, s 05000000, c 0x01008401
>13236@1471749327.603107:usb_xhci_irq_intx level 1
>13236@1471749327.603109:usb_xhci_fetch_trb addr 000000000499fdb0, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c00
>13236@1471749327.603419:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749327.603427:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749327.603430:usb_xhci_irq_intx level 1
>13236@1471749327.603433:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749327.603436:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749327.603437:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[33] = 33 (0x000000000499fda0 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749327.605994:usb_xhci_runtime_write off 0x0038, val 0x0499f2a8
>13236@1471749327.605999:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>usb_dma_delay_done_cb: Completed 0x7b87160
>usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: xfer=0x7b87160 endpoint=0x7b730d8 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
>xhci_device_generic_close: 
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=5
>usbd_transfer_done: err=USB_ERR_CANCELLED
>usbd_transfer_done: not transferring
>usbd_do_request_flags: I/O error - waiting a bit for TT cleanup
>Root mount waiting for: usbus0
>usbd_do_request_flags: udev=0x7b73000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_do_request_callback: st=0
>usbd_transfer_submit: xfer=0x7b87160, endpoint=0x7b730d8, nframes=2, dir=read
>usb_dump_endpoint: endpoint=0x7b730d8 edesc=0x7b73720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
>usb_dump_queue: endpoint=0x7b730d8 xfer: 
>usbd_transfer_submit: open
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_enter: enter
>xhci_device_generic_enter: 
>xhci_dump_trb: trb = 0x7b8d600
>xhci_dump_trb: qwTrb0 = 0x0012000001000680
>xhci_dump_trb: dwTrb2 = 0x00000008
>xhci_dump_trb: dwTrb3 = 0x00030841
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d480
>xhci_dump_trb: trb = 0x7b8d610
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d480
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8d480
>xhci_dump_trb: qwTrb0 = 0x0000000007b85808
>xhci_dump_trb: dwTrb2 = 0x00020012
>xhci_dump_trb: dwTrb3 = 0x00010c15
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d300
>xhci_dump_trb: trb = 0x7b8d490
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d300
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8d300
>xhci_dump_trb: qwTrb0 = 0x0000000000000000
>xhci_dump_trb: dwTrb2 = 0x00020000
>xhci_dump_trb: dwTrb3 = 0x00001011
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d180
>xhci_dump_trb: trb = 0x7b8d310
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d180
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_setup_generic_chain: first=0x7b8d600 last=0x7b8d300
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_start: start
>xhci_device_generic_start: 
>xhci_transfer_insert: 
>xhci_dump_trb: trb = 0x7b8d310
>xhci_dump_trb: qwTrb0 = 0x0000000007b2d010
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001821
>xhci_dump_trb: trb = 0x7b2d000
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d600
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00000000
>xhci_transfer_insert: qh_pos = 0
>13236@1471749327.930354:usb_xhci_doorbell_write off 0x0004, val 0x00000001
>13236@1471749327.930358:usb_xhci_ep_kick slotid 1, epid 1, streamid 0
>xhci: set epctx: 831a020 state=1 dequeue=0000000007b2d001
>13236@1471749327.930363:usb_xhci_ep_state slotid 1, epid 1, stopped -> running
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0 (leave)
>usb_command_wrapper: cb 0 (leave)
>xhci_timeout: xfer=0x7b87160
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=20
>usbd_transfer_done: err=USB_ERR_TIMEOUT
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: DMA delay, 2 ms, on 0x7b87160
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[3] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749328.447087:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749328.447093:usb_xhci_fetch_trb addr 000000000499fdb0, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749328.447096:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749328.447102:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749328.447104:usb_xhci_queue_event v 0, idx 34, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdb0, s 01000000, c 0x01008401
>13236@1471749328.447106:usb_xhci_irq_intx level 1
>13236@1471749328.447108:usb_xhci_fetch_trb addr 000000000499fdc0, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013800
>13236@1471749328.447427:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749328.447435:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749328.447437:usb_xhci_irq_intx level 1
>13236@1471749328.447440:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749328.447443:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749328.447444:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[34] = 33 (0x000000000499fdb0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749328.450094:usb_xhci_runtime_write off 0x0038, val 0x0499f2b8
>13236@1471749328.450099:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[4] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749328.452198:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749328.452203:usb_xhci_fetch_trb addr 000000000499fdc0, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749328.452206:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749328.452208:usb_xhci_queue_event v 0, idx 35, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fdc0, s 13000000, c 0x01008401
>13236@1471749328.452211:usb_xhci_irq_intx level 1
>13236@1471749328.452213:usb_xhci_fetch_trb addr 000000000499fdd0, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014000
>13236@1471749328.452531:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749328.452539:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749328.452540:usb_xhci_irq_intx level 1
>13236@1471749328.452543:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749328.452546:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749328.452548:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[35] = 33 (0x000000000499fdc0 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749328.455111:usb_xhci_runtime_write off 0x0038, val 0x0499f2c8
>13236@1471749328.455116:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[5] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749328.458682:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749328.458688:usb_xhci_fetch_trb addr 000000000499fdd0, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749328.458690:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749328.458694:usb_xhci_queue_event v 0, idx 36, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdd0, s 01000000, c 0x01008401
>13236@1471749328.458697:usb_xhci_irq_intx level 1
>13236@1471749328.458699:usb_xhci_fetch_trb addr 000000000499fde0, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003400
>13236@1471749328.459097:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749328.459105:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749328.459106:usb_xhci_irq_intx level 1
>13236@1471749328.459110:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749328.459112:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749328.459113:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[36] = 33 (0x000000000499fdd0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749328.461576:usb_xhci_runtime_write off 0x0038, val 0x0499f2d8
>13236@1471749328.461581:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[6] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749328.463750:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749328.463755:usb_xhci_fetch_trb addr 000000000499fde0, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749328.463757:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749328.463766:usb_xhci_queue_event v 0, idx 37, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fde0, s 01000000, c 0x01008401
>13236@1471749328.463769:usb_xhci_irq_intx level 1
>13236@1471749328.463771:usb_xhci_fetch_trb addr 000000000499fdf0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003000
>13236@1471749328.464175:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749328.464183:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749328.464185:usb_xhci_irq_intx level 1
>13236@1471749328.464188:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749328.464191:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749328.464192:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[37] = 33 (0x000000000499fde0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749328.466751:usb_xhci_runtime_write off 0x0038, val 0x0499f2e8
>13236@1471749328.466757:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[7] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749328.468994:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749328.469000:usb_xhci_fetch_trb addr 000000000499fdf0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749328.469002:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749328.469007:usb_xhci_queue_event v 0, idx 38, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fdf0, s 05000000, c 0x01008401
>13236@1471749328.469009:usb_xhci_irq_intx level 1
>13236@1471749328.469011:usb_xhci_fetch_trb addr 000000000499fe00, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c00
>13236@1471749328.469323:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749328.469330:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749328.469333:usb_xhci_irq_intx level 1
>13236@1471749328.469336:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749328.469339:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749328.469340:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[38] = 33 (0x000000000499fdf0 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749328.471928:usb_xhci_runtime_write off 0x0038, val 0x0499f2f8
>13236@1471749328.471934:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>usb_dma_delay_done_cb: Completed 0x7b87160
>usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: xfer=0x7b87160 endpoint=0x7b730d8 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
>xhci_device_generic_close: 
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=5
>usbd_transfer_done: err=USB_ERR_CANCELLED
>usbd_transfer_done: not transferring
>usbd_do_request_flags: I/O error - waiting a bit for TT cleanup
>Root mount waiting for: usbus0
>usbd_do_request_flags: udev=0x7b73000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_do_request_callback: st=0
>usbd_transfer_submit: xfer=0x7b87160, endpoint=0x7b730d8, nframes=2, dir=read
>usb_dump_endpoint: endpoint=0x7b730d8 edesc=0x7b73720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
>usb_dump_queue: endpoint=0x7b730d8 xfer: 
>usbd_transfer_submit: open
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_enter: enter
>xhci_device_generic_enter: 
>xhci_dump_trb: trb = 0x7b8c600
>xhci_dump_trb: qwTrb0 = 0x0012000001000680
>xhci_dump_trb: dwTrb2 = 0x00000008
>xhci_dump_trb: dwTrb3 = 0x00030841
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c480
>xhci_dump_trb: trb = 0x7b8c610
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c480
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8c480
>xhci_dump_trb: qwTrb0 = 0x0000000007b85808
>xhci_dump_trb: dwTrb2 = 0x00020012
>xhci_dump_trb: dwTrb3 = 0x00010c15
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c300
>xhci_dump_trb: trb = 0x7b8c490
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c300
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8c300
>xhci_dump_trb: qwTrb0 = 0x0000000000000000
>xhci_dump_trb: dwTrb2 = 0x00020000
>xhci_dump_trb: dwTrb3 = 0x00001011
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c180
>xhci_dump_trb: trb = 0x7b8c310
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c180
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_setup_generic_chain: first=0x7b8c600 last=0x7b8c300
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_start: start
>xhci_device_generic_start: 
>xhci_transfer_insert: 
>xhci_dump_trb: trb = 0x7b8c310
>xhci_dump_trb: qwTrb0 = 0x0000000007b2d010
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001821
>xhci_dump_trb: trb = 0x7b2d000
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c600
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00000000
>xhci_transfer_insert: qh_pos = 0
>13236@1471749328.796097:usb_xhci_doorbell_write off 0x0004, val 0x00000001
>13236@1471749328.796102:usb_xhci_ep_kick slotid 1, epid 1, streamid 0
>xhci: set epctx: 831a020 state=1 dequeue=0000000007b2d001
>13236@1471749328.796107:usb_xhci_ep_state slotid 1, epid 1, stopped -> running
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0 (leave)
>usb_command_wrapper: cb 0 (leave)
>xhci_timeout: xfer=0x7b87160
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=20
>usbd_transfer_done: err=USB_ERR_TIMEOUT
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: DMA delay, 2 ms, on 0x7b87160
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[8] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749329.319994:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749329.320003:usb_xhci_fetch_trb addr 000000000499fe00, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749329.320007:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749329.320016:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749329.320019:usb_xhci_queue_event v 0, idx 39, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe00, s 01000000, c 0x01008401
>13236@1471749329.320023:usb_xhci_irq_intx level 1
>13236@1471749329.320027:usb_xhci_fetch_trb addr 000000000499fe10, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013800
>13236@1471749329.320357:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749329.320368:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749329.320371:usb_xhci_irq_intx level 1
>13236@1471749329.320376:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749329.320381:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749329.320385:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[39] = 33 (0x000000000499fe00 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749329.324156:usb_xhci_runtime_write off 0x0038, val 0x0499f308
>13236@1471749329.324163:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[9] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749329.327254:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749329.327262:usb_xhci_fetch_trb addr 000000000499fe10, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749329.327265:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749329.327269:usb_xhci_queue_event v 0, idx 40, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fe10, s 13000000, c 0x01008401
>13236@1471749329.327274:usb_xhci_irq_intx level 1
>13236@1471749329.327278:usb_xhci_fetch_trb addr 000000000499fe20, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014000
>13236@1471749329.327607:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749329.327618:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749329.327621:usb_xhci_irq_intx level 1
>13236@1471749329.327626:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749329.327631:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749329.327634:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[40] = 33 (0x000000000499fe10 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749329.331399:usb_xhci_runtime_write off 0x0038, val 0x0499f318
>13236@1471749329.331406:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[10] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749329.336545:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749329.336552:usb_xhci_fetch_trb addr 000000000499fe20, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749329.336556:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749329.336562:usb_xhci_queue_event v 0, idx 41, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe20, s 01000000, c 0x01008401
>13236@1471749329.336566:usb_xhci_irq_intx level 1
>13236@1471749329.336570:usb_xhci_fetch_trb addr 000000000499fe30, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003400
>13236@1471749329.337025:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749329.337033:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749329.337036:usb_xhci_irq_intx level 1
>13236@1471749329.337041:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749329.337046:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749329.337049:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[41] = 33 (0x000000000499fe20 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749329.340648:usb_xhci_runtime_write off 0x0038, val 0x0499f328
>13236@1471749329.340654:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[11] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749329.343767:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749329.343774:usb_xhci_fetch_trb addr 000000000499fe30, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749329.343778:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749329.343794:usb_xhci_queue_event v 0, idx 42, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe30, s 01000000, c 0x01008401
>13236@1471749329.343798:usb_xhci_irq_intx level 1
>13236@1471749329.343802:usb_xhci_fetch_trb addr 000000000499fe40, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003000
>13236@1471749329.344237:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749329.344247:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749329.344250:usb_xhci_irq_intx level 1
>13236@1471749329.344255:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749329.344260:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749329.344262:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[42] = 33 (0x000000000499fe30 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749329.348027:usb_xhci_runtime_write off 0x0038, val 0x0499f338
>13236@1471749329.348033:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[12] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749329.351228:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749329.351235:usb_xhci_fetch_trb addr 000000000499fe40, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749329.351238:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749329.351246:usb_xhci_queue_event v 0, idx 43, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fe40, s 05000000, c 0x01008401
>13236@1471749329.351250:usb_xhci_irq_intx level 1
>13236@1471749329.351253:usb_xhci_fetch_trb addr 000000000499fe50, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c00
>13236@1471749329.351577:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749329.351587:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749329.351590:usb_xhci_irq_intx level 1
>13236@1471749329.351595:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749329.351600:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749329.351603:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[43] = 33 (0x000000000499fe40 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749329.355242:usb_xhci_runtime_write off 0x0038, val 0x0499f348
>13236@1471749329.355249:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>usb_dma_delay_done_cb: Completed 0x7b87160
>usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: xfer=0x7b87160 endpoint=0x7b730d8 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
>xhci_device_generic_close: 
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=5
>usbd_transfer_done: err=USB_ERR_CANCELLED
>usbd_transfer_done: not transferring
>usbd_do_request_flags: I/O error - waiting a bit for TT cleanup
>usbd_do_request_flags: udev=0x7b73000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_do_request_callback: st=0
>usbd_transfer_submit: xfer=0x7b87160, endpoint=0x7b730d8, nframes=2, dir=read
>usb_dump_endpoint: endpoint=0x7b730d8 edesc=0x7b73720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
>usb_dump_queue: endpoint=0x7b730d8 xfer: 
>usbd_transfer_submit: open
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_enter: enter
>xhci_device_generic_enter: 
>xhci_dump_trb: trb = 0x7b8d600
>xhci_dump_trb: qwTrb0 = 0x0012000001000680
>xhci_dump_trb: dwTrb2 = 0x00000008
>xhci_dump_trb: dwTrb3 = 0x00030841
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d480
>xhci_dump_trb: trb = 0x7b8d610
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d480
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8d480
>xhci_dump_trb: qwTrb0 = 0x0000000007b85808
>xhci_dump_trb: dwTrb2 = 0x00020012
>xhci_dump_trb: dwTrb3 = 0x00010c15
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d300
>xhci_dump_trb: trb = 0x7b8d490
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d300
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8d300
>xhci_dump_trb: qwTrb0 = 0x0000000000000000
>xhci_dump_trb: dwTrb2 = 0x00020000
>xhci_dump_trb: dwTrb3 = 0x00001011
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d180
>xhci_dump_trb: trb = 0x7b8d310
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d180
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_setup_generic_chain: first=0x7b8d600 last=0x7b8d300
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_start: start
>xhci_device_generic_start: 
>xhci_transfer_insert: 
>xhci_dump_trb: trb = 0x7b8d310
>xhci_dump_trb: qwTrb0 = 0x0000000007b2d010
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001821
>xhci_dump_trb: trb = 0x7b2d000
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d600
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00000000
>xhci_transfer_insert: qh_pos = 0
>13236@1471749329.679056:usb_xhci_doorbell_write off 0x0004, val 0x00000001
>13236@1471749329.679061:usb_xhci_ep_kick slotid 1, epid 1, streamid 0
>xhci: set epctx: 831a020 state=1 dequeue=0000000007b2d001
>13236@1471749329.679066:usb_xhci_ep_state slotid 1, epid 1, stopped -> running
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0 (leave)
>usb_command_wrapper: cb 0 (leave)
>Root mount waiting for: usbus0
>xhci_timeout: xfer=0x7b87160
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=20
>usbd_transfer_done: err=USB_ERR_TIMEOUT
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: DMA delay, 2 ms, on 0x7b87160
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[13] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749330.183680:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.183686:usb_xhci_fetch_trb addr 000000000499fe50, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749330.183689:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749330.183694:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749330.183696:usb_xhci_queue_event v 0, idx 44, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe50, s 01000000, c 0x01008401
>13236@1471749330.183699:usb_xhci_irq_intx level 1
>13236@1471749330.183701:usb_xhci_fetch_trb addr 000000000499fe60, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013800
>13236@1471749330.184133:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.184141:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.184143:usb_xhci_irq_intx level 1
>13236@1471749330.184146:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.184149:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.184151:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[44] = 33 (0x000000000499fe50 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.186570:usb_xhci_runtime_write off 0x0038, val 0x0499f358
>13236@1471749330.186576:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[14] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749330.188682:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.188687:usb_xhci_fetch_trb addr 000000000499fe60, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749330.188690:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749330.188693:usb_xhci_queue_event v 0, idx 45, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fe60, s 13000000, c 0x01008401
>13236@1471749330.188695:usb_xhci_irq_intx level 1
>13236@1471749330.188697:usb_xhci_fetch_trb addr 000000000499fe70, TR_LINK, p 000000000499fd80, s 00000000, c 0x00001803
>13236@1471749330.188699:usb_xhci_fetch_trb addr 000000000499fd80, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749330.189096:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.189107:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.189108:usb_xhci_irq_intx level 1
>13236@1471749330.189112:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.189114:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.189116:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[45] = 33 (0x000000000499fe60 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.191636:usb_xhci_runtime_write off 0x0038, val 0x0499f368
>13236@1471749330.191641:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[0] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749330.195186:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.195192:usb_xhci_fetch_trb addr 000000000499fd80, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014000
>13236@1471749330.195194:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749330.195198:usb_xhci_queue_event v 0, idx 46, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fd80, s 01000000, c 0x01008401
>13236@1471749330.195200:usb_xhci_irq_intx level 1
>13236@1471749330.195203:usb_xhci_fetch_trb addr 000000000499fd90, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749330.195517:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.195525:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.195526:usb_xhci_irq_intx level 1
>13236@1471749330.195529:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.195532:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.195533:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[46] = 33 (0x000000000499fd80 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.198356:usb_xhci_runtime_write off 0x0038, val 0x0499f378
>13236@1471749330.198362:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[1] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749330.200494:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.200500:usb_xhci_fetch_trb addr 000000000499fd90, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003400
>13236@1471749330.200502:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749330.200514:usb_xhci_queue_event v 0, idx 47, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fd90, s 01000000, c 0x01008401
>13236@1471749330.200516:usb_xhci_irq_intx level 1
>13236@1471749330.200519:usb_xhci_fetch_trb addr 000000000499fda0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749330.200924:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.200931:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.200933:usb_xhci_irq_intx level 1
>13236@1471749330.200936:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.200938:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.200940:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[47] = 33 (0x000000000499fd90 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.203331:usb_xhci_runtime_write off 0x0038, val 0x0499f388
>13236@1471749330.203336:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[2] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749330.205562:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.205567:usb_xhci_fetch_trb addr 000000000499fda0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003000
>13236@1471749330.205569:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749330.205573:usb_xhci_queue_event v 0, idx 48, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fda0, s 05000000, c 0x01008401
>13236@1471749330.205576:usb_xhci_irq_intx level 1
>13236@1471749330.205578:usb_xhci_fetch_trb addr 000000000499fdb0, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749330.206005:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.206012:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.206014:usb_xhci_irq_intx level 1
>13236@1471749330.206017:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.206019:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.206021:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[48] = 33 (0x000000000499fda0 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.208418:usb_xhci_runtime_write off 0x0038, val 0x0499f398
>13236@1471749330.208423:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>usb_dma_delay_done_cb: Completed 0x7b87160
>usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: xfer=0x7b87160 endpoint=0x7b730d8 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
>xhci_device_generic_close: 
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=5
>usbd_transfer_done: err=USB_ERR_CANCELLED
>usbd_transfer_done: not transferring
>usbd_do_request_flags: I/O error - waiting a bit for TT cleanup
>usbd_setup_device_desc: getting device descriptor at addr 1 failed, USB_ERR_TIMEOUT
>usbd_req_warm_reset_port: 
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0005 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0005
>xhci_roothub_exec: UR_GET_STATUS i=5
>13236@1471749330.293079:usb_xhci_port_read port 5, off 0x0000, ret 0x00000e03
>xhci_roothub_exec: port status=0x00000e03
>usbd_req_warm_reset_port: Wrong state for warm reset
>usbd_req_reset_port: 
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x01 wValue=0x0014 wIndex=0x0005 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x01 wLen=0x0000 wValue=0x0014 wIndex=0x0005
>xhci_roothub_exec: UR_CLEAR_PORT_FEATURE
>13236@1471749330.301191:usb_xhci_port_read port 5, off 0x0000, ret 0x00000e03
>13236@1471749330.301198:usb_xhci_port_write port 5, off 0x0000, val 0x00200e00
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x03 wValue=0x0004 wIndex=0x0005 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x03 wLen=0x0000 wValue=0x0004 wIndex=0x0005
>13236@1471749330.306058:usb_xhci_port_read port 5, off 0x0000, ret 0x00000e03
>xhci_roothub_exec: reset port 5
>13236@1471749330.306637:usb_xhci_port_write port 5, off 0x0000, val 0x00000e10
>13236@1471749330.306641:usb_xhci_port_reset port 5, warm 0
>13236@1471749330.306642:usb_xhci_port_link port 5, pls 0
>13236@1471749330.306644:usb_xhci_port_notify port 5, bits 200000
>13236@1471749330.306645:usb_xhci_queue_event v 0, idx 49, ER_PORT_STATUS_CHANGE, CC_SUCCESS, p 0000000005000000, s 01000000, c 0x00008801
>13236@1471749330.306648:usb_xhci_irq_intx level 1
>13236@1471749330.307021:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.307029:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.307030:usb_xhci_irq_intx level 1
>13236@1471749330.307033:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.307036:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.307037:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[49] = 34 (0x0000000005000000 0x01000000 0x00008801)
>xhci_interrupt_poll: Unhandled event = 34
>13236@1471749330.309489:usb_xhci_runtime_write off 0x0038, val 0x0499f3a8
>13236@1471749330.309493:usb_xhci_runtime_write off 0x003c, val 0x00000000
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0xa3 bRequest=0x00 wValue=0x0000 wIndex=0x0005 wLength=0x0004
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0005
>xhci_roothub_exec: UR_GET_STATUS i=5
>13236@1471749330.364518:usb_xhci_port_read port 5, off 0x0000, ret 0x00200e03
>xhci_roothub_exec: port status=0x00200e03
>usbd_do_request_flags: udev=0x7b15000 bmRequestType=0x23 bRequest=0x01 wValue=0x0014 wIndex=0x0005 wLength=0x0000
>usbd_do_request_flags: Handle Request function is set
>xhci_roothub_exec: type=0x23 request=0x01 wLen=0x0000 wValue=0x0014 wIndex=0x0005
>xhci_roothub_exec: UR_CLEAR_PORT_FEATURE
>13236@1471749330.371170:usb_xhci_port_read port 5, off 0x0000, ret 0x00200e03
>13236@1471749330.371176:usb_xhci_port_write port 5, off 0x0000, val 0x00200e00
>usbd_req_reset_port: port 5 reset returning error=USB_ERR_NORMAL_COMPLETION
>usb_set_device_state: udev 0x7b73000 state ADDRESSED -> POWERED
>xhci_device_state_change: 
>xhci_cmd_reset_dev: 
>xhci_do_command: command[3] = 17 (0x0000000000000000, 0x00000000, 0x01004400)
>13236@1471749330.622350:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.622356:usb_xhci_fetch_trb addr 000000000499fdb0, CR_RESET_DEVICE, p 0000000000000000, s 00000000, c 0x01004400
>13236@1471749330.622360:usb_xhci_slot_reset slotid 1
>xhci: output context at 831a000
>xhci: output slot context: 08300000 00050000 00000000 08000001
>13236@1471749330.622364:usb_xhci_queue_event v 0, idx 50, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdb0, s 01000000, c 0x01008401
>13236@1471749330.622367:usb_xhci_irq_intx level 1
>13236@1471749330.622369:usb_xhci_fetch_trb addr 000000000499fdc0, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749330.622779:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.622786:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.622788:usb_xhci_irq_intx level 1
>13236@1471749330.622873:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.622878:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.622880:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[50] = 33 (0x000000000499fdb0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.625278:usb_xhci_runtime_write off 0x0038, val 0x0499f3b8
>13236@1471749330.625283:usb_xhci_runtime_write off 0x003c, val 0x00000000
>usbd_req_set_address: setting device address=1
>xhci_configure_device: index=1
>xhci_configure_device: Route=0x00000000
>xhci_dump_device: psl = 0xc0000000015e3020
>xhci_dump_device: dwSctx0=0x08300000
>xhci_dump_device: dwSctx1=0x00050000
>xhci_dump_device: dwSctx2=0x00000000
>xhci_dump_device: dwSctx3=0x00000000
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_set_address: 
>xhci_do_command: command[4] = 11 (0x000000000831c000, 0x00000000, 0x01002c00)
>13236@1471749330.640153:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.640159:usb_xhci_fetch_trb addr 000000000499fdc0, CR_ADDRESS_DEVICE, p 000000000831c000, s 00000000, c 0x01002c00
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>13236@1471749330.640168:usb_xhci_slot_address slotid 1, port 1
>13236@1471749330.640171:usb_packet_state_change bus 0, port 1, ep 0, packet 0x7fd45d994e10, state undef -> setup
>13236@1471749330.640176:usb_set_addr dev 1
>13236@1471749330.640177:usb_xhci_ep_enable slotid 1, epid 1
>13236@1471749330.640179:usb_xhci_ep_disable slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=0 dequeue=0000000007b2d001
>13236@1471749330.640185:usb_xhci_ep_state slotid 1, epid 1, stopped -> disabled
>xhci: endpoint 0.1 type is 4, max transaction (burst) size is 64
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: output ep0 context: 00000001 00400026 07b2d001 00000000 00000008
>13236@1471749330.640199:usb_xhci_queue_event v 0, idx 51, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdc0, s 01000000, c 0x01008401
>13236@1471749330.640202:usb_xhci_irq_intx level 1
>13236@1471749330.640204:usb_xhci_fetch_trb addr 000000000499fdd0, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749330.640506:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.640518:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.640521:usb_xhci_irq_intx level 1
>13236@1471749330.640524:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.640527:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.640528:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[51] = 33 (0x000000000499fdc0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.643044:usb_xhci_runtime_write off 0x0038, val 0x0499f3c8
>13236@1471749330.643049:usb_xhci_runtime_write off 0x003c, val 0x00000000
>usbd_setup_device_desc: Minimum bMaxPacketSize is large enough to hold the complete device descriptor or only one bMaxPacketSize choice
>usbd_req_get_device_desc: 
>usbd_req_get_desc: id=0, type=1, index=0, max_len=18
>usbd_do_request_flags: udev=0x7b73000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_do_request_callback: st=0
>usbd_transfer_submit: xfer=0x7b87160, endpoint=0x7b730d8, nframes=2, dir=read
>usb_dump_endpoint: endpoint=0x7b730d8 edesc=0x7b73720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
>usb_dump_queue: endpoint=0x7b730d8 xfer: 
>usbd_transfer_submit: open
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_enter: enter
>xhci_device_generic_enter: 
>xhci_dump_trb: trb = 0x7b8c600
>xhci_dump_trb: qwTrb0 = 0x0012000001000680
>xhci_dump_trb: dwTrb2 = 0x00000008
>xhci_dump_trb: dwTrb3 = 0x00030841
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c480
>xhci_dump_trb: trb = 0x7b8c610
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c480
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8c480
>xhci_dump_trb: qwTrb0 = 0x0000000007b85808
>xhci_dump_trb: dwTrb2 = 0x00020012
>xhci_dump_trb: dwTrb3 = 0x00010c15
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c300
>xhci_dump_trb: trb = 0x7b8c490
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c300
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8c300
>xhci_dump_trb: qwTrb0 = 0x0000000000000000
>xhci_dump_trb: dwTrb2 = 0x00020000
>xhci_dump_trb: dwTrb3 = 0x00001011
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8c180
>xhci_dump_trb: trb = 0x7b8c310
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c180
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_setup_generic_chain: first=0x7b8c600 last=0x7b8c300
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_start: start
>xhci_device_generic_start: 
>xhci_transfer_insert: 
>xhci_transfer_insert: Not running
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0 (leave)
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[5] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749330.709458:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.709464:usb_xhci_fetch_trb addr 000000000499fdd0, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c00
>13236@1471749330.709467:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749330.709472:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749330.709474:usb_xhci_queue_event v 0, idx 52, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdd0, s 01000000, c 0x01008401
>13236@1471749330.709476:usb_xhci_irq_intx level 1
>13236@1471749330.709478:usb_xhci_fetch_trb addr 000000000499fde0, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749330.709886:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.709893:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.709895:usb_xhci_irq_intx level 1
>13236@1471749330.709898:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.709901:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.709902:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[52] = 33 (0x000000000499fdd0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.712286:usb_xhci_runtime_write off 0x0038, val 0x0499f3d8
>13236@1471749330.712291:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[6] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749330.714386:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.714392:usb_xhci_fetch_trb addr 000000000499fde0, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013800
>13236@1471749330.714394:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749330.714396:usb_xhci_queue_event v 0, idx 53, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fde0, s 13000000, c 0x01008401
>13236@1471749330.714399:usb_xhci_irq_intx level 1
>13236@1471749330.714401:usb_xhci_fetch_trb addr 000000000499fdf0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749330.714716:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.714723:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.714725:usb_xhci_irq_intx level 1
>13236@1471749330.714728:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.714731:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.714732:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[53] = 33 (0x000000000499fde0 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.717320:usb_xhci_runtime_write off 0x0038, val 0x0499f3e8
>13236@1471749330.717325:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[7] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749330.720764:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.720770:usb_xhci_fetch_trb addr 000000000499fdf0, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014000
>13236@1471749330.720772:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749330.720776:usb_xhci_queue_event v 0, idx 54, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdf0, s 01000000, c 0x01008401
>13236@1471749330.720779:usb_xhci_irq_intx level 1
>13236@1471749330.720781:usb_xhci_fetch_trb addr 000000000499fe00, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749330.721181:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.721189:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.721190:usb_xhci_irq_intx level 1
>13236@1471749330.721193:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.721196:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.721197:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[54] = 33 (0x000000000499fdf0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.723584:usb_xhci_runtime_write off 0x0038, val 0x0499f3f8
>13236@1471749330.723589:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[8] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749330.725948:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.725953:usb_xhci_fetch_trb addr 000000000499fe00, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003400
>13236@1471749330.725956:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749330.725965:usb_xhci_queue_event v 0, idx 55, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe00, s 01000000, c 0x01008401
>13236@1471749330.725967:usb_xhci_irq_intx level 1
>13236@1471749330.725970:usb_xhci_fetch_trb addr 000000000499fe10, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749330.726277:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.726285:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.726288:usb_xhci_irq_intx level 1
>13236@1471749330.726291:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.726294:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.726296:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[55] = 33 (0x000000000499fe00 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.728737:usb_xhci_runtime_write off 0x0038, val 0x0499f408
>13236@1471749330.728742:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[9] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749330.731104:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749330.731109:usb_xhci_fetch_trb addr 000000000499fe10, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003000
>13236@1471749330.731112:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749330.731116:usb_xhci_queue_event v 0, idx 56, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fe10, s 05000000, c 0x01008401
>13236@1471749330.731119:usb_xhci_irq_intx level 1
>13236@1471749330.731121:usb_xhci_fetch_trb addr 000000000499fe20, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749330.731426:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749330.731433:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749330.731435:usb_xhci_irq_intx level 1
>13236@1471749330.731438:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749330.731441:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749330.731442:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[56] = 33 (0x000000000499fe10 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749330.734077:usb_xhci_runtime_write off 0x0038, val 0x0499f418
>13236@1471749330.734082:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>xhci_transfer_insert: 
>xhci_dump_trb: trb = 0x7b8c310
>xhci_dump_trb: qwTrb0 = 0x0000000007b2d010
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001821
>xhci_dump_trb: trb = 0x7b2d000
>xhci_dump_trb: qwTrb0 = 0x0000000007b8c600
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00000000
>xhci_transfer_insert: qh_pos = 0
>13236@1471749330.742194:usb_xhci_doorbell_write off 0x0004, val 0x00000001
>13236@1471749330.742198:usb_xhci_ep_kick slotid 1, epid 1, streamid 0
>xhci: set epctx: 831a020 state=1 dequeue=0000000007b2d001
>13236@1471749330.742203:usb_xhci_ep_state slotid 1, epid 1, stopped -> running
>Root mount waiting for: usbus0
>xhci_timeout: xfer=0x7b87160
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=20
>usbd_transfer_done: err=USB_ERR_TIMEOUT
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: DMA delay, 2 ms, on 0x7b87160
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[10] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749331.202676:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749331.202682:usb_xhci_fetch_trb addr 000000000499fe20, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c00
>13236@1471749331.202685:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749331.202690:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749331.202692:usb_xhci_queue_event v 0, idx 57, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe20, s 01000000, c 0x01008401
>13236@1471749331.202695:usb_xhci_irq_intx level 1
>13236@1471749331.202697:usb_xhci_fetch_trb addr 000000000499fe30, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749331.203112:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749331.203120:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749331.203122:usb_xhci_irq_intx level 1
>13236@1471749331.203125:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749331.203128:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749331.203129:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[57] = 33 (0x000000000499fe20 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749331.205651:usb_xhci_runtime_write off 0x0038, val 0x0499f428
>13236@1471749331.205656:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[11] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749331.207769:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749331.207775:usb_xhci_fetch_trb addr 000000000499fe30, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013800
>13236@1471749331.207777:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749331.207779:usb_xhci_queue_event v 0, idx 58, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fe30, s 13000000, c 0x01008401
>13236@1471749331.207782:usb_xhci_irq_intx level 1
>13236@1471749331.207784:usb_xhci_fetch_trb addr 000000000499fe40, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749331.208204:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749331.208212:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749331.208214:usb_xhci_irq_intx level 1
>13236@1471749331.208217:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749331.208219:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749331.208221:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[58] = 33 (0x000000000499fe30 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749331.210637:usb_xhci_runtime_write off 0x0038, val 0x0499f438
>13236@1471749331.210642:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[12] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749331.214248:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749331.214254:usb_xhci_fetch_trb addr 000000000499fe40, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014000
>13236@1471749331.214257:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749331.214260:usb_xhci_queue_event v 0, idx 59, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe40, s 01000000, c 0x01008401
>13236@1471749331.214263:usb_xhci_irq_intx level 1
>13236@1471749331.214265:usb_xhci_fetch_trb addr 000000000499fe50, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749331.214576:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749331.214583:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749331.214585:usb_xhci_irq_intx level 1
>13236@1471749331.214588:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749331.214591:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749331.214592:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[59] = 33 (0x000000000499fe40 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749331.217114:usb_xhci_runtime_write off 0x0038, val 0x0499f448
>13236@1471749331.217119:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[13] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749331.219291:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749331.219296:usb_xhci_fetch_trb addr 000000000499fe50, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003400
>13236@1471749331.219298:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749331.219307:usb_xhci_queue_event v 0, idx 60, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fe50, s 01000000, c 0x01008401
>13236@1471749331.219310:usb_xhci_irq_intx level 1
>13236@1471749331.219312:usb_xhci_fetch_trb addr 000000000499fe60, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749331.219625:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749331.219633:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749331.219635:usb_xhci_irq_intx level 1
>13236@1471749331.219638:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749331.219640:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749331.219642:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[60] = 33 (0x000000000499fe50 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749331.222271:usb_xhci_runtime_write off 0x0038, val 0x0499f458
>13236@1471749331.222276:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[14] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749331.224440:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749331.224446:usb_xhci_fetch_trb addr 000000000499fe60, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003000
>13236@1471749331.224448:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749331.224452:usb_xhci_queue_event v 0, idx 61, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fe60, s 05000000, c 0x01008401
>13236@1471749331.224454:usb_xhci_irq_intx level 1
>13236@1471749331.224456:usb_xhci_fetch_trb addr 000000000499fe70, TR_LINK, p 000000000499fd80, s 00000000, c 0x00001802
>13236@1471749331.224458:usb_xhci_fetch_trb addr 000000000499fd80, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014000
>13236@1471749331.224769:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749331.224777:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749331.224779:usb_xhci_irq_intx level 1
>13236@1471749331.224887:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749331.224893:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749331.224894:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[61] = 33 (0x000000000499fe60 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749331.227291:usb_xhci_runtime_write off 0x0038, val 0x0499f468
>13236@1471749331.227296:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>usb_dma_delay_done_cb: Completed 0x7b87160
>usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: xfer=0x7b87160 endpoint=0x7b730d8 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
>xhci_device_generic_close: 
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=5
>usbd_transfer_done: err=USB_ERR_CANCELLED
>usbd_transfer_done: not transferring
>usbd_do_request_flags: I/O error - waiting a bit for TT cleanup
>usbd_do_request_flags: udev=0x7b73000 bmRequestType=0x80 bRequest=0x06 wValue=0x0100 wIndex=0x0000 wLength=0x0012
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_do_request_callback: st=0
>usbd_transfer_submit: xfer=0x7b87160, endpoint=0x7b730d8, nframes=2, dir=read
>usb_dump_endpoint: endpoint=0x7b730d8 edesc=0x7b73720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
>usb_dump_queue: endpoint=0x7b730d8 xfer: 
>usbd_transfer_submit: open
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_enter: enter
>xhci_device_generic_enter: 
>xhci_dump_trb: trb = 0x7b8d600
>xhci_dump_trb: qwTrb0 = 0x0012000001000680
>xhci_dump_trb: dwTrb2 = 0x00000008
>xhci_dump_trb: dwTrb3 = 0x00030841
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d480
>xhci_dump_trb: trb = 0x7b8d610
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d480
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8d480
>xhci_dump_trb: qwTrb0 = 0x0000000007b85808
>xhci_dump_trb: dwTrb2 = 0x00020012
>xhci_dump_trb: dwTrb3 = 0x00010c15
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d300
>xhci_dump_trb: trb = 0x7b8d490
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d300
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_dump_trb: trb = 0x7b8d300
>xhci_dump_trb: qwTrb0 = 0x0000000000000000
>xhci_dump_trb: dwTrb2 = 0x00020000
>xhci_dump_trb: dwTrb3 = 0x00001011
>xhci_setup_generic_chain_sub: NTRB=1
>xhci_setup_generic_chain_sub: LINK=0x07b8d180
>xhci_dump_trb: trb = 0x7b8d310
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d180
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001831
>xhci_setup_generic_chain: first=0x7b8d600 last=0x7b8d300
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_pipe_start: start
>xhci_device_generic_start: 
>xhci_transfer_insert: 
>xhci_dump_trb: trb = 0x7b8d310
>xhci_dump_trb: qwTrb0 = 0x0000000007b2d010
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00001821
>xhci_dump_trb: trb = 0x7b2d000
>xhci_dump_trb: qwTrb0 = 0x0000000007b8d600
>xhci_dump_trb: dwTrb2 = 0x00000000
>xhci_dump_trb: dwTrb3 = 0x00000000
>xhci_transfer_insert: qh_pos = 0
>13236@1471749331.550400:usb_xhci_doorbell_write off 0x0004, val 0x00000001
>13236@1471749331.550404:usb_xhci_ep_kick slotid 1, epid 1, streamid 0
>xhci: set epctx: 831a020 state=1 dequeue=0000000007b2d001
>13236@1471749331.550409:usb_xhci_ep_state slotid 1, epid 1, stopped -> running
>usb_command_wrapper: cb 0x7b87160 (leave)
>usb_command_wrapper: cb 0 (leave)
>usb_command_wrapper: cb 0 (leave)
>Root mount waiting for: usbus0
>xhci_timeout: xfer=0x7b87160
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=20
>usbd_transfer_done: err=USB_ERR_TIMEOUT
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: DMA delay, 2 ms, on 0x7b87160
>usb_command_wrapper: cb 0 (leave)
>xhci_dump_endpoint: pep = 0xc0000000015e3040
>xhci_dump_endpoint: dwEpCtx0=0x00000000
>xhci_dump_endpoint: dwEpCtx1=0x00400026
>xhci_dump_endpoint: qwEpCtx2=0x0000000007b2d001
>xhci_dump_endpoint: dwEpCtx4=0x00000008
>xhci_dump_endpoint: dwEpCtx5=0x00000000
>xhci_dump_endpoint: dwEpCtx6=0x00000000
>xhci_dump_endpoint: dwEpCtx7=0x00000000
>xhci_cmd_stop_ep: 
>xhci_do_command: command[0] = 15 (0x0000000000000000, 0x00000000, 0x01013c00)
>13236@1471749332.054582:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749332.054588:usb_xhci_fetch_trb addr 000000000499fd80, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c01
>13236@1471749332.054591:usb_xhci_ep_stop slotid 1, epid 1
>xhci_ep_nuke_xfers(1, 1)
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749332.054596:usb_xhci_ep_state slotid 1, epid 1, running -> stopped
>13236@1471749332.054598:usb_xhci_queue_event v 0, idx 62, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fd80, s 01000000, c 0x01008401
>13236@1471749332.054600:usb_xhci_irq_intx level 1
>13236@1471749332.054602:usb_xhci_fetch_trb addr 000000000499fd90, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003400
>13236@1471749332.055015:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749332.055023:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749332.055025:usb_xhci_irq_intx level 1
>13236@1471749332.055028:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749332.055031:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749332.055032:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[62] = 33 (0x000000000499fd80 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749332.057567:usb_xhci_runtime_write off 0x0038, val 0x0499f478
>13236@1471749332.057572:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_reset_ep: 
>xhci_do_command: command[1] = 14 (0x0000000000000000, 0x00000000, 0x01013800)
>13236@1471749332.059657:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749332.059663:usb_xhci_fetch_trb addr 000000000499fd90, CR_RESET_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013801
>13236@1471749332.059665:usb_xhci_ep_reset slotid 1, epid 1
>xhci: reset EP while EP 1 not halted (3)
>13236@1471749332.059668:usb_xhci_queue_event v 0, idx 63, ER_COMMAND_COMPLETE, CC_CONTEXT_STATE_ERROR, p 000000000499fd90, s 13000000, c 0x01008401
>13236@1471749332.059670:usb_xhci_irq_intx level 1
>13236@1471749332.059672:usb_xhci_fetch_trb addr 000000000499fda0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003000
>13236@1471749332.060090:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749332.060098:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749332.060099:usb_xhci_irq_intx level 1
>13236@1471749332.060102:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749332.060105:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749332.060106:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[63] = 33 (0x000000000499fd90 0x13000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749332.062544:usb_xhci_runtime_write off 0x0038, val 0x0499f488
>13236@1471749332.062549:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not reset endpoint 1 (18)
>xhci_cmd_set_tr_dequeue_ptr: 
>xhci_do_command: command[2] = 16 (0x0000000007b2d001, 0x00000000, 0x01014000)
>13236@1471749332.066205:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749332.066211:usb_xhci_fetch_trb addr 000000000499fda0, CR_SET_TR_DEQUEUE, p 0000000007b2d001, s 00000000, c 0x01014001
>13236@1471749332.066214:usb_xhci_ep_set_dequeue slotid 1, epid 1, streamid 0, ptr 0000000007b2d001
>xhci: set epctx: 831a020 state=3 dequeue=0000000007b2d001
>13236@1471749332.066218:usb_xhci_queue_event v 0, idx 64, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fda0, s 01000000, c 0x01008401
>13236@1471749332.066220:usb_xhci_irq_intx level 1
>13236@1471749332.066222:usb_xhci_fetch_trb addr 000000000499fdb0, CR_RESET_DEVICE, p 0000000000000000, s 00000000, c 0x01004400
>13236@1471749332.066533:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749332.066541:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749332.066543:usb_xhci_irq_intx level 1
>13236@1471749332.066546:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749332.066549:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749332.066550:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[64] = 33 (0x000000000499fda0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749332.069058:usb_xhci_runtime_write off 0x0038, val 0x0499f498
>13236@1471749332.069063:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_evaluate_ctx: 
>xhci_do_command: command[3] = 13 (0x000000000831c000, 0x00000000, 0x01003400)
>13236@1471749332.071246:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749332.071251:usb_xhci_fetch_trb addr 000000000499fdb0, CR_EVALUATE_CONTEXT, p 000000000831c000, s 00000000, c 0x01003401
>13236@1471749332.071253:usb_xhci_slot_evaluate slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: input slot context: 08300000 00050000 00000000 00000000
>xhci: output slot context: 08300000 00050000 00000000 10000001
>xhci: input ep0 context: 00000000 00400026 07b2d001 00000000 00000008
>xhci: output ep0 context: 00000003 00400026 07b2d001 00000000 00000008
>13236@1471749332.071262:usb_xhci_queue_event v 0, idx 65, ER_COMMAND_COMPLETE, CC_SUCCESS, p 000000000499fdb0, s 01000000, c 0x01008401
>13236@1471749332.071264:usb_xhci_irq_intx level 1
>13236@1471749332.071266:usb_xhci_fetch_trb addr 000000000499fdc0, CR_ADDRESS_DEVICE, p 000000000831c000, s 00000000, c 0x01002c00
>13236@1471749332.071678:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749332.071686:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749332.071688:usb_xhci_irq_intx level 1
>13236@1471749332.071691:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749332.071694:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749332.071695:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[65] = 33 (0x000000000499fdb0 0x01000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749332.074252:usb_xhci_runtime_write off 0x0038, val 0x0499f4a8
>13236@1471749332.074257:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_cmd_configure_ep: 
>xhci_do_command: command[4] = 12 (0x000000000831c000, 0x00000000, 0x01003000)
>13236@1471749332.076412:usb_xhci_doorbell_write off 0x0000, val 0x00000000
>xhci_process_commands()
>13236@1471749332.076417:usb_xhci_fetch_trb addr 000000000499fdc0, CR_CONFIGURE_ENDPOINT, p 000000000831c000, s 00000000, c 0x01003001
>13236@1471749332.076419:usb_xhci_slot_configure slotid 1
>xhci: input context at 831c000
>xhci: output context at 831a000
>xhci: invalid input context control 00000000 00000003
>13236@1471749332.076423:usb_xhci_queue_event v 0, idx 66, ER_COMMAND_COMPLETE, CC_TRB_ERROR, p 000000000499fdc0, s 05000000, c 0x01008401
>13236@1471749332.076426:usb_xhci_irq_intx level 1
>13236@1471749332.076428:usb_xhci_fetch_trb addr 000000000499fdd0, CR_STOP_ENDPOINT, p 0000000000000000, s 00000000, c 0x01013c00
>13236@1471749332.076741:usb_xhci_oper_read off 0x0004, ret 0x00000008
>13236@1471749332.076749:usb_xhci_oper_write off 0x0004, val 0x00000008
>13236@1471749332.076751:usb_xhci_irq_intx level 1
>13236@1471749332.076754:usb_xhci_runtime_read off 0x0020, ret 0x00000003
>13236@1471749332.076757:usb_xhci_runtime_write off 0x0020, val 0x00000003
>13236@1471749332.076758:usb_xhci_irq_intx level 0
>xhci_interrupt_poll: event[66] = 33 (0x000000000499fdc0 0x05000000 0x01008401)
>xhci_check_command: Received command event
>13236@1471749332.079389:usb_xhci_runtime_write off 0x0038, val 0x0499f4b8
>13236@1471749332.079395:usb_xhci_runtime_write off 0x003c, val 0x00000000
>xhci_configure_reset_endpoint: Could not configure endpoint 1
>usb_dma_delay_done_cb: Completed 0x7b87160
>usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
>usb_command_wrapper: cb 0x7b87160 (enter)
>usbd_callback_wrapper: case 1-4
>usbd_callback_wrapper_sub: xfer=0x7b87160 endpoint=0x7b730d8 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
>xhci_device_generic_close: 
>xhci_device_done: xfer=0x7b87160, endpoint=0x7b730d8, error=5
>usbd_transfer_done: err=USB_ERR_CANCELLED
>usbd_transfer_done: not transferring
>usbd_do_request_flags: I/O error - waiting a bit for TT cleanup
>qemu-system-ppc64: terminating on signal 15 from pid 13292

Actions: View

Attachments on bug 212021: 173901 | 173916 | 173956