From owner-freebsd-usb@freebsd.org Fri May 12 07:48:59 2017 Return-Path: Delivered-To: freebsd-usb@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 34DC9D693B7 for ; Fri, 12 May 2017 07:48:59 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: from kenobi.freebsd.org (kenobi.freebsd.org [IPv6:2001:1900:2254:206a::16:76]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (Client did not present a certificate) by mx1.freebsd.org (Postfix) with ESMTPS id 192D093B for ; Fri, 12 May 2017 07:48:59 +0000 (UTC) (envelope-from bugzilla-noreply@freebsd.org) Received: from bugs.freebsd.org ([127.0.1.118]) by kenobi.freebsd.org (8.15.2/8.15.2) with ESMTP id v4C7mw82042797 for ; Fri, 12 May 2017 07:48:58 GMT (envelope-from bugzilla-noreply@freebsd.org) From: bugzilla-noreply@freebsd.org To: freebsd-usb@FreeBSD.org Subject: [Bug 219220] syscalls (ioctls) on USB devices are very slow Date: Fri, 12 May 2017 07:48:59 +0000 X-Bugzilla-Reason: AssignedTo X-Bugzilla-Type: changed X-Bugzilla-Watch-Reason: None X-Bugzilla-Product: Base System X-Bugzilla-Component: usb X-Bugzilla-Version: CURRENT X-Bugzilla-Keywords: X-Bugzilla-Severity: Affects Only Me X-Bugzilla-Who: guru@unixarea.de X-Bugzilla-Status: New X-Bugzilla-Resolution: X-Bugzilla-Priority: --- X-Bugzilla-Assigned-To: freebsd-usb@FreeBSD.org X-Bugzilla-Flags: X-Bugzilla-Changed-Fields: Message-ID: In-Reply-To: References: Content-Type: text/plain; charset="UTF-8" Content-Transfer-Encoding: quoted-printable X-Bugzilla-URL: https://bugs.freebsd.org/bugzilla/ Auto-Submitted: auto-generated MIME-Version: 1.0 X-BeenThere: freebsd-usb@freebsd.org X-Mailman-Version: 2.1.23 Precedence: list List-Id: FreeBSD support for USB List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 12 May 2017 07:48:59 -0000 https://bugs.freebsd.org/bugzilla/show_bug.cgi?id=3D219220 --- Comment #25 from Matthias Apitz --- with only set dev.uhub.1.disable_enumeration=3D1 in loader.conf the 'usbdump' are not working, but the pcscd is now booting fast and fine; here is its log with time stamped each line; it does see the pull out of the CCID stick at 09:44:40, but not any plug-in again (will investigate this): 09:44:26: 00000000 pcscdaemon.c:346:main() pcscd set to foreground with deb= ug send to stdout 09:44:26: 00000554 configfile.l:358:DBGetReaderList() Parsing conf file: /usr/local/etc/reader.conf.d 09:44:26: 00000045 pcscdaemon.c:655:main() pcsc-lite 1.8.20 daemon ready. 09:44:26: 00015032 hotplug_libusb.c:536:HPAddHotPluggable() Adding USB devi= ce: 0:4:0 09:44:26: 00000122 readerfactory.c:1079:RFInitializeReader() Attempting sta= rtup of HID Global OMNIKEY 6121 Smart Card Reader 00 00 using /usr/local/lib/pcsc/drivers//ifd-ccid.bundle/Contents/FreeBSD/libccid.so 09:44:26: 00000245 readerfactory.c:954:RFBindFunctions() Loading IFD Handler 3.0 09:44:26: 00000048 ifdhandler.c:1953:init_driver() Driver version: 1.4.25 09:44:26: 00003965 ifdhandler.c:1970:init_driver() LogLevel: 0x0003 09:44:26: 00000013 ifdhandler.c:1981:init_driver() DriverOptions: 0x0000 09:44:26: 00002578 ifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:076b/6632:libusb-1.0:0:4:0 09:44:26: 00000053 ccid_usb.c:287:OpenUSBByName() Using: /usr/local/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist 09:44:26: 00003592 ccid_usb.c:305:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr) 09:44:26: 00000011 ccid_usb.c:306:OpenUSBByName() ifdProductString: Generic CCID driver 09:44:26: 00000010 ccid_usb.c:307:OpenUSBByName() Copyright: This driver is protected by terms of the GNU Lesser General Public License version 2.1, or= (at your option) any later version. 09:44:26: 00001580 ccid_usb.c:621:OpenUSBByName() Found Vendor/Product: 076B/6632 (HID Global OMNIKEY 6121 Smart Card Reader) 09:44:26: 00000011 ccid_usb.c:623:OpenUSBByName() Using USB bus/device: 0/4 09:44:27: 00012361 ccid_usb.c:1153:get_data_rates() Got 105 data rates but = was expecting 96 09:44:27: 00000106 ccid_usb.c:1171:get_data_rates() declared: 10752 bps 09:44:27: 00000007 ccid_usb.c:1171:get_data_rates() declared: 21505 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 43010 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 86021 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 172043 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 344086 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 129032 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 215053 bps 09:44:27: 00000014 ccid_usb.c:1171:get_data_rates() declared: 12903 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 25806 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 51612 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 103225 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 206451 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 412903 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 154838 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 258064 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 7168 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 14336 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 28673 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 57347 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 114695 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 229390 bps 09:44:27: 00000004 ccid_usb.c:1171:get_data_rates() declared: 143369 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 8602 bps 09:44:27: 00000006 ccid_usb.c:1171:get_data_rates() declared: 17204 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 34408 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 68817 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 137634 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 275268 bps 09:44:27: 00000004 ccid_usb.c:1171:get_data_rates() declared: 5376 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 64516 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 107526 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 6451 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 77419 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 8064 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 16129 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 32258 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 96774 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 161290 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 3584 bps 09:44:27: 00000019 ccid_usb.c:1171:get_data_rates() declared: 4301 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 2688 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 53763 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 3225 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 38709 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 4032 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 48387 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 80645 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 2150 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 2580 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 5161 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 10322 bps 09:44:27: 00000004 ccid_usb.c:1171:get_data_rates() declared: 20645 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 41290 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 82580 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 30967 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 7812 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 15625 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 31250 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 62500 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 125000 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 250000 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 93750 bps 09:44:27: 00000004 ccid_usb.c:1171:get_data_rates() declared: 156250 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 9375 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 18750 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 37500 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 75000 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 150000 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 300000 bps 09:44:27: 00000008 ccid_usb.c:1171:get_data_rates() declared: 112500 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 187500 bps 09:44:27: 00000004 ccid_usb.c:1171:get_data_rates() declared: 5208 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 10416 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 20833 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 41666 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 83333 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 166666 bps 09:44:27: 00000004 ccid_usb.c:1171:get_data_rates() declared: 104166 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 6250 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 12500 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 25000 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 50000 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 100000 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 200000 bps 09:44:27: 00000004 ccid_usb.c:1171:get_data_rates() declared: 3906 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 46875 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 78125 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 4687 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 56250 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 5859 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 11718 bps 09:44:27: 00000004 ccid_usb.c:1171:get_data_rates() declared: 23437 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 70312 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 117187 bps 09:44:27: 00000005 ccid_usb.c:1171:get_data_rates() declared: 2604 bps 09:44:27: 00120240 ifdhandler.c:379:IFDHGetCapabilities() tag: 0xFB3, usb:076b/6632:libusb-1.0:0:4:0 (lun: 0) 09:44:27: 00000016 readerfactory.c:395:RFAddReader() Using the reader polli= ng thread 09:44:27: 00000521 ifdhandler.c:379:IFDHGetCapabilities() tag: 0xFAE, usb:076b/6632:libusb-1.0:0:4:0 (lun: 0) 09:44:27: 00000010 ifdhandler.c:470:IFDHGetCapabilities() Reader supports 1 slot(s) 09:44:27: 00000095 readerfactory.c:1420:RFWaitForReaderInit() Waiting init = for reader: HID Global OMNIKEY 6121 Smart Card Reader 00 00 09:44:27: 00000025 hotplug_libusb.c:440:HPEstablishUSBNotifications() Driver ifd-ccid.bundle does not support IFD_GENERATE_HOTPLUG. Using active polling instead. 09:44:27: 00000018 hotplug_libusb.c:449:HPEstablishUSBNotifications() Polli= ng forced every 1 second(s) 09:44:40: 13099101 hotplug_libusb.c:626:HPRemoveHotPluggable() Removing USB device[0]: 0:4:0 09:44:40: 00002788 readerfactory.c:608:RFRemoveReader() UnrefReader() count was: 1 09:44:40: 00000012 eventhandler.c:176:EHDestroyEventHandler() Stomping thre= ad. 09:44:40: 00000008 ifdhandler.c:379:IFDHGetCapabilities() tag: 0xFB1, usb:076b/6632:libusb-1.0:0:4:0 (lun: 0) 09:44:40: 00000006 ifdhandler.c:379:IFDHGetCapabilities() tag: 0xFB2, usb:076b/6632:libusb-1.0:0:4:0 (lun: 0) 09:44:40: 00000004 eventhandler.c:201:EHDestroyEventHandler() Request stopp= ing of polling thread 09:44:40: 00000005 ifdhandler.c:344:IFDHStopPolling() usb:076b/6632:libusb-1.0:0:4:0 (lun: 0) 09:44:40: 00000013 ccid_usb.c:1337:InterruptStop() libusb_cancel_transfer failed: -5 --=20 You are receiving this mail because: You are the assignee for the bug.=