Closed emaxx-google closed 9 months ago
Sample failure:
[ RUN ] AllDevices/SmartCardConnectorApplicationReaderWithoutBuiltinCardCompatibilityTest.Basic/1 [DEBUG] Performing services initialization... [DEBUG] [PC/SC-Lite NaCl port] Initialization... [DEBUG] [PC/SC-Lite NaCl port] Setting up PC/SC-Lite logging... [INFO] ../../../../../third_party/pcsc-lite/src/src/debuglog.c:390:DebugLogSetLevel() debug level=debug [INFO] ../../../../../third_party/pcsc-lite/src/src/debuglog.c:411:DebugLogSetCategory() Debug options: APDU [DEBUG] [PC/SC-Lite NaCl port] PC/SC-Lite logging was set up. [DEBUG] [PC/SC-Lite NaCl port] Allocating reader structures... [DEBUG] [PC/SC-Lite NaCl port] Reader structures allocation finished with the following result: "Command successful.". [DEBUG] [PC/SC-Lite NaCl port] Performing initial hot plug drivers search... [DEBUG] [emulated IPC] A blocking in-memory file 1 was created [DEBUG] [emulated IPC] A blocking in-memory file 2 was created [DEBUG] [emulated IPC] The in-memory file 1 connected to the in-memory file 2 [DEBUG] [emulated IPC] The in-memory file 2 connected to the in-memory file 1 [DEBUG] [libusb] libusb_init#4794(ctx=NULL): called... [DEBUG] [libusb] libusb_init#4794: returning "LIBUSB_SUCCESS" [0x00000000] [DEBUG] [libusb] libusb_get_device_list#4795(ctx=libusb_context<DEFAULT>, list=0xE87FEFE0): called... [DEBUG] [libusb] libusb_get_device_list#4795: returning 0, *list=0xD9840C70([]) [DEBUG] [libusb] libusb_free_device_list#4796(list=0xD9840C70([]), unref_devices=1): called... [DEBUG] [libusb] libusb_free_device_list#4796: returning [DEBUG] [emulated IPC] A blocking in-memory file 3 was created [DEBUG] [emulated IPC] A blocking in-memory file 4 was created [DEBUG] [emulated IPC] The in-memory file 3 connected to the in-memory file 4 [DEBUG] [emulated IPC] The in-memory file 4 connected to the in-memory file 3 [DEBUG] [emulated IPC] The in-memory file 1 was closed [DEBUG] [emulated IPC] The in-memory file 2 was closed [DEBUG] [PC/SC-Lite NaCl port] Initial hot plug drivers search finished with the following result code: 0. [DEBUG] [PC/SC-Lite NaCl port] Registering for hot plug events... [DEBUG] [PC/SC-Lite NaCl port] Registering for hot plug events finished with the following result code: 0. [DEBUG] [PC/SC-Lite NaCl port] Allocating client structures... [DEBUG] [PC/SC-Lite NaCl port] Client structures allocation finished with the following result code: 1... [DEBUG] [PC/SC-Lite NaCl port] Waiting for the readers initialization... [DEBUG] [PC/SC-Lite NaCl port] Waiting for the readers initialization finished. [DEBUG] [PC/SC-Lite NaCl port] Starting PC/SC-Lite daemon thread... [DEBUG] [PC/SC-Lite NaCl port] PC/SC-Lite daemon thread has started. [DEBUG] [PC/SC-Lite NaCl port] Initialization successfully finished. [DEBUG] All services are successfully initialized, posting ready message... [DEBUG] [emulated IPC] The in-memory file 1 was destroyed [DEBUG] [emulated IPC] The in-memory file 2 was destroyed [INFO] ../../../../../third_party/pcsc-lite/src/src/hotplug_libusb.c:492:HPEstablishUSBNotifications() Driver ifd-ccid.bundle does not support IFD_GENERATE_HOTPLUG. Using active polling instead. [INFO] ../../../../../third_party/pcsc-lite/src/src/hotplug_libusb.c:501:HPEstablishUSBNotifications() Polling forced every 1 second(s) [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Created client handler [DEBUG] [PC/SC-Lite clients manager] Created a new client handler for aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (handler id 1234) [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Started processing request SCardEstablishContext(0x02,null,null)... [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardEstablishContext#4797(dwScope=SCARD_SCOPE_SYSTEM, pvReserved1=null, pvReserved2=null): called... [DEBUG] [emulated IPC] A non-blocking in-memory file 5 was created [DEBUG] [emulated IPC] A non-blocking in-memory file 6 was created [DEBUG] [emulated IPC] The in-memory file 5 connected to the in-memory file 6 [DEBUG] [emulated IPC] The in-memory file 6 connected to the in-memory file 5 [DEBUG] [PC/SC-Lite NaCl port] [daemon thread] Waiting for the new connected clients... [DEBUG] [PC/SC-Lite NaCl port] [daemon thread] A new client was connected, starting a handler thread... [DEBUG] [PC/SC-Lite NaCl port] [daemon thread] Waiting for the new connected clients... [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:341:ContextThread() Authorized PC/SC client [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:345:ContextThread() Thread is started: dwClientID=6, threadContext @0xdecdfcc0 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_VERSION from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:375:ContextThread() Client is protocol version 4:4 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:397:ContextThread() CMD_VERSION for client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:610:SCardEstablishContextTH() Server is protocol version 4:4 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: ESTABLISH_CONTEXT from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard.c:210:SCardEstablishContextServer() Establishing Context: 0x577BD55 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:466:ContextThread() ESTABLISH_CONTEXT for client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:3254:SCardAddContext() Allocating new SCONTEXTMAP @0xdecf25c0 [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardEstablishContext#4797: returning 0x00000000 ["Command successful."], hContext=0x0577BD55 [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Request SCardEstablishContext finished successfully with the following results: [0x00, 0x0577BD55] [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Started processing request SCardGetStatusChange(0x0577BD55,0xFFFFFFFF,[{"current_state": 0x00, "reader_name": "\\?PnP?\Notification"}])... [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardGetStatusChange#4798(hContext=0x0577BD55, dwTimeout=4294967295, rgReaderStates=0xD9624F80[{szReader="\\?PnP?\Notification", pvUserData=NULL, dwCurrentState=SCARD_STATE_UNAWARE}]): called... [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:843:MSGSendReaderStates() Send reader states: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:1781:SCardGetStatusChange() Event Loop Start, dwTimeout: -1 [DEBUG] [libusb] libusb_get_device_list#4799(ctx=libusb_context<DEFAULT>, list=0xE87FEFE0): called... [DEBUG] [libusb] libusb_get_device_list#4799: returning 1, *list=0xD9840970([0xD9626AE0]) [DEBUG] [libusb] libusb_get_bus_number#4800(dev=0xD9626AE0): called... [DEBUG] [libusb] libusb_get_bus_number#4800: returning 1 [DEBUG] [libusb] libusb_get_device_address#4801(dev=0xD9626AE0): called... [DEBUG] [libusb] libusb_get_device_address#4801: returning 123 [DEBUG] [libusb] libusb_get_device_descriptor#4802(dev=0xD9626AE0, desc=0xE87FEFF0): called... [DEBUG] [libusb] libusb_get_device_descriptor#4802: returning "LIBUSB_SUCCESS" [0x00000000], *desc=libusb_device_descriptor(bLength=18, bDescriptorType=LIBUSB_DT_DEVICE, bcdUSB=0x0000, bDeviceClass=LIBUSB_CLASS_PER_INTERFACE, bDeviceSubClass=0x00, bDeviceProtocol=0x00, bMaxPacketSize0=0, idVendor=0x413C, idProduct=0x2101, bcdDevice=0x0201, iManufacturer=0, iProduct=0, iSerialNumber=0, bNumConfigurations=0) [DEBUG] [libusb] libusb_get_active_config_descriptor#4803(dev=0xD9626AE0, config=0xE87FF030): called... [DEBUG] [libusb] libusb_get_active_config_descriptor#4803: returning "LIBUSB_SUCCESS" [0x00000000], *config=0xD8C57130(libusb_config_descriptor(bLength=24, bDescriptorType=LIBUSB_DT_CONFIG, wTotalLength=24, bNumInterfaces=2, bConfigurationValue=1, iConfiguration=0, bmAttributes=0, MaxPower=0, interface=[libusb_interface(altsetting=[libusb_interface_descriptor(bLength=24, bDescriptorType=LIBUSB_DT_INTERFACE, bInterfaceNumber=1, bAlternateSetting=0, bNumEndpoints=3, bInterfaceClass=LIBUSB_CLASS_SMART_CARD, endpoint=[libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), bmAttributes=0x02(transfer_type=LIBUSB_TRANSFER_TYPE_BULK, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=64, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0), libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), bmAttributes=0x02(transfer_type=LIBUSB_TRANSFER_TYPE_BULK, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=64, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0), libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), bmAttributes=0x03(transfer_type=LIBUSB_TRANSFER_TYPE_INTERRUPT, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=8, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0)], extra=<0x36 0x21 0x01 0x01 0x00 0x07 0x03 0x00 0x00 0x00 0xC0 0x12 0x00 0x00 0xC0 0x12 0x00 0x00 0x00 0x67 0x32 0x00 0x00 0xCE 0x99 0x0C 0x00 0x35 0xFE 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x30 0x02 0x01 0x00 0x0F 0x01 0x00 0x00 0x00 0x00 0x00 0x00 0x03 0x01>, extra_length=54)], num_altsetting=1), libusb_interface(altsetting=<NULL>, num_altsetting=0)], extra=<>, extra_length=0)) [INFO] ../../../../../third_party/pcsc-lite/src/src/hotplug_libusb.c:621:HPAddHotPluggable() Adding USB device: 1:123:0 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1090:RFInitializeReader() Attempting startup of Dell Dell Smart Card Reader Keyboard 00 00 using executable-module-filesystem/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:964:RFBindFunctions() Loading IFD Handler 3.0 [INFO] 02054591 ../../src/src/ifdhandler.c:111:CreateChannelByNameOrChannel() Lun: 0, device: usb:413c/2101:libusb-1.0:1:123:0 [INFO] 00000059 ../../src/src/ccid_usb.c:327:OpenUSBByName() Using: executable-module-filesystem/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist [INFO] 00003682 ../../src/src/ccid_usb.c:345:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr) [INFO] 00000043 ../../src/src/ccid_usb.c:346:OpenUSBByName() ifdProductString: Generic CCID driver [INFO] 00000024 ../../src/src/ccid_usb.c:347: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. [DEBUG] [libusb] libusb_init#4804(ctx=0x5799A160): called... [DEBUG] [libusb] libusb_init#4804: returning "LIBUSB_SUCCESS" [0x00000000], *ctx=libusb_context<0xDC04354C> [DEBUG] [libusb] libusb_get_device_list#4805(ctx=libusb_context<0xDC04354C>, list=0xE87FD5B0): called... [DEBUG] [libusb] libusb_get_device_list#4805: returning 1, *list=0xD985A890([0xD9627F80]) [DEBUG] [libusb] libusb_get_bus_number#4806(dev=0xD9627F80): called... [DEBUG] [libusb] libusb_get_bus_number#4806: returning 1 [DEBUG] [libusb] libusb_get_device_address#4807(dev=0xD9627F80): called... [DEBUG] [libusb] libusb_get_device_address#4807: returning 123 [DEBUG] [libusb] libusb_get_device_descriptor#4808(dev=0xD9627F80, desc=0xE87FD660): called... [DEBUG] [libusb] libusb_get_device_descriptor#4808: returning "LIBUSB_SUCCESS" [0x00000000], *desc=libusb_device_descriptor(bLength=18, bDescriptorType=LIBUSB_DT_DEVICE, bcdUSB=0x0000, bDeviceClass=LIBUSB_CLASS_PER_INTERFACE, bDeviceSubClass=0x00, bDeviceProtocol=0x00, bMaxPacketSize0=0, idVendor=0x413C, idProduct=0x2101, bcdDevice=0x0201, iManufacturer=0, iProduct=0, iSerialNumber=0, bNumConfigurations=0) [DEBUG] [libusb] libusb_open#4809(dev=0xD9627F80, handle=0xE87FC4D0): called... [DEBUG] [libusb] libusb_open#4809: returning "LIBUSB_SUCCESS" [0x00000000], *handle=libusb_device_handle<0xD985A610> [DEBUG] [libusb] libusb_get_active_config_descriptor#4810(dev=0xD9627F80, config=0xE87FD6A0): called... [DEBUG] [libusb] libusb_get_active_config_descriptor#4810: returning "LIBUSB_SUCCESS" [0x00000000], *config=0xD8C5DCA0(libusb_config_descriptor(bLength=24, bDescriptorType=LIBUSB_DT_CONFIG, wTotalLength=24, bNumInterfaces=2, bConfigurationValue=1, iConfiguration=0, bmAttributes=0, MaxPower=0, interface=[libusb_interface(altsetting=[libusb_interface_descriptor(bLength=24, bDescriptorType=LIBUSB_DT_INTERFACE, bInterfaceNumber=1, bAlternateSetting=0, bNumEndpoints=3, bInterfaceClass=LIBUSB_CLASS_SMART_CARD, endpoint=[libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), bmAttributes=0x02(transfer_type=LIBUSB_TRANSFER_TYPE_BULK, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=64, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0), libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), bmAttributes=0x02(transfer_type=LIBUSB_TRANSFER_TYPE_BULK, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=64, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0), libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), bmAttributes=0x03(transfer_type=LIBUSB_TRANSFER_TYPE_INTERRUPT, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=8, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0)], extra=<0x36 0x21 0x01 0x01 0x00 0x07 0x03 0x00 0x00 0x00 0xC0 0x12 0x00 0x00 0xC0 0x12 0x00 0x00 0x00 0x67 0x32 0x00 0x00 0xCE 0x99 0x0C 0x00 0x35 0xFE 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x30 0x02 0x01 0x00 0x0F 0x01 0x00 0x00 0x00 0x00 0x00 0x00 0x03 0x01>, extra_length=54)], num_altsetting=1), libusb_interface(altsetting=<NULL>, num_altsetting=0)], extra=<>, extra_length=0)) [DEBUG] [libusb] libusb_free_config_descriptor#4811(config=0xD8C5DCA0): called... [DEBUG] [libusb] libusb_free_config_descriptor#4811: returning [INFO] 00002282 ../../src/src/ccid_usb.c:671:OpenUSBByName() Found interface 1 but expecting 0 [INFO] 00000029 ../../src/src/ccid_usb.c:673:OpenUSBByName() Wrong interface for USB device 1/123. Checking next one. [DEBUG] [libusb] libusb_get_active_config_descriptor#4812(dev=0xD9627F80, config=0xE87FD6A0): called... [DEBUG] [libusb] libusb_get_active_config_descriptor#4812: returning "LIBUSB_SUCCESS" [0x00000000], *config=0xD8C60880(libusb_config_descriptor(bLength=24, bDescriptorType=LIBUSB_DT_CONFIG, wTotalLength=24, bNumInterfaces=2, bConfigurationValue=1, iConfiguration=0, bmAttributes=0, MaxPower=0, interface=[libusb_interface(altsetting=[libusb_interface_descriptor(bLength=24, bDescriptorType=LIBUSB_DT_INTERFACE, bInterfaceNumber=1, bAlternateSetting=0, bNumEndpoints=3, bInterfaceClass=LIBUSB_CLASS_SMART_CARD, endpoint=[libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), bmAttributes=0x02(transfer_type=LIBUSB_TRANSFER_TYPE_BULK, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=64, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0), libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), bmAttributes=0x02(transfer_type=LIBUSB_TRANSFER_TYPE_BULK, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=64, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0), libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), bmAttributes=0x03(transfer_type=LIBUSB_TRANSFER_TYPE_INTERRUPT, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=8, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0)], extra=<0x36 0x21 0x01 0x01 0x00 0x07 0x03 0x00 0x00 0x00 0xC0 0x12 0x00 0x00 0xC0 0x12 0x00 0x00 0x00 0x67 0x32 0x00 0x00 0xCE 0x99 0x0C 0x00 0x35 0xFE 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x30 0x02 0x01 0x00 0x0F 0x01 0x00 0x00 0x00 0x00 0x00 0x00 0x03 0x01>, extra_length=54)], num_altsetting=1), libusb_interface(altsetting=<NULL>, num_altsetting=0)], extra=<>, extra_length=0)) [DEBUG] [libusb] libusb_free_config_descriptor#4813(config=0xD8C60880): called... [DEBUG] [libusb] libusb_free_config_descriptor#4813: returning [DEBUG] [libusb] libusb_close#4814(handle=libusb_device_handle<0xD985A610>): called... [DEBUG] [libusb] libusb_close#4814: returning [DEBUG] [libusb] libusb_free_device_list#4815(list=0xD985A890([0xD9627F80]), unref_devices=1): called... [DEBUG] [libusb] libusb_free_device_list#4815: returning [INFO] 00004139 ../../src/src/ccid_usb.c:208:close_libusb_if_needed() libusb_exit [DEBUG] [libusb] libusb_exit#4816(ctx=libusb_context<0xDC04354C>): called... [DEBUG] [libusb] libusb_exit#4816: returning [INFO] 00000080 ../../src/src/ccid_usb.c:839:OpenUSBByName() Device not found? [INFO] 00000027 ../../src/src/ifdhandler.c:156:CreateChannelByNameOrChannel() failed [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1131:RFInitializeReader() Open Port 0x200000 Failed (usb:413c/2101:libusb-1.0:1:123:0) [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:374:RFAddReaderOriginal() Dell Dell Smart Card Reader Keyboard init failed. [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:624:RFRemoveReaderOriginal() UnrefReader() count was: 1 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1144:RFUnInitializeReader() Attempting shutdown of Dell Dell Smart Card Reader Keyboard 00 00. [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1001:RFUnloadReader() Unloading reader driver. [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:831:MSGSignalClient() Signal client: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:834:MSGSignalClient() SIGNAL for client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/hotplug_libusb.c:621:HPAddHotPluggable() Adding USB device: 1:123:1 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1090:RFInitializeReader() Attempting startup of Dell Dell Smart Card Reader Keyboard 00 00 using executable-module-filesystem/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:843:MSGSendReaderStates() Send reader states: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:1829:SCardGetStatusChange() Reader list changed [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:2138:SCardGetStatusChange() Event Loop End [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:446:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 6 [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardGetStatusChange#4798: returning 0x00000000 ["Command successful."], rgReaderStates=0xD9624F80[{szReader="\\?PnP?\Notification", pvUserData=NULL, dwCurrentState=SCARD_STATE_UNAWARE, dwEventState=SCARD_STATE_CHANGED, cbAtr=0, rgbAtr=<>}] [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Request SCardGetStatusChange finished successfully with the following results: [0x00, [{"atr": binary[], "current_state": 0x00, "event_state": 0x02, "reader_name": "\\?PnP?\Notification"}]] [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Started processing request SCardListReaders(0x0577BD55,null)... [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardListReaders#4817(hContext=0x0577BD55, mszGroups=null): called... [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_GET_READERS_STATE from client 6 [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardListReaders#4817: returning 0x00000000 ["Command successful."], mszReaders=["Dell Dell Smart Card Reader Keyboard 00 00"] [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Request SCardListReaders finished successfully with the following results: [0x00, ["Dell Dell Smart Card Reader Keyboard 00 00"]] [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:964:RFBindFunctions() Loading IFD Handler 3.0 [INFO] 00005046 ../../src/src/ifdhandler.c:111:CreateChannelByNameOrChannel() Lun: 0, device: usb:413c/2101:libusb-1.0:1:123:1 [INFO] 00000042 ../../src/src/ccid_usb.c:327:OpenUSBByName() Using: executable-module-filesystem/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist [INFO] 00003911 ../../src/src/ccid_usb.c:345:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr) [INFO] 00000043 ../../src/src/ccid_usb.c:346:OpenUSBByName() ifdProductString: Generic CCID driver [INFO] 00000025 ../../src/src/ccid_usb.c:347: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. [DEBUG] [libusb] libusb_init#4818(ctx=0x5799A160): called... [DEBUG] [libusb] libusb_init#4818: returning "LIBUSB_SUCCESS" [0x00000000], *ctx=libusb_context<0xDC03D9CC> [DEBUG] [libusb] libusb_get_device_list#4819(ctx=libusb_context<0xDC03D9CC>, list=0xE87FD5B0): called... [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Started processing request SCardGetStatusChange(0x0577BD55,0xFFFFFFFF,[{"current_state": 0x10, "reader_name": "Dell Dell Smart Card Reader Keyboard 00 00"}])... [DEBUG] [libusb] libusb_get_device_list#4819: returning 1, *list=0xD9871DB0([0xD962AC80]) [DEBUG] [libusb] libusb_get_bus_number#4821(dev=0xD962AC80): called... [DEBUG] [libusb] libusb_get_bus_number#4821: returning 1 [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardGetStatusChange#4820(hContext=0x0577BD55, dwTimeout=4294967295, rgReaderStates=0xD962D020[{szReader="Dell Dell Smart Card Reader Keyboard 00 00", pvUserData=NULL, dwCurrentState=SCARD_STATE_EMPTY}]): called... [DEBUG] [libusb] libusb_get_device_address#4822(dev=0xD962AC80): called... [DEBUG] [libusb] libusb_get_device_address#4822: returning 123 [DEBUG] [libusb] libusb_get_device_descriptor#4823(dev=0xD962AC80, desc=0xE87FD660): called... [DEBUG] [libusb] libusb_get_device_descriptor#4823: returning "LIBUSB_SUCCESS" [0x00000000], *desc=libusb_device_descriptor(bLength=18, bDescriptorType=LIBUSB_DT_DEVICE, bcdUSB=0x0000, bDeviceClass=LIBUSB_CLASS_PER_INTERFACE, bDeviceSubClass=0x00, bDeviceProtocol=0x00, bMaxPacketSize0=0, idVendor=0x413C, idProduct=0x2101, bcdDevice=0x0201, iManufacturer=0, iProduct=0, iSerialNumber=0, bNumConfigurations=0) [DEBUG] [libusb] libusb_open#4824(dev=0xD962AC80, handle=0xE87FC4D0): called... [DEBUG] [libusb] libusb_open#4824: returning "LIBUSB_SUCCESS" [0x00000000], *handle=libusb_device_handle<0xD9872AB0> [DEBUG] [libusb] libusb_get_active_config_descriptor#4825(dev=0xD962AC80, config=0xE87FD6A0): called... [DEBUG] [libusb] libusb_get_active_config_descriptor#4825: returning "LIBUSB_SUCCESS" [0x00000000], *config=0xD8C6A150(libusb_config_descriptor(bLength=24, bDescriptorType=LIBUSB_DT_CONFIG, wTotalLength=24, bNumInterfaces=2, bConfigurationValue=1, iConfiguration=0, bmAttributes=0, MaxPower=0, interface=[libusb_interface(altsetting=[libusb_interface_descriptor(bLength=24, bDescriptorType=LIBUSB_DT_INTERFACE, bInterfaceNumber=1, bAlternateSetting=0, bNumEndpoints=3, bInterfaceClass=LIBUSB_CLASS_SMART_CARD, endpoint=[libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), bmAttributes=0x02(transfer_type=LIBUSB_TRANSFER_TYPE_BULK, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=64, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0), libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), bmAttributes=0x02(transfer_type=LIBUSB_TRANSFER_TYPE_BULK, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=64, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0), libusb_endpoint_descriptor(bLength=20, bDescriptorType=LIBUSB_DT_ENDPOINT, bEndpointAddress=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), bmAttributes=0x03(transfer_type=LIBUSB_TRANSFER_TYPE_INTERRUPT, iso_sync_type=0, iso_usage_type=0), wMaxPacketSize=8, bInterval=0, bRefresh=0, bSynchAddress=0, extra=<>, extra_length=0)], extra=<0x36 0x21 0x01 0x01 0x00 0x07 0x03 0x00 0x00 0x00 0xC0 0x12 0x00 0x00 0xC0 0x12 0x00 0x00 0x00 0x67 0x32 0x00 0x00 0xCE 0x99 0x0C 0x00 0x35 0xFE 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x30 0x02 0x01 0x00 0x0F 0x01 0x00 0x00 0x00 0x00 0x00 0x00 0x03 0x01>, extra_length=54)], num_altsetting=1), libusb_interface(altsetting=<NULL>, num_altsetting=0)], extra=<>, extra_length=0)) [DEBUG] [libusb] libusb_claim_interface#4826(dev=libusb_device_handle<0xD9872AB0>, interface_number=1): called... [DEBUG] [libusb] libusb_claim_interface#4826: returning "LIBUSB_SUCCESS" [0x00000000] [INFO] 00002871 ../../src/src/ccid_usb.c:694:OpenUSBByName() Found Vendor/Product: 413C/2101 (Dell Dell Smart Card Reader Keyboard) [INFO] 00000041 ../../src/src/ccid_usb.c:696:OpenUSBByName() Using USB bus/device: 1/123 [DEBUG] [libusb] libusb_control_transfer#4827(dev=libusb_device_handle<0xD9872AB0>, bmRequestType=0xA1(recipient=LIBUSB_RECIPIENT_INTERFACE, type=LIBUSB_REQUEST_TYPE_CLASS, direction=LIBUSB_ENDPOINT_IN), bRequest=0x03, wValue=0x0000, wIndex=0x0001, data=0xE87FBF40, wLength=212, timeout=3000): called... [DEBUG] [libusb] libusb_control_transfer#4827: returning 212, data=0xE87FBF40<0x67 0x32 0x00 0x00 0xCE 0x64 0x00 0x00 0x9D 0xC9 0x00 0x00 0x3A 0x93 0x01 0x00 0x74 0x26 0x03 0x00 0xE7 0x4C 0x06 0x00 0xCE 0x99 0x0C 0x00 0xD7 0x5C 0x02 0x00 0x11 0xF0 0x03 0x00 0x34 0x43 0x00 0x00 0x69 0x86 0x00 0x00 0xD1 0x0C 0x01 0x00 0xA2 0x19 0x02 0x00 0x45 0x33 0x04 0x00 0x8A 0x66 0x08 0x00 0x0B 0xA0 0x02 0x00 0x73 0x30 0x00 0x00 0xE6 0x60 0x00 0x00 0xCC 0xC1 0x00 0x00 0x99 0x83 0x01 0x00 0x32 0x07 0x03 0x00 0x63 0x0E 0x06 0x00 0xB3 0x22 0x01 0x00 0x7F 0xE4 0x01 0x00 0x06 0x50 0x01 0x00 0x36 0x97 0x00 0x00 0x04 0xFC 0x00 0x00 0x53 0x28 0x00 0x00 0xA5 0x50 0x00 0x00 0x4A 0xA1 0x00 0x00 0x95 0x42 0x01 0x00 0x29 0x85 0x02 0x00 0xF8 0x78 0x00 0x00 0x3E 0x49 0x00 0x00 0x7C 0x92 0x00 0x00 0xF8 0x24 0x01 0x00 0xF0 0x49 0x02 0x00 0xE0 0x93 0x04 0x00 0xC0 0x27 0x09 0x00 0x74 0xB7 0x01 0x00 0x6C 0xDC 0x02 0x00 0xD4 0x30 0x00 0x00 0xA8 0x61 0x00 0x00 0x50 0xC3 0x00 0x00 0xA0 0x86 0x01 0x00 0x40 0x0D 0x03 0x00 0x80 0x1A 0x06 0x00 0x48 0xE8 0x01 0x00 0xBA 0xDB 0x00 0x00 0x36 0x6E 0x01 0x00 0x24 0xF4 0x00 0x00 0xDD 0x6D 0x00 0x00 0x1B 0xB7 0x00 0x00> [INFO] 00001336 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 12903 bps [INFO] 00000023 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 25806 bps [INFO] 00000020 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 51613 bps [INFO] 00000024 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 103226 bps [INFO] 00000018 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 206452 bps [INFO] 00000016 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 412903 bps [INFO] 00000025 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 825806 bps [INFO] 00000017 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 154839 bps [INFO] 00000024 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 258065 bps [INFO] 00000016 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 17204 bps [INFO] 00000018 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 34409 bps [INFO] 00000022 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 68817 bps [INFO] 00000017 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 137634 bps [INFO] 00000023 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 275269 bps [INFO] 00000023 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 550538 bps [INFO] 00000016 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 172043 bps [INFO] 00000034 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 12403 bps [INFO] 00000016 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 24806 bps [INFO] 00000017 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 49612 bps [INFO] 00000016 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 99225 bps [INFO] 00000023 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 198450 bps [INFO] 00000017 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 396899 bps [INFO] 00000018 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 74419 bps [INFO] 00000025 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 124031 bps [INFO] 00000022 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 86022 bps [INFO] 00000024 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 38710 bps [INFO] 00000021 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 64516 bps [INFO] 00000017 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 10323 bps [INFO] 00000017 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 20645 bps [INFO] 00000018 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 41290 bps [INFO] 00000021 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 82581 bps [INFO] 00000019 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 165161 bps [INFO] 00000017 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 30968 bps [INFO] 00000018 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 18750 bps [INFO] 00000021 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 37500 bps [INFO] 00000018 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 75000 bps [INFO] 00000016 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 150000 bps [INFO] 00000023 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 300000 bps [INFO] 00000016 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 600000 bps [INFO] 00000028 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 112500 bps [INFO] 00000017 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 187500 bps [INFO] 00000017 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 12500 bps [INFO] 00000017 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 25000 bps [INFO] 00000021 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 50000 bps [INFO] 00000024 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 100000 bps [INFO] 00006597 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 200000 bps [INFO] 00000036 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 400000 bps [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] 00000032 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 125000 bps [INFO] 00000129 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 56250 bps [INFO] 00000034 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 93750 bps [INFO] 00000033 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 62500 bps [INFO] 00000024 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 28125 bps [INFO] 00000026 ../../src/src/ccid_usb.c:1425:get_data_rates() declared: 46875 bps [DEBUG] [libusb] libusb_free_config_descriptor#4828(config=0xD8C6A150): called... [DEBUG] [libusb] libusb_free_config_descriptor#4828: returning [DEBUG] [libusb] libusb_free_device_list#4829(list=0xD9871DB0([0xD962AC80]), unref_devices=1): called... [DEBUG] [libusb] libusb_free_device_list#4829: returning [DEBUG] [libusb] libusb_alloc_transfer#4830(iso_packets=0): called... [DEBUG] [libusb] libusb_alloc_transfer#4830: returning 0xD8A13B90 [DEBUG] [libusb] libusb_submit_transfer#4831(transfer=0xD8A13B90(libusb_transfer(dev_handle=libusb_device_handle<0xD9872AB0>, flags=0, endpoint=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), type=LIBUSB_TRANSFER_TYPE_INTERRUPT, timeout=100, length=8, callback=0x56CA1690, user_data=0xE87FDA70, buffer=0xE87FDA50, num_iso_packets=0))): called... [DEBUG] [libusb] libusb_submit_transfer#4831: returning "LIBUSB_SUCCESS" [0x00000000] [DEBUG] [libusb] libusb_handle_events_completed#4832(ctx=libusb_context<0xDC03D9CC>, completed=0xE87FDA70): called... [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [DEBUG] [libusb] libusb_transfer->callback#4833(libusb_transfer=0xD8A13B90(libusb_transfer(dev_handle=libusb_device_handle<0xD9872AB0>, flags=0, endpoint=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), type=LIBUSB_TRANSFER_TYPE_INTERRUPT, timeout=100, status=LIBUSB_TRANSFER_ERROR, length=8, actual_length=0, callback=0x56CA1690, user_data=0xE87FDA70, buffer=0xE87FDA50<>, num_iso_packets=0))): called... [DEBUG] [libusb] libusb_transfer->callback#4833: returning [DEBUG] [libusb] libusb_handle_events_completed#4832: returning "LIBUSB_SUCCESS" [0x00000000] [DEBUG] [libusb] libusb_free_transfer#4834(transfer=0xD8A13B90): called... [DEBUG] [libusb] libusb_free_transfer#4834: returning [DEBUG] [libusb] libusb_bulk_transfer#4835(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), data=0xE87FDC50<0x65 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00>, length=10, actual_length=0xE87FDB30, timeout=5000): called... [DEBUG] [libusb] libusb_bulk_transfer#4835: returning "LIBUSB_SUCCESS" [0x00000000], *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4836(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), data=0xE87FDE50, length=10, actual_length=0xE87FD9D0, timeout=3000): called... [DEBUG] [libusb] libusb_bulk_transfer#4836: returning "LIBUSB_SUCCESS" [0x00000000], data=0xE87FDE50<0x81 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x00 0x00>, *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4837(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), data=0xE87FDC50<0x65 0x00 0x00 0x00 0x00 0x00 0x01 0x00 0x00 0x00>, length=10, actual_length=0xE87FDB30, timeout=5000): called... [DEBUG] [libusb] libusb_bulk_transfer#4837: returning "LIBUSB_SUCCESS" [0x00000000], *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4838(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), data=0xE87FDE50, length=10, actual_length=0xE87FD9D0, timeout=100): called... [DEBUG] [libusb] libusb_bulk_transfer#4838: returning "LIBUSB_SUCCESS" [0x00000000], data=0xE87FDE50<0x81 0x00 0x00 0x00 0x00 0x00 0x01 0x01 0x00 0x00>, *actual_length=10 [INFO] 00107272 ../../src/src/ifdhandler.c:390:IFDHGetCapabilities() tag: 0xFB3, usb:413c/2101:libusb-1.0:1:123:1 (lun: 0) [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:394:RFAddReaderOriginal() Using the reader polling thread [DEBUG] [libusb] libusb_bulk_transfer#4839(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), data=0xE87FDC50<0x65 0x00 0x00 0x00 0x00 0x00 0x02 0x00 0x00 0x00>, length=10, actual_length=0xE87FDB30, timeout=5000): called... [DEBUG] [libusb] libusb_bulk_transfer#4839: returning "LIBUSB_SUCCESS" [0x00000000], *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4840(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), data=0xE87FDE50, length=10, actual_length=0xE87FD9D0, timeout=3000): called... [DEBUG] [libusb] libusb_bulk_transfer#4840: returning "LIBUSB_SUCCESS" [0x00000000], data=0xE87FDE50<0x81 0x00 0x00 0x00 0x00 0x00 0x02 0x01 0x00 0x00>, *actual_length=10 [INFO] 00002294 ../../src/src/ifdhandler.c:390:IFDHGetCapabilities() tag: 0xFAE, usb:413c/2101:libusb-1.0:1:123:1 (lun: 0) [INFO] 00000055 ../../src/src/ifdhandler.c:487:IFDHGetCapabilities() Reader supports 1 slot(s) [DEBUG] [libusb] libusb_free_config_descriptor#4841(config=0xD8C57130): called... [DEBUG] [libusb] libusb_free_config_descriptor#4841: returning [DEBUG] [libusb] libusb_free_device_list#4842(list=0xD9840970([0xD9626AE0]), unref_devices=1): called... [DEBUG] [libusb] libusb_free_device_list#4842: returning [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [DEBUG] [libusb] libusb_bulk_transfer#4843(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), data=0xD7FFEBD0<0x65 0x00 0x00 0x00 0x00 0x00 0x03 0x00 0x00 0x00>, length=10, actual_length=0xD7FFEAB0, timeout=5000): called... [DEBUG] [libusb] libusb_bulk_transfer#4843: returning "LIBUSB_SUCCESS" [0x00000000], *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4844(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), data=0xD7FFEDD0, length=10, actual_length=0xD7FFE950, timeout=3000): called... [DEBUG] [libusb] libusb_bulk_transfer#4844: returning "LIBUSB_SUCCESS" [0x00000000], data=0xD7FFEDD0<0x81 0x00 0x00 0x00 0x00 0x00 0x03 0x01 0x00 0x00>, *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4845(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), data=0xD7FFEA70<0x65 0x00 0x00 0x00 0x00 0x00 0x04 0x00 0x00 0x00>, length=10, actual_length=0xD7FFE950, timeout=5000): called... [DEBUG] [libusb] libusb_bulk_transfer#4845: returning "LIBUSB_SUCCESS" [0x00000000], *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4846(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), data=0xD7FFEC70, length=10, actual_length=0xD7FFE7F0, timeout=3000): called... [DEBUG] [libusb] libusb_bulk_transfer#4846: returning "LIBUSB_SUCCESS" [0x00000000], data=0xD7FFEC70<0x81 0x00 0x00 0x00 0x00 0x00 0x04 0x01 0x00 0x00>, *actual_length=10 [INFO] 00021081 ../../src/src/ifdhandler.c:1247:IFDHPowerICC() action: PowerUp, usb:413c/2101:libusb-1.0:1:123:1 (lun: 0) [DEBUG] [libusb] libusb_bulk_transfer#4847(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), data=0xD7FFE8D0<0x62 0x00 0x00 0x00 0x00 0x00 0x05 0x01 0x00 0x00>, length=10, actual_length=0xD7FFE7B0, timeout=5000): called... [DEBUG] [libusb] libusb_bulk_transfer#4847: returning "LIBUSB_SUCCESS" [0x00000000], *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4848(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), data=0xD7FFEC60, length=43, actual_length=0xD7FFE650, timeout=60000): called... [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1433:RFWaitForReaderInit() Waiting init for reader: Dell Dell Smart Card Reader Keyboard 00 00 [DEBUG] [libusb] libusb_bulk_transfer#4848: returning "LIBUSB_SUCCESS" [0x00000000], data=0xD7FFEC60<0x80 0x16 0x00 0x00 0x00 0x00 0x05 0x00 0x00 0x00 0x3B 0xDB 0x96 0x00 0x80 0xB1 0xFE 0x45 0x1F 0x83 0x00 0x31 0xC0 0x64 0xC7 0xFC 0x10 0x00 0x01 0x90 0x00 0x74>, *actual_length=32 [INFO] ../../../../../third_party/pcsc-lite/src/src/eventhandler.c:289:EHStatusHandlerThread() powerState: POWER_STATE_POWERED [INFO] Card ATR: 3B DB 96 00 80 B1 FE 45 1F 83 00 31 C0 64 C7 FC 10 00 01 90 00 74 [DEBUG] [libusb] libusb_bulk_transfer#4849(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), data=0xD7FFEBD0<0x65 0x00 0x00 0x00 0x00 0x00 0x06 0x00 0x00 0x00>, length=10, actual_length=0xD7FFEAB0, timeout=5000): called... [DEBUG] [libusb] libusb_bulk_transfer#4849: returning "LIBUSB_SUCCESS" [0x00000000], *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4850(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), data=0xD7FFEDD0, length=10, actual_length=0xD7FFE950, timeout=3000): called... [DEBUG] [libusb] libusb_bulk_transfer#4850: returning "LIBUSB_SUCCESS" [0x00000000], data=0xD7FFEDD0<0x81 0x00 0x00 0x00 0x00 0x00 0x06 0x00 0x00 0x00>, *actual_length=10 [DEBUG] [libusb] libusb_alloc_transfer#4851(iso_packets=0): called... [DEBUG] [libusb] libusb_alloc_transfer#4851: returning 0xD8A1C390 [DEBUG] [libusb] libusb_submit_transfer#4852(transfer=0xD8A1C390(libusb_transfer(dev_handle=libusb_device_handle<0xD9872AB0>, flags=0, endpoint=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), type=LIBUSB_TRANSFER_TYPE_INTERRUPT, timeout=5000, length=8, callback=0x56CA1690, user_data=0xD7FFEDD0, buffer=0xD7FFEDB0, num_iso_packets=0))): called... [DEBUG] [libusb] libusb_submit_transfer#4852: returning "LIBUSB_SUCCESS" [0x00000000] [DEBUG] [libusb] libusb_handle_events_completed#4853(ctx=libusb_context<0xDC03D9CC>, completed=0xD7FFEDD0): called... [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:843:MSGSendReaderStates() Send reader states: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:1781:SCardGetStatusChange() Event Loop Start, dwTimeout: -1 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:1968:SCardGetStatusChange() [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:2138:SCardGetStatusChange() Event Loop End [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:446:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 6 [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardGetStatusChange#4820: returning 0x00000000 ["Command successful."], rgReaderStates=0xD962D020[{szReader="Dell Dell Smart Card Reader Keyboard 00 00", pvUserData=NULL, dwCurrentState=SCARD_STATE_EMPTY, dwEventState=SCARD_STATE_CHANGED|SCARD_STATE_PRESENT, cbAtr=22, rgbAtr=<0x3B 0xDB 0x96 0x00 0x80 0xB1 0xFE 0x45 0x1F 0x83 0x00 0x31 0xC0 0x64 0xC7 0xFC 0x10 0x00 0x01 0x90 0x00 0x74>}] [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Request SCardGetStatusChange finished successfully with the following results: [0x00, [{"atr": binary[0x3B, 0xDB, 0x96, 0x00, 0x80, 0xB1, 0xFE, 0x45, 0x1F, 0x83, 0x00, 0x31, 0xC0, 0x64, 0xC7, 0xFC, 0x10, 0x00, 0x01, 0x90, 0x00, 0x74], "current_state": 0x10, "event_state": 0x22, "reader_name": "Dell Dell Smart Card Reader Keyboard 00 00"}]] ../../../src/application_unittest.cc:1808: Failure Value of: reader_states[0] Expected: dictionary has key "event_state" with value 65570 Actual: {"atr": binary[0x3B, 0xDB, 0x96, 0x00, 0x80, 0xB1, 0xFE, 0x45, 0x1F, 0x83, 0x00, 0x31, 0xC0, 0x64, 0xC7, 0xFC, 0x10, 0x00, 0x01, 0x90, 0x00, 0x74], "current_state": 0x10, "event_state": 0x22, "reader_name": "Dell Dell Smart Card Reader Keyboard 00 00"} (of type google_smart_card::Value) [DEBUG] [libusb] libusb_transfer->callback#4854(libusb_transfer=0xD8A1C390(libusb_transfer(dev_handle=libusb_device_handle<0xD9872AB0>, flags=0, endpoint=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), type=LIBUSB_TRANSFER_TYPE_INTERRUPT, timeout=5000, status=LIBUSB_TRANSFER_ERROR, length=8, actual_length=0, callback=0x56CA1690, user_data=0xD7FFEDD0, buffer=0xD7FFEDB0<>, num_iso_packets=0))): called... [DEBUG] [libusb] libusb_transfer->callback#4854: returning [DEBUG] [libusb] libusb_handle_events_completed#4853: returning "LIBUSB_SUCCESS" [0x00000000] [DEBUG] [libusb] libusb_free_transfer#4855(transfer=0xD8A1C390): called... [DEBUG] [libusb] libusb_free_transfer#4855: returning [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Started processing request SCardGetStatusChange(0x0577BD55,0xFFFFFFFF,[{"current_state": 0x20, "reader_name": "Dell Dell Smart Card Reader Keyboard 00 00"}])... [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardGetStatusChange#4856(hContext=0x0577BD55, dwTimeout=4294967295, rgReaderStates=0xD96372E0[{szReader="Dell Dell Smart Card Reader Keyboard 00 00", pvUserData=NULL, dwCurrentState=SCARD_STATE_PRESENT}]): called... [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:843:MSGSendReaderStates() Send reader states: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:1781:SCardGetStatusChange() Event Loop Start, dwTimeout: -1 [DEBUG] [libusb] libusb_bulk_transfer#4857(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), data=0xD7FFEA70<0x65 0x00 0x00 0x00 0x00 0x00 0x07 0x00 0x00 0x00>, length=10, actual_length=0xD7FFE950, timeout=5000): called... [DEBUG] [libusb] libusb_bulk_transfer#4857: returning "LIBUSB_SUCCESS" [0x00000000], *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4858(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), data=0xD7FFEC70, length=10, actual_length=0xD7FFE7F0, timeout=3000): called... [DEBUG] [libusb] libusb_bulk_transfer#4858: returning "LIBUSB_SUCCESS" [0x00000000], data=0xD7FFEC70<0x81 0x00 0x00 0x00 0x00 0x00 0x07 0x02 0x00 0x00>, *actual_length=10 [INFO] ../../../../../third_party/pcsc-lite/src/src/eventhandler.c:482:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED [DEBUG] [libusb] libusb_bulk_transfer#4859(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), data=0xD7FFEBD0<0x65 0x00 0x00 0x00 0x00 0x00 0x08 0x00 0x00 0x00>, length=10, actual_length=0xD7FFEAB0, timeout=5000): called... [DEBUG] [libusb] libusb_bulk_transfer#4859: returning "LIBUSB_SUCCESS" [0x00000000], *actual_length=10 [DEBUG] [libusb] libusb_bulk_transfer#4860(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x82(number=2, direction=LIBUSB_ENDPOINT_IN), data=0xD7FFEDD0, length=10, actual_length=0xD7FFE950, timeout=3000): called... [DEBUG] [libusb] libusb_bulk_transfer#4860: returning "LIBUSB_SUCCESS" [0x00000000], data=0xD7FFEDD0<0x81 0x00 0x00 0x00 0x00 0x00 0x08 0x02 0x00 0x00>, *actual_length=10 [INFO] ../../../../../third_party/pcsc-lite/src/src/eventhandler.c:358:EHStatusHandlerThread() Card Removed From Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:831:MSGSignalClient() Signal client: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:834:MSGSignalClient() SIGNAL for client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:843:MSGSendReaderStates() Send reader states: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:1950:SCardGetStatusChange() [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:2138:SCardGetStatusChange() Event Loop End [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:446:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 6 [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardGetStatusChange#4856: returning 0x00000000 ["Command successful."], rgReaderStates=0xD96372E0[{szReader="Dell Dell Smart Card Reader Keyboard 00 00", pvUserData=NULL, dwCurrentState=SCARD_STATE_PRESENT, dwEventState=SCARD_STATE_CHANGED|SCARD_STATE_EMPTY with eventCount=1, cbAtr=0, rgbAtr=<>}] [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Request SCardGetStatusChange finished successfully with the following results: [0x00, [{"atr": binary[], "current_state": 0x20, "event_state": 0x00010012, "reader_name": "Dell Dell Smart Card Reader Keyboard 00 00"}]] ../../../src/application_unittest.cc:1834: Failure Value of: reader_states[0] Expected: dictionary has key "event_state" with value 131090 Actual: {"atr": binary[], "current_state": 0x20, "event_state": 0x00010012, "reader_name": "Dell Dell Smart Card Reader Keyboard 00 00"} (of type google_smart_card::Value) [DEBUG] [libusb] libusb_alloc_transfer#4861(iso_packets=0): called... [DEBUG] [libusb] libusb_alloc_transfer#4861: returning 0xD8A203D0 [DEBUG] [libusb] libusb_submit_transfer#4862(transfer=0xD8A203D0(libusb_transfer(dev_handle=libusb_device_handle<0xD9872AB0>, flags=0, endpoint=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), type=LIBUSB_TRANSFER_TYPE_INTERRUPT, timeout=600000, length=8, callback=0x56CA1690, user_data=0xD7FFEDD0, buffer=0xD7FFEDB0, num_iso_packets=0))): called... [INFO] interruptTransfer failed: Unknown device [DEBUG] [libusb] libusb_submit_transfer#4862: returning "LIBUSB_SUCCESS" [0x00000000] [DEBUG] [libusb] libusb_handle_events_completed#4863(ctx=libusb_context<0xDC03D9CC>, completed=0xD7FFEDD0): called... [DEBUG] [libusb] libusb_transfer->callback#4864(libusb_transfer=0xD8A203D0(libusb_transfer(dev_handle=libusb_device_handle<0xD9872AB0>, flags=0, endpoint=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), type=LIBUSB_TRANSFER_TYPE_INTERRUPT, timeout=600000, status=LIBUSB_TRANSFER_ERROR, length=8, actual_length=0, callback=0x56CA1690, user_data=0xD7FFEDD0, buffer=0xD7FFEDB0<>, num_iso_packets=0))): called... [DEBUG] [libusb] libusb_transfer->callback#4864: returning [DEBUG] [libusb] libusb_handle_events_completed#4863: returning "LIBUSB_SUCCESS" [0x00000000] [DEBUG] [libusb] libusb_free_transfer#4865(transfer=0xD8A203D0): called... [DEBUG] [libusb] libusb_free_transfer#4865: returning [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Started processing request SCardGetStatusChange(0x0577BD55,0xFFFFFFFF,[{"current_state": 0x00, "reader_name": "\\?PnP?\Notification"}])... [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardGetStatusChange#4866(hContext=0x0577BD55, dwTimeout=4294967295, rgReaderStates=0xD963A100[{szReader="\\?PnP?\Notification", pvUserData=NULL, dwCurrentState=SCARD_STATE_UNAWARE}]): called... [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:843:MSGSendReaderStates() Send reader states: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:1781:SCardGetStatusChange() Event Loop Start, dwTimeout: -1 [DEBUG] [libusb] libusb_bulk_transfer#4867(dev=libusb_device_handle<0xD9872AB0>, endpoint=0x01(number=1, direction=LIBUSB_ENDPOINT_OUT), data=0xD7FFEBD0<0x65 0x00 0x00 0x00 0x00 0x00 0x09 0x00 0x00 0x00>, length=10, actual_length=0xD7FFEAB0, timeout=5000): called... [INFO] bulkTransfer failed: Unknown device [DEBUG] [libusb] libusb_bulk_transfer#4867: returning "LIBUSB_ERROR_OTHER" [0xFFFFFF9D] [INFO] 00823836 ../../src/src/ccid_usb.c:905:WriteUSB() write failed (1/123): LIBUSB_ERROR_OTHER [INFO] ../../../../../third_party/pcsc-lite/src/src/ifdwrapper.c:364:IFDStatusICC() Card not transacted: 612 [INFO] ../../../../../third_party/pcsc-lite/src/src/eventhandler.c:336:EHStatusHandlerThread() Error communicating to: Dell Dell Smart Card Reader Keyboard 00 00 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:831:MSGSignalClient() Signal client: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:834:MSGSignalClient() SIGNAL for client 6 [DEBUG] [libusb] libusb_alloc_transfer#4868(iso_packets=0): called... [DEBUG] [libusb] libusb_alloc_transfer#4868: returning 0xD8A20F10 [DEBUG] [libusb] libusb_submit_transfer#4869(transfer=0xD8A20F10(libusb_transfer(dev_handle=libusb_device_handle<0xD9872AB0>, flags=0, endpoint=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), type=LIBUSB_TRANSFER_TYPE_INTERRUPT, timeout=600000, length=8, callback=0x56CA1690, user_data=0xD7FFEDD0, buffer=0xD7FFEDB0, num_iso_packets=0))): called... [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:843:MSGSendReaderStates() Send reader states: 6 [INFO] interruptTransfer failed: Unknown device [DEBUG] [libusb] libusb_submit_transfer#4869: returning "LIBUSB_SUCCESS" [0x00000000] [DEBUG] [libusb] libusb_handle_events_completed#4870(ctx=libusb_context<0xDC03D9CC>, completed=0xD7FFEDD0): called... [DEBUG] [libusb] libusb_transfer->callback#4871(libusb_transfer=0xD8A20F10(libusb_transfer(dev_handle=libusb_device_handle<0xD9872AB0>, flags=0, endpoint=0x83(number=3, direction=LIBUSB_ENDPOINT_IN), type=LIBUSB_TRANSFER_TYPE_INTERRUPT, timeout=600000, status=LIBUSB_TRANSFER_ERROR, length=8, actual_length=0, callback=0x56CA1690, user_data=0xD7FFEDD0, buffer=0xD7FFEDB0<>, num_iso_packets=0))): called... [DEBUG] [libusb] libusb_transfer->callback#4871: returning [DEBUG] [libusb] libusb_handle_events_completed#4870: returning "LIBUSB_SUCCESS" [0x00000000] [DEBUG] [libusb] libusb_free_transfer#4872(transfer=0xD8A20F10): called... [DEBUG] [libusb] libusb_free_transfer#4872: returning [DEBUG] [libusb] libusb_get_device_list#4873(ctx=libusb_context<DEFAULT>, list=0xE87FEFE0): called... [DEBUG] [libusb] libusb_get_device_list#4873: returning 0, *list=0xD9875B70([]) [INFO] ../../../../../third_party/pcsc-lite/src/src/hotplug_libusb.c:777:HPRemoveHotPluggable() Removing USB device[0]: 1:123:0 [INFO] ../../../../../third_party/pcsc-lite/src/src/hotplug_libusb.c:777:HPRemoveHotPluggable() Removing USB device[1]: 1:123:1 [INFO] 00156325 ../../src/src/ifdhandler.c:390:IFDHGetCapabilities() tag: 0xFB4, usb:413c/2101:libusb-1.0:1:123:1 (lun: 0) [INFO] 00000034 ../../src/src/ifdhandler.c:688:IFDHSetCapabilities() tag: 0xFB4, usb:413c/2101:libusb-1.0:1:123:1 (lun: 0) [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:624:RFRemoveReaderOriginal() UnrefReader() count was: 1 [INFO] ../../../../../third_party/pcsc-lite/src/src/eventhandler.c:175:EHDestroyEventHandler() Stomping thread. [INFO] 00000089 ../../src/src/ifdhandler.c:390:IFDHGetCapabilities() tag: 0xFB1, usb:413c/2101:libusb-1.0:1:123:1 (lun: 0) [INFO] 00000035 ../../src/src/ifdhandler.c:390:IFDHGetCapabilities() tag: 0xFB2, usb:413c/2101:libusb-1.0:1:123:1 (lun: 0) [INFO] ../../../../../third_party/pcsc-lite/src/src/eventhandler.c:200:EHDestroyEventHandler() Request stopping of polling thread [INFO] 00000058 ../../src/src/ifdhandler.c:355:IFDHStopPolling() usb:413c/2101:libusb-1.0:1:123:1 (lun: 0) [INFO] ../../../../../third_party/pcsc-lite/src/src/eventhandler.c:504:EHStatusHandlerThread() Die [INFO] ../../../../../third_party/pcsc-lite/src/src/eventhandler.c:215:EHDestroyEventHandler() Thread stomped. [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1144:RFUnInitializeReader() Attempting shutdown of Dell Dell Smart Card Reader Keyboard 00 00. [INFO] 00245919 ../../src/src/ifdhandler.c:293:IFDHCloseChannel() usb:413c/2101:libusb-1.0:1:123:1 (lun: 0) [DEBUG] [libusb] libusb_release_interface#4874(dev=libusb_device_handle<0xD9872AB0>, interface_number=1): called... [INFO] LibusbReleaseInterface request failed: Unknown device [DEBUG] [libusb] libusb_release_interface#4874: returning "LIBUSB_ERROR_OTHER" [0xFFFFFF9D] [DEBUG] [libusb] libusb_close#4875(handle=libusb_device_handle<0xD9872AB0>): called... [INFO] Failed to close USB device [DEBUG] [libusb] libusb_close#4875: returning [INFO] 00001266 ../../src/src/ccid_usb.c:208:close_libusb_if_needed() libusb_exit [DEBUG] [libusb] libusb_exit#4876(ctx=libusb_context<0xDC03D9CC>): called... [DEBUG] [libusb] libusb_exit#4876: returning [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1001:RFUnloadReader() Unloading reader driver. [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:831:MSGSignalClient() Signal client: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:834:MSGSignalClient() SIGNAL for client 6 [DEBUG] [libusb] libusb_free_device_list#4877(list=0xD9875B70([]), unref_devices=1): called... [DEBUG] [libusb] libusb_free_device_list#4877: returning [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:843:MSGSendReaderStates() Send reader states: 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:1829:SCardGetStatusChange() Reader list changed [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_clnt.c:2138:SCardGetStatusChange() Event Loop End [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:446:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 6 [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardGetStatusChange#4866: returning 0x00000000 ["Command successful."], rgReaderStates=0xD963A100[{szReader="\\?PnP?\Notification", pvUserData=NULL, dwCurrentState=SCARD_STATE_UNAWARE, dwEventState=SCARD_STATE_CHANGED, cbAtr=0, rgbAtr=<>}] [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Request SCardGetStatusChange finished successfully with the following results: [0x00, [{"atr": binary[], "current_state": 0x00, "event_state": 0x02, "reader_name": "\\?PnP?\Notification"}]] [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Started processing request SCardListReaders(0x0577BD55,null)... [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardListReaders#4878(hContext=0x0577BD55, mszGroups=null): called... [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: CMD_GET_READERS_STATE from client 6 [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardListReaders#4878: returning 0x8010002E ["Cannot find a smart card reader."] [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Request SCardListReaders finished successfully with the following results: [0x8010002E] [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Started processing request SCardReleaseContext(0x0577BD55)... [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardReleaseContext#4879(hContext=0x0577BD55): called... [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:363:ContextThread() Received command: RELEASE_CONTEXT from client 6 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard.c:224:SCardReleaseContextServer() Releasing Context: 0x577BD55 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:481:ContextThread() RELEASE_CONTEXT for client 6 [DEBUG] [emulated IPC] The in-memory file 5 was closed [DEBUG] [emulated IPC] The in-memory file 6 was closed [DEBUG] [emulated IPC] The in-memory file 5 was destroyed [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:355:ContextThread() Client die: 6 [INFO] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] SCardReleaseContext#4879: returning 0x00000000 ["Command successful."] [DEBUG] [emulated IPC] The in-memory file 6 was destroyed [DEBUG] [PC/SC from aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (id 1234)] Request SCardReleaseContext finished successfully with the following results: [0x00] [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:1074:MSGCleanupClient() Thread is stopping: dwClientID=6, threadContext @0xdecdfcc0 [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:1080:MSGCleanupClient() Freeing SCONTEXT @0xdecdfcc0 [DEBUG] [PC/SC-Lite clients manager] Deleted client handler for aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa (handler id was 1234) [DEBUG] [PC/SC-Lite NaCl port] Shutting down the PC/SC-Lite daemon thread... [DEBUG] [PC/SC-Lite NaCl port] [daemon thread] Shutting down... [DEBUG] [libusb] libusb_get_device_list#4880(ctx=libusb_context<DEFAULT>, list=0xE87FEFE0): called... [DEBUG] [libusb] libusb_get_device_list#4880: returning 0, *list=0xD987D110([]) [DEBUG] [libusb] libusb_free_device_list#4881(list=0xD987D110([]), unref_devices=1): called... [DEBUG] [libusb] libusb_free_device_list#4881: returning [DEBUG] [libusb] libusb_exit#4882(ctx=libusb_context<DEFAULT>): called... [DEBUG] [libusb] libusb_exit#4882: returning [INFO] ../../../../../third_party/pcsc-lite/src/src/hotplug_libusb.c:544:HPEstablishUSBNotifications() Hotplug stopped [DEBUG] [emulated IPC] The in-memory file 3 was closed [DEBUG] [emulated IPC] The in-memory file 4 was closed [DEBUG] [emulated IPC] The in-memory file 3 was destroyed [DEBUG] [emulated IPC] The in-memory file 4 was destroyed [INFO] ../../../../../third_party/pcsc-lite/src/src/readerfactory.c:1374:RFCleanupReaders() entering cleaning function [INFO] ../../../../../third_party/pcsc-lite/src/src/winscard_svc.c:156:ContextsDeinitialize() remaining threads: 0 [DEBUG] [PC/SC-Lite NaCl port] The PC/SC-Lite daemon thread shut down. [ FAILED ] AllDevices/SmartCardConnectorApplicationReaderWithoutBuiltinCardCompatibilityTest.Basic/1, where GetParam() = 12-byte object <01-00 00-00 00-00 00-00 E0-40 F3-56> (3444 ms)
Sample failure: