00000000 debuglog.c:269:DebugLogSetLevel() debug level=debug
00000029 debuglog.c:298:DebugLogSetCategory() Debug options: APDU
00000097 configfile.l:254:DBGetReaderListDir() Parsing conf directory: /etc/reader.conf.d
00000062 configfile.l:307:DBGetReaderList() Parsing conf file: /etc/reader.conf.d/libccidtwin
00000083 configfile.l:266:DBGetReaderListDir() Skipping non regular file: ..
00000006 configfile.l:266:DBGetReaderListDir() Skipping non regular file: .
00000008 pcscdaemon.c:545:main() pcsc-lite 1.8.10 daemon ready.
00001666 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000094 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/002/001
00000083 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000062 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000067 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x8087, PID: 0x0024, path: /dev/bus/usb/003/002
00000069 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/003/003
00000069 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/003/003
00000067 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/003/003
00000064 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x8087, PID: 0x0024, path: /dev/bus/usb/003/002
00000099 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/004/001
00000067 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/004/001
00000067 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x8087, PID: 0x0024, path: /dev/bus/usb/004/002
00000071 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x05E3, PID: 0x070E, path: /dev/bus/usb/004/003
00000068 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x8087, PID: 0x0024, path: /dev/bus/usb/004/002
00000070 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x058F, PID: 0x9520, path: /dev/bus/usb/004/004
00000017 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x058F, PID: 0x9520, path: /dev/bus/usb/004/004
00000003 hotplug_libudev.c:321:HPAddDevice() Adding USB device: Alcor Micro AU9520
00000022 readerfactory.c:989:RFInitializeReader() Attempting startup of Alcor Micro AU9520 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
00000187 readerfactory.c:874:RFBindFunctions() Loading IFD Handler 3.0
00000025 ifdhandler.c:1950:init_driver() Driver version: 1.4.22
00000793 ifdhandler.c:1967:init_driver() LogLevel: 0x0003
00000079 ifdhandler.c:1978:init_driver() DriverOptions: 0x0000
00000206 ifdhandler.c:1991:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000028 ifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:058f/9520:libudev:0:/dev/bus/usb/004/004
00000026 ccid_usb.c:234:OpenUSBByName() Reader index: 0, Device: usb:058f/9520:libudev:0:/dev/bus/usb/004/004
00000030 ccid_usb.c:266:OpenUSBByName() interface_number: 0
00000022 ccid_usb.c:267:OpenUSBByName() usb bus/device: 4/4
00000021 ccid_usb.c:284:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
00000859 ccid_usb.c:302:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr)
00000010 ccid_usb.c:303:OpenUSBByName() ifdProductString: Generic CCID driver
00000004 ccid_usb.c:304: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.
00001927 ccid_usb.c:388:OpenUSBByName() Try device: 4/4
00000088 ccid_usb.c:398:OpenUSBByName() vid/pid : 058F/9520
00000022 ccid_usb.c:445:OpenUSBByName() Checking device: 4/4
00000020 ccid_usb.c:507:OpenUSBByName() Trying to open USB bus/device: 4/4
00059785 ccid_usb.c:609:OpenUSBByName() Found Vendor/Product: 058F/9520 (Alcor Micro AU9520)
00000009 ccid_usb.c:611:OpenUSBByName() Using USB bus/device: 4/4
00000003 ccid_usb.c:1172:ControlUSB() request: 0x03
00001916 receive: 0F 07 00 00 C5 07 00 00 69 09 00 00 B7 09 00 00 F3 0C 00 00 1D 0E 00 00 8A 0F 00 00 D2 12 00 00 6D 13 00 00 E7 19 00 00 3B 1C 00 00 15 1F 00 00 A3 25 00 00 DA 26 00 00 CE 33 00 00 75 38 00 00 2A 3E 00 00 47 4B 00 00 B4 4D 00 00 B0 54 00 00 3F 5D 00 00 9B 67 00 00 EA 70 00 00 8F 74 00 00 54 7C 00 00 25 8D 00 00 8E 96 00 00 69 9B 00 00 5F A9 00 00 31 BC 00 00 43 C2 00 00 37 CF 00 00 D5 E1 00 00 1D E9 00 00 A8 F8 00 00 04 03 01 00 4A 1A 01 00 1B 2D 01 00 D2 36 01 00 BF 52 01 00 62 78 01 00 86 84 01 00 6D 9E 01 00 A9 C3 01 00 3B D2 01 00 08 06 02 00 93 34 02 00 37 5A 02 00 A4 6D 02 00 0D 09 03 00 DA 3C 03 00 52 87 03 00 48 DB 04 00 
00000014 ccid_usb.c:1152:get_data_rates() declared: 1807 bps
00000003 ccid_usb.c:1152:get_data_rates() declared: 1989 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 2409 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 2487 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 3315 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 3613 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 3978 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 4818 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 4973 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 6631 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 7227 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 7957 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 9635 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 9946 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 13262 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 14453 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 15914 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 19271 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 19892 bps
00000011 ccid_usb.c:1152:get_data_rates() declared: 21680 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 23871 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 26523 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 28906 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 29839 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 31828 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 36133 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 38542 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 39785 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 43359 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 48177 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 49731 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 53047 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 57813 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 59677 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 63656 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 66308 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 72266 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 77083 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 79570 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 86719 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 96354 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 99462 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 106093 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 115625 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 119355 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 132616 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 144531 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 154167 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 159140 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 198925 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 212186 bps
00000001 ccid_usb.c:1152:get_data_rates() declared: 231250 bps
00000002 ccid_usb.c:1152:get_data_rates() declared: 318280 bps
00000892 ccid_usb.c:1225:InterruptRead() before (0)
00102209 ccid_usb.c:1269:InterruptRead() after (0) (2)
00000020 -> 000000 65 00 00 00 00 00 00 00 00 00 
05000354 ccid_usb.c:774:WriteUSB() write failed (4/4): -7 LIBUSB_ERROR_TIMEOUT
00000023 -> 000000 65 00 00 00 00 00 01 00 00 00 
05000393 ccid_usb.c:774:WriteUSB() write failed (4/4): -7 LIBUSB_ERROR_TIMEOUT
00000022 -> 000000 65 00 00 00 00 00 02 00 00 00 
05000310 ccid_usb.c:774:WriteUSB() write failed (4/4): -7 LIBUSB_ERROR_TIMEOUT
00000017 ifdhandler.c:185:CreateChannelByNameOrChannel() failed
00000010 ccid_usb.c:856:CloseUSB() Closing USB device: 4/4
00000002 ccid_usb.c:866:CloseUSB() Last slot closed. Release resources
00000040 ccid_usb.c:186:close_libusb_if_needed() libusb_exit
00000163 readerfactory.c:1020:RFInitializeReader() Open Port 0x200000 Failed (usb:058f/9520:libudev:0:/dev/bus/usb/004/004)
00000036 readerfactory.c:312:RFAddReader() Alcor Micro AU9520 init failed.
00000022 readerfactory.c:535:RFRemoveReader() UnrefReader() count was: 1
00000006 readerfactory.c:1040:RFUnInitializeReader() Attempting shutdown of Alcor Micro AU9520 00 00.
00000003 readerfactory.c:911:RFUnloadReader() Unloading reader driver.
00000053 hotplug_libudev.c:391:HPAddDevice() Failed adding USB device: Alcor Micro AU9520
00000142 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x8087, PID: 0x0024, path: /dev/bus/usb/004/002
07855136 winscard_msg_srv.c:230:ProcessEventsServer() Common channel packet arrival
00000086 winscard_msg_srv.c:242:ProcessEventsServer() ProcessCommonChannelRequest detects: 5
00000022 pcscdaemon.c:111:SVCServiceRunLoop() A new context thread creation is requested: 5
00000100 winscard_svc.c:301:ContextThread() Thread is started: dwClientID=5, threadContext @0x1e0a170
00000063 winscard_svc.c:319:ContextThread() Received command: CMD_VERSION from client 5
00000023 winscard_svc.c:331:ContextThread() Client is protocol version 4:3
00000006 winscard_svc.c:351:ContextThread() CMD_VERSION rv=0x0 for client 5
00000042 winscard_svc.c:319:ContextThread() Received command: ESTABLISH_CONTEXT from client 5
00000031 winscard.c:193:SCardEstablishContext() Establishing Context: 0x639E2CC5
00000005 winscard_svc.c:412:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 5
00000029 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 5
00000067 winscard_svc.c:319:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 5
00000031 winscard_svc.c:319:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 5
00000006 winscard_svc.c:393:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 5
00000019 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 5
00000066 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 5
00000059 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 5
00000047 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 5
00000039 winscard_svc.c:319:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 5
12346241 winscard_svc.c:311:ContextThread() Client die: 5
00000088 winscard.c:204:SCardReleaseContext() Releasing Context: 0x639E2CC5
00000024 winscard_svc.c:933:MSGCleanupClient() Thread is stopping: dwClientID=5, threadContext @0x1e0a170
00000005 winscard_svc.c:939:MSGCleanupClient() Freeing SCONTEXT @0x1e0a170