From owner-freebsd-stable@freebsd.org Mon Aug 7 16:28:11 2017 Return-Path: Delivered-To: freebsd-stable@mailman.ysv.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:1900:2254:206a::19:1]) by mailman.ysv.freebsd.org (Postfix) with ESMTP id D281CDB47DD; Mon, 7 Aug 2017 16:28:11 +0000 (UTC) (envelope-from schmiedgen@takwa.de) Received: from mail.takwa.de (antares.takwa.de [5.9.72.166]) by mx1.freebsd.org (Postfix) with ESMTP id 7C50C7317D; Mon, 7 Aug 2017 16:28:10 +0000 (UTC) (envelope-from schmiedgen@takwa.de) Received: by mail.takwa.de (Postfix, from userid 65534) id 1722B521E; Mon, 7 Aug 2017 18:28:10 +0200 (CEST) X-Spam-Checker-Version: SpamAssassin 3.4.1 (2015-04-28) on mail.takwa.de X-Spam-Level: X-Spam-Status: No, score=-2.9 required=5.0 tests=ALL_TRUSTED,BAYES_00 autolearn=disabled version=3.4.1 Received: from [192.168.10.5] (unknown [62.246.110.10]) by mail.takwa.de (Postfix) with ESMTPSA id 5D4E0521B; Mon, 7 Aug 2017 18:28:09 +0200 (CEST) Subject: Re: [USB] hang after upgrade from 11.0 to 11.1, ZFS or callout() related? To: Hans Petter Selasky , freebsd-stable@freebsd.org, freebsd-usb@freebsd.org References: <7a382dbf-5211-18b0-d6c4-f2abb3a327b6@selasky.org> From: Michael Schmiedgen Message-ID: <2f3f1931-cfbc-124c-3ecc-6e8b71cc7b52@takwa.de> Date: Mon, 7 Aug 2017 18:28:09 +0200 User-Agent: Mozilla/5.0 (Windows NT 10.0; WOW64; rv:52.0) Gecko/20100101 Thunderbird/52.2.1 MIME-Version: 1.0 In-Reply-To: <7a382dbf-5211-18b0-d6c4-f2abb3a327b6@selasky.org> Content-Type: text/plain; charset=utf-8; format=flowed Content-Language: en-US Content-Transfer-Encoding: 8bit X-BeenThere: freebsd-stable@freebsd.org X-Mailman-Version: 2.1.23 Precedence: list List-Id: Production branch of FreeBSD source code List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 07 Aug 2017 16:28:11 -0000 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@takwa.de Web http://www.takwa.de/ ___________________________ Amtsgericht Jena HRB 112964 Geschäftsführung: Ingo Buchholz