[USB] hang after upgrade from 11.0 to 11.1, ZFS or callout() related?

Michael Schmiedgen schmiedgen at takwa.de
Mon Aug 7 16:28:11 UTC 2017 

On 07.08.2017 18:10, Hans Petter Selasky wrote:
> 
> Can you try getting the dmesg.
> 
> You can also disable USB enumeration setting these:
> 
> hw.usb.disable_enumeration: 0
> dev.uhub.2.disable_enumeration: 0
> dev.uhub.1.disable_enumeration: 0
> dev.uhub.0.disable_enumeration: 0
> 
> Are you sure you loaded all drivers, like XHCI, EHCI, OHCI, UHCI ? DOes the BIOS offer any USB options?
> 

It is a generic 11.1 kernel.

I already tried hw.usb.disable_enumeration="1" but that triggered the
strange 'ZFS error 5'.

Part of the debug dmesg with 11.0 kernel below.

Thanks,
   Michael


dmesg:

Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023165148 endpoint=0xfffff800231640d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023196148 endpoint=0xfffff8002318f0d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usbd_transfer_submit: xfer=0xfffff80023165148, endpoint=0xfffff800231640d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff800231640d8 edesc=0xfffff80023164720 isoc_next=0 
toggle_next=0usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: bEndpointAddress=0x00usbd_transfer_submit: xfer=0xfffff80023196148, endpoint=0xfffff8002318f0d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel:
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff8002318f0d8usb_dump_queue: endpoint=0xfffff800231640d8 xfer: 
edesc=0xfffff8002318f720 isoc_next=0 toggle_next=0
Aug  7 18:26:34 antares kernel: bEndpointAddress=0x00usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel:
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usb_dump_queue: endpoint=0xfffff8002318f0d8 xfer:
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023196148 endpoint=0xfffff8002318f0d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023165148 endpoint=0xfffff800231640d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usbd_transfer_submit: xfer=0xfffff80023165148, endpoint=0xfffff800231640d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff800231640d8usbd_transfer_submit: xfer=0xfffff80023196148, 
endpoint=0xfffff8002318f0d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: edesc=0xfffff80023164720 isoc_next=0 toggle_next=0usb_dump_endpoint: endpoint=0xfffff8002318f0d8 bEndpointAddress=0x00 
edesc=0xfffff8002318f720 isoc_next=0 toggle_next=0
Aug  7 18:26:34 antares kernel: bEndpointAddress=0x00usb_dump_queue: endpoint=0xfffff800231640d8 xfer:
Aug  7 18:26:34 antares kernel:
Aug  7 18:26:34 antares kernel: usb_dump_queue: endpoint=0xfffff8002318f0d8 xfer: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel:
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023196148 endpoint=0xfffff8002318f0d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023165148 endpoint=0xfffff800231640d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_transfer_submit: xfer=0xfffff80023196148, endpoint=0xfffff8002318f0d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff8002318f0d8usbd_transfer_submit: xfer=0xfffff80023165148, 
endpoint=0xfffff800231640d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: edesc=0xfffff8002318f720 isoc_next=0 toggle_next=0usb_dump_endpoint: endpoint=0xfffff800231640d8 bEndpointAddress=0x00 
edesc=0xfffff80023164720 isoc_next=0 toggle_next=0
Aug  7 18:26:34 antares kernel: bEndpointAddress=0x00usb_dump_queue: endpoint=0xfffff8002318f0d8 xfer:
Aug  7 18:26:34 antares kernel:
Aug  7 18:26:34 antares kernel: usb_dump_queue: endpoint=0xfffff800231640d8 xfer: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel:
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023196148 endpoint=0xfffff8002318f0d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023165148 endpoint=0xfffff800231640d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_transfer_submit: xfer=0xfffff80023196148, endpoint=0xfffff8002318f0d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff8002318f0d8usbd_transfer_submit: xfer=0xfffff80023165148, 
endpoint=0xfffff800231640d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: edesc=0xfffff8002318f720 isoc_next=0 toggle_next=0usb_dump_endpoint: endpoint=0xfffff800231640d8 bEndpointAddress=0x00 
edesc=0xfffff80023164720 isoc_next=0 toggle_next=0
Aug  7 18:26:34 antares kernel: bEndpointAddress=0x00usb_dump_queue: endpoint=0xfffff8002318f0d8 xfer:
Aug  7 18:26:34 antares kernel:
Aug  7 18:26:34 antares kernel: usb_dump_queue: endpoint=0xfffff800231640d8 xfer: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel:
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023196148 endpoint=0xfffff8002318f0d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023165148 endpoint=0xfffff800231640d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_do_request_flags: Handle Request function is set
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usbd_transfer_submit: xfer=0xfffff800231c5148, endpoint=0xfffff800231c00d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff800231c00d8 edesc=0xfffff800231c0720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
Aug  7 18:26:34 antares kernel: usb_dump_queue: endpoint=0xfffff800231c00d8 xfer:
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff800231c5148 endpoint=0xfffff800231c00d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usbd_transfer_submit: xfer=0xfffff800231c5148, endpoint=0xfffff800231c00d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff800231c00d8 edesc=0xfffff800231c0720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
Aug  7 18:26:34 antares kernel: usb_dump_queue: endpoint=0xfffff800231c00d8 xfer:
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff800231c5148 endpoint=0xfffff800231c00d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usbd_transfer_submit: xfer=0xfffff800231c5148, endpoint=0xfffff800231c00d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff800231c00d8 edesc=0xfffff800231c0720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
Aug  7 18:26:34 antares kernel: usb_dump_queue: endpoint=0xfffff800231c00d8 xfer:
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff800231c5148 endpoint=0xfffff800231c00d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usbd_transfer_submit: xfer=0xfffff800231c5148, endpoint=0xfffff800231c00d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff800231c00d8 edesc=0xfffff800231c0720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
Aug  7 18:26:34 antares kernel: usb_dump_queue: endpoint=0xfffff800231c00d8 xfer:
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff800231c5148 endpoint=0xfffff800231c00d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usbd_transfer_submit: xfer=0xfffff80023196148, endpoint=0xfffff8002318f0d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff8002318f0d8 edesc=0xfffff8002318f720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
Aug  7 18:26:34 antares kernel: usb_dump_queue: endpoint=0xfffff8002318f0d8 xfer:
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023196148 endpoint=0xfffff8002318f0d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=0
Aug  7 18:26:34 antares kernel: usbd_transfer_submit: xfer=0xfffff80023196148, endpoint=0xfffff8002318f0d8, nframes=2, dir=read
Aug  7 18:26:34 antares kernel: usb_dump_endpoint: endpoint=0xfffff8002318f0d8 edesc=0xfffff8002318f720 isoc_next=0 toggle_next=0 bEndpointAddress=0x00
Aug  7 18:26:34 antares kernel: usb_dump_queue: endpoint=0xfffff8002318f0d8 xfer:
Aug  7 18:26:34 antares kernel: usbd_pipe_enter: enter
Aug  7 18:26:34 antares kernel: usbd_pipe_start: start
Aug  7 18:26:34 antares kernel: usbd_transfer_done: err=USB_ERR_NORMAL_COMPLETION
Aug  7 18:26:34 antares kernel: usbd_callback_wrapper_sub: xfer=0xfffff80023196148 endpoint=0xfffff8002318f0d8 sts=0 alen=12, slen=12, afrm=2, nfrm=2
Aug  7 18:26:34 antares kernel: usbd_do_request_callback: st=1
Aug  7 18:26:34 antares kernel: usbd_do_request_flags: Handle Request function is set
Aug  7 18:26:38 antares kernel: usb_needs_explore:
Aug  7 18:26:38 antares kernel: usb_bus_powerd: bus=0xfffffe000118a428
Aug  7 18:26:38 antares kernel: usb_bus_powerd: Recomputing power masks
Aug  7 18:26:38 antares kernel: usbd_do_request_flags: Handle Request function is set







-- 
___________________________

Michael Schmiedgen, BSc
Senior Software Engineer

Takwa GmbH
Friedrich-List-Str. 36
99096 Erfurt GERMANY

Tel  +49 361 6534096
Fax  +49 361 6534097
Mail schmiedgen at takwa.de
Web  http://www.takwa.de/
___________________________


Amtsgericht Jena HRB 112964
Geschäftsführung: Ingo Buchholz

More information about the freebsd-usb mailing list