From owner-freebsd-usb@FreeBSD.ORG Mon Dec 15 08:59:01 2014 Return-Path: Delivered-To: freebsd-usb@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:1900:2254:206a::19:1]) (using TLSv1.2 with cipher AECDH-AES256-SHA (256/256 bits)) (No client certificate requested) by hub.freebsd.org (Postfix) with ESMTPS id 132476A9 for ; Mon, 15 Dec 2014 08:59:01 +0000 (UTC) Received: from mail-wi0-x22f.google.com (mail-wi0-x22f.google.com [IPv6:2a00:1450:400c:c05::22f]) (using TLSv1 with cipher ECDHE-RSA-RC4-SHA (128/128 bits)) (Client CN "smtp.gmail.com", Issuer "Google Internet Authority G2" (verified OK)) by mx1.freebsd.org (Postfix) with ESMTPS id 0410BBB9 for ; Mon, 15 Dec 2014 08:59:00 +0000 (UTC) Received: by mail-wi0-f175.google.com with SMTP id l15so8135196wiw.2 for ; Mon, 15 Dec 2014 00:58:58 -0800 (PST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20120113; h=mime-version:in-reply-to:references:date:message-id:subject:from:to :cc:content-type; bh=Bq2rqzNsCJeP8KIDvJaFlApzgY050/vqr6EHLwHM0pE=; b=jMREFKI63wc/XTwecxDUZ9s/ZMARipF8m5MEA8zVYjiANWEJM4DD4KF6EPS3GnBV0O luBO/Mfhy0qoB0NecobIKDchuSWY22rq6RW7/ljss/NBK6hBsQ0ReYaui6IJ0bnRFT/A O5hcmx/ouwm4CHtxiHgdHPGXXPBmTqj695m6+LctM7p5VMWzMYiInTrCAVfh9zM92a5I 2TciLsAfyejkLk77cGKDOZkFSUXx2GSUsW+JZCOWQKSwb5TPsM4xZCdSREEcb7b1Dk8d YJgZ1qnIFb9c23GK6Xm3pwCu82DJpECq5l4Ui0pFfj4lOS7pCJ1SHKVegfEEAtmX7M6S jEBw== MIME-Version: 1.0 X-Received: by 10.194.62.19 with SMTP id u19mr40395824wjr.0.1418633938365; Mon, 15 Dec 2014 00:58:58 -0800 (PST) Received: by 10.27.174.20 with HTTP; Mon, 15 Dec 2014 00:58:58 -0800 (PST) In-Reply-To: <548E964B.8080408@selasky.org> References: <548D38D5.2010700@selasky.org> <548DDC93.2020601@selasky.org> <548E964B.8080408@selasky.org> Date: Mon, 15 Dec 2014 00:58:58 -0800 Message-ID: Subject: Re: Disconnects every 15 seconds From: Randall Fox To: Hans Petter Selasky Content-Type: text/plain; charset=UTF-8 X-Content-Filtered-By: Mailman/MimeDel 2.1.18-1 Cc: freebsd-usb@freebsd.org X-BeenThere: freebsd-usb@freebsd.org X-Mailman-Version: 2.1.18-1 Precedence: list List-Id: FreeBSD support for USB List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 15 Dec 2014 08:59:01 -0000 > Also, does the endless attach/detach happen to other USB devices? No, I haven't seen it with other devices, though it has been tested only a little. The mouse for example is silent when attached as expected, as is the keyboard. Since this is a server, I have little use for any connections, except the single UPS via USB.. I also can't rule out a defective UPS, though I would have expected to see it do the same on Windows, which I didn't see, but that probably does not prove anything conclusively. It also could be a defective motherboard, though I would expect to see issues with other peripherals. I have seen messages on the FreeNAS forum where other people experience something similar to this (COMMBAD,NOCOMM from NUT), although it is not as repeatable as this. Many say swapping the cable out fixed it (mostly fixed, it seemed to reduce it for some), but for me, I have tried a few cables, and did not see any difference. And besides, it happens every 15 seconds, if it was a silicon/hardware failure, i wouldn't expect it to be so periodic. And I did recently get it to stop while running usbhidctl with the -anrl argument to continually read the device for changes. While that ran, it did not show the disconnect for like 10-15 minutes. But as soon as I stopped it, the disconnects came back. Here is the trace.. thx again.. Dec 15 00:28:49 freenas kernel: uhub_intr_callback: Dec 15 00:28:49 freenas kernel: ugen1.4: at usbus1 (disconnected) Dec 15 00:28:49 freenas kernel: usb_needs_explore: Dec 15 00:28:49 freenas kernel: usb_bus_port_set_device: bus 0xffffff8001e8bcd8 devices[4] = 0 Dec 15 00:28:49 freenas kernel: uhid0: at uhub3, port 2, addr 4 (disconnected) Dec 15 00:28:49 freenas kernel: uhub_child_location_string: device not on hub Dec 15 00:28:49 freenas kernel: uhub_child_pnpinfo_string: device not on hub Dec 15 00:28:49 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0101, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:49 freenas kernel: uhub_reattach_port: Port 2 is in Host Mode Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: usb_bus_port_set_device: bus 0xffffff8001e8bcd8 devices[4] = 0xfffffe001317b000 Dec 15 00:28:50 freenas kernel: ugen1.4: at usbus1 Dec 15 00:28:50 freenas kernel: uhid0: on usbus1 Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:28:50 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:50 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: usb_needs_explore: Dec 15 00:28:51 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:28:51 freenas kernel: usb_needs_explore: Dec 15 00:28:51 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:28:51 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:28:51 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:28:51 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:28:51 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:28:51 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:51 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: usb_needs_explore: Dec 15 00:28:55 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:28:55 freenas kernel: usb_needs_explore: Dec 15 00:28:55 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:28:55 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:28:55 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:28:55 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:28:55 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:28:55 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:55 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: usb_needs_explore: Dec 15 00:28:59 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:28:59 freenas kernel: usb_needs_explore: Dec 15 00:28:59 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:28:59 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:28:59 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:28:59 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:28:59 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:28:59 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:28:59 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:28:59 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:28:59 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:00 freenas kernel: usbd_transfer_power_ref: Adding type 2 to power state Dec 15 00:29:00 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:03 freenas kernel: usb_needs_explore: Dec 15 00:29:03 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:03 freenas kernel: usb_needs_explore: Dec 15 00:29:03 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:03 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:03 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:03 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:03 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:03 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:03 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:03 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:03 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:03 freenas kernel: usbd_transfer_power_ref: Adding type 2 to power state Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:03 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:05 freenas kernel: uhub_intr_callback: Dec 15 00:29:05 freenas kernel: usb_needs_explore: Dec 15 00:29:05 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:05 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:05 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:05 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:05 freenas kernel: uhub_intr_callback: Dec 15 00:29:05 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:05 freenas kernel: usb_needs_explore: Dec 15 00:29:05 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0101, wPortChange=0x0001, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:05 freenas kernel: uhub_reattach_port: reattaching port 2 Dec 15 00:29:05 freenas kernel: uhub_intr_callback: Dec 15 00:29:05 freenas kernel: ugen1.4: at usbus1 (disconnected) Dec 15 00:29:05 freenas kernel: usb_needs_explore: Dec 15 00:29:05 freenas kernel: usb_bus_port_set_device: bus 0xffffff8001e8bcd8 devices[4] = 0 Dec 15 00:29:05 freenas kernel: uhid0: at uhub3, port 2, addr 4 (disconnected) Dec 15 00:29:05 freenas kernel: uhub_child_location_string: device not on hub Dec 15 00:29:05 freenas kernel: uhub_child_pnpinfo_string: device not on hub Dec 15 00:29:05 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0101, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:05 freenas kernel: uhub_reattach_port: Port 2 is in Host Mode Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: usb_bus_port_set_device: bus 0xffffff8001e8bcd8 devices[4] = 0xfffffe001318a000 Dec 15 00:29:06 freenas kernel: ugen1.4: at usbus1 Dec 15 00:29:06 freenas kernel: uhid0: on usbus1 Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:06 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:06 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: usb_needs_explore: Dec 15 00:29:07 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:07 freenas kernel: usb_needs_explore: Dec 15 00:29:07 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:07 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:07 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:07 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:07 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:07 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:07 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: usb_needs_explore: Dec 15 00:29:11 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:11 freenas kernel: usb_needs_explore: Dec 15 00:29:11 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:11 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:11 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:11 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:11 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:11 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:11 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:11 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:11 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:12 freenas kernel: usbd_transfer_power_ref: Adding type 2 to power state Dec 15 00:29:12 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:15 freenas kernel: usb_needs_explore: Dec 15 00:29:15 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:15 freenas kernel: usb_needs_explore: Dec 15 00:29:15 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:15 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:15 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:15 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:15 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:15 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:15 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:15 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:15 freenas kernel: usbd_transfer_power_ref: Adding type 2 to power state Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:15 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:16 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: usb_needs_explore: Dec 15 00:29:19 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:19 freenas kernel: usb_needs_explore: Dec 15 00:29:19 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:19 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:19 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:19 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:19 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:19 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:19 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:21 freenas kernel: uhub_intr_callback: Dec 15 00:29:21 freenas kernel: usb_needs_explore: Dec 15 00:29:21 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:21 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:21 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:21 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:21 freenas kernel: uhub_intr_callback: Dec 15 00:29:21 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:21 freenas kernel: usb_needs_explore: Dec 15 00:29:21 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0101, wPortChange=0x0001, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:21 freenas kernel: uhub_reattach_port: reattaching port 2 Dec 15 00:29:21 freenas kernel: uhub_intr_callback: Dec 15 00:29:21 freenas kernel: ugen1.4: at usbus1 (disconnected) Dec 15 00:29:21 freenas kernel: usb_needs_explore: Dec 15 00:29:21 freenas kernel: usb_bus_port_set_device: bus 0xffffff8001e8bcd8 devices[4] = 0 Dec 15 00:29:21 freenas kernel: uhid0: at uhub3, port 2, addr 4 (disconnected) Dec 15 00:29:21 freenas kernel: uhub_child_location_string: device not on hub Dec 15 00:29:21 freenas kernel: uhub_child_pnpinfo_string: device not on hub Dec 15 00:29:21 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0101, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:21 freenas kernel: uhub_reattach_port: Port 2 is in Host Mode Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: usb_bus_port_set_device: bus 0xffffff8001e8bcd8 devices[4] = 0xfffffe001317b000 Dec 15 00:29:22 freenas kernel: ugen1.4: at usbus1 Dec 15 00:29:22 freenas kernel: uhid0: on usbus1 Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:22 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:22 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: usb_needs_explore: Dec 15 00:29:23 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:23 freenas kernel: usb_needs_explore: Dec 15 00:29:23 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:23 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:23 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:23 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:23 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:23 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:23 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: usb_needs_explore: Dec 15 00:29:27 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:27 freenas kernel: usb_needs_explore: Dec 15 00:29:27 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:27 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:27 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:27 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:27 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:27 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:27 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:27 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:27 freenas kernel: usbd_transfer_power_ref: Adding type 2 to power state Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:27 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: usb_needs_explore: Dec 15 00:29:31 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:31 freenas kernel: usb_needs_explore: Dec 15 00:29:31 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:31 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:31 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:31 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:31 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:31 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:31 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: usb_needs_explore: Dec 15 00:29:35 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:35 freenas kernel: usb_needs_explore: Dec 15 00:29:35 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:35 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:35 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:35 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:35 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:35 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:35 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:35 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:35 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:36 freenas kernel: usbd_transfer_power_ref: Adding type 2 to power state Dec 15 00:29:36 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:37 freenas kernel: uhub_intr_callback: Dec 15 00:29:37 freenas kernel: usb_needs_explore: Dec 15 00:29:37 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:37 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:37 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:37 freenas kernel: uhub_intr_callback: Dec 15 00:29:37 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:37 freenas kernel: usb_needs_explore: Dec 15 00:29:37 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:37 freenas kernel: uhub_intr_callback: Dec 15 00:29:37 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0101, wPortChange=0x0001, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:37 freenas kernel: usb_needs_explore: Dec 15 00:29:37 freenas kernel: uhub_reattach_port: reattaching port 2 Dec 15 00:29:37 freenas kernel: ugen1.4: at usbus1 (disconnected) Dec 15 00:29:37 freenas kernel: usb_bus_port_set_device: bus 0xffffff8001e8bcd8 devices[4] = 0 Dec 15 00:29:37 freenas kernel: uhid0: at uhub3, port 2, addr 4 (disconnected) Dec 15 00:29:37 freenas kernel: uhub_child_location_string: device not on hub Dec 15 00:29:37 freenas kernel: uhub_child_pnpinfo_string: device not on hub Dec 15 00:29:37 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0101, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:37 freenas kernel: uhub_reattach_port: Port 2 is in Host Mode Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: usb_bus_port_set_device: bus 0xffffff8001e8bcd8 devices[4] = 0xfffffe01b6be8000 Dec 15 00:29:38 freenas kernel: ugen1.4: at usbus1 Dec 15 00:29:38 freenas kernel: uhid0: on usbus1 Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:38 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:38 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: usb_needs_explore: Dec 15 00:29:39 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:39 freenas kernel: usb_needs_explore: Dec 15 00:29:39 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:39 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:39 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:39 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:39 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:39 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:39 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: usb_needs_explore: Dec 15 00:29:43 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:43 freenas kernel: usb_needs_explore: Dec 15 00:29:43 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:43 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:43 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:43 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:43 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:43 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:43 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: usb_needs_explore: Dec 15 00:29:47 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:47 freenas kernel: usb_needs_explore: Dec 15 00:29:47 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:47 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:47 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:47 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:47 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:47 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:47 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:47 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:47 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:48 freenas kernel: usbd_transfer_power_ref: Adding type 2 to power state Dec 15 00:29:48 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:51 freenas kernel: usb_needs_explore: Dec 15 00:29:51 freenas kernel: usb_bus_powerd: bus=0xffffff8001db1cd8 Dec 15 00:29:51 freenas kernel: usb_needs_explore: Dec 15 00:29:51 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:51 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:51 freenas kernel: uhub_explore: udev=0xfffffe000c42c000 addr=1 Dec 15 00:29:51 freenas kernel: usb_bus_powerd: Recomputing power masks Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:51 freenas kernel: uhub_explore: udev=0xfffffe000c431000 addr=2 Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:51 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:51 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:51 freenas kernel: usbd_transfer_power_ref: Adding type 0 to power state Dec 15 00:29:51 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: usbd_transfer_power_ref: Adding type 2 to power state Dec 15 00:29:51 freenas kernel: usbd_transfer_power_ref: needs power Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 3, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 4, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 5, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0103, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 6, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 7, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 8, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:51 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0500, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:53 freenas kernel: uhub_intr_callback: Dec 15 00:29:53 freenas kernel: usb_needs_explore: Dec 15 00:29:53 freenas kernel: usb_bus_powerd: bus=0xffffff8001e8bcd8 Dec 15 00:29:53 freenas kernel: uhub_explore: udev=0xfffffe000c42f000 addr=1 Dec 15 00:29:53 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0503, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:53 freenas kernel: uhub_intr_callback: Dec 15 00:29:53 freenas kernel: uhub_explore: udev=0xfffffe000c43e000 addr=2 Dec 15 00:29:53 freenas kernel: usb_needs_explore: Dec 15 00:29:53 freenas kernel: uhub_read_port_status: port 1, wPortStatus=0x0100, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:53 freenas kernel: uhub_intr_callback: Dec 15 00:29:53 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0101, wPortChange=0x0001, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:53 freenas kernel: usb_needs_explore: Dec 15 00:29:53 freenas kernel: uhub_reattach_port: reattaching port 2 Dec 15 00:29:53 freenas kernel: ugen1.4: at usbus1 (disconnected) Dec 15 00:29:53 freenas kernel: usb_bus_port_set_device: bus 0xffffff8001e8bcd8 devices[4] = 0 Dec 15 00:29:53 freenas kernel: uhid0: at uhub3, port 2, addr 4 (disconnected) Dec 15 00:29:53 freenas kernel: uhub_child_location_string: device not on hub Dec 15 00:29:53 freenas kernel: uhub_child_pnpinfo_string: device not on hub Dec 15 00:29:53 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0101, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:53 freenas kernel: uhub_reattach_port: Port 2 is in Host Mode Dec 15 00:29:53 freenas kernel: uhub_intr_callback: Dec 15 00:29:53 freenas kernel: usb_needs_explore: Dec 15 00:29:54 freenas kernel: uhub_read_port_status: port 2, wPortStatus=0x0303, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION Dec 15 00:29:54 freenas kernel: usb_bus_port_set_device: bus 0xffffff8001e8bcd8 devices[4] = 0xfffffe01b6be8000 Dec 15 00:29:54 freenas kernel: ugen1.4: at usbus1 On Mon, Dec 15, 2014 at 12:05 AM, Hans Petter Selasky wrote: > > On 12/14/14 20:43, Randall Fox wrote: > >> On everything below, I removed the USB hub I tested with in the last >> message and plugged the UPS directly into the computers USB port, like >> before. >> >> Can you send the output from "pciconv -lv" especially those lines which >>> >> relate to USB controllers? >> >>> Also try setting: >>> sysctl hw.usb.no_cs_fail=1 >>> >> >> $ sysctl hw.usb.no_cs_fail=1 >> hw.usb.no_cs_fail: 0 -> 1 >> $ >> I previously tried the above, it had no effect. I tried it again just to >> be sure, still no effect. I assumed an immediate effect, unless a reboot >> is needed? >> >> Here are the interesting results from pciconf -lv, (I replaced the \n with >> a comma to make thing more compact and I removed the boring stuff. I also >> put the USB stuff at the top.) >> Note: I included my motherboard and periph data below if that helps. And >> thanks again for your help. >> >> ehci0@pci0:0:26:0: class=0x0c0320 card=0x062b15d9 chip=0x1d2d8086 >> rev=0x06 hdr=0x00,vendor='Intel Corporation',device= 'Patsburg USB2 >> Enhanced Host Controller',class=serial bus,subclass= USB >> ehci1@pci0:0:29:0: class=0x0c0320 card=0x062b15d9 chip=0x1d268086 >> rev=0x06 hdr=0x00,vendor='Intel Corporation',device= 'Patsburg USB2 >> Enhanced Host Controller',class=serial bus,subclass= USB >> >> > Hi, > > The pciconf output looks normal. All USB controllers have drivers attached. > > Can you set "hw.usb.uhub.debug=16" and collect messages in dmesg for and > attach/detach/attach cycle? > > Also, does the endless attach/detach happen to other USB devices? > > --HPS >