Michael Schmiedgen
2017-Aug-07 16:28 UTC
[USB] hang after upgrade from 11.0 to 11.1, ZFS or callout() related?
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
Hans Petter Selasky
2017-Aug-07 17:09 UTC
[USB] hang after upgrade from 11.0 to 11.1, ZFS or callout() related?
On 08/07/17 18:28, Michael Schmiedgen wrote:> 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 kernelCan you get dmesg without the USB debug enabled? --HPS