00000000 debuglog.c:269:DebugLogSetLevel() debug level=debug 00000014 debuglog.c:298:DebugLogSetCategory() Debug options: APDU 00000071 configfile.l:254:DBGetReaderListDir() Parsing conf directory: /etc/reader.conf.d 00000019 configfile.l:266:DBGetReaderListDir() Skipping non regular file: .. 00000002 configfile.l:266:DBGetReaderListDir() Skipping non regular file: . 00000013 configfile.l:307:DBGetReaderList() Parsing conf file: /etc/reader.conf.d/libccidtwin 00000038 pcscdaemon.c:545:main() pcsc-lite 1.8.10 daemon ready. 00000681 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001 00000071 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001 00000085 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x8087, PID: 0x07DC, path: /dev/bus/usb/002/002 00000071 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x8087, PID: 0x07DC, path: /dev/bus/usb/002/002 00000072 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001 00000066 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x138A, PID: 0x003F, path: /dev/bus/usb/002/003 00000055 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001 00000061 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x05C8, PID: 0x035D, path: /dev/bus/usb/002/004 00000090 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x05C8, PID: 0x035D, path: /dev/bus/usb/002/004 00000057 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001 00000054 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x058F, PID: 0x9540, path: /dev/bus/usb/002/005 00000013 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x058F, PID: 0x9540, path: /dev/bus/usb/002/005 00000004 hotplug_libudev.c:321:HPAddDevice() Adding USB device: Alcor Micro AU9540 00000017 readerfactory.c:989:RFInitializeReader() Attempting startup of Alcor Micro AU9540 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so 00000101 readerfactory.c:874:RFBindFunctions() Loading IFD Handler 3.0 00000017 ifdhandler.c:1910:init_driver() Driver version: 1.4.16 00000286 ifdhandler.c:1927:init_driver() LogLevel: 0x0003 00000004 ifdhandler.c:1938:init_driver() DriverOptions: 0x0000 00000063 ifdhandler.c:1951:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F 00000003 ifdhandler.c:83:CreateChannelByNameOrChannel() Lun: 0, device: usb:058f/9540:libudev:0:/dev/bus/usb/002/005 00000003 ccid_usb.c:233:OpenUSBByName() Reader index: 0, Device: usb:058f/9540:libudev:0:/dev/bus/usb/002/005 00000007 ccid_usb.c:265:OpenUSBByName() interface_number: 0 00000002 ccid_usb.c:281:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist 00000268 ccid_usb.c:299:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr) 00000004 ccid_usb.c:300:OpenUSBByName() ifdProductString: Generic CCID driver 00000002 ccid_usb.c:301: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. 00000904 ccid_usb.c:426:OpenUSBByName() Checking device: 2/5 00000004 ccid_usb.c:488:OpenUSBByName() Trying to open USB bus/device: 2/5 00000072 ccid_usb.c:589:OpenUSBByName() Found Vendor/Product: 058F/9540 (Alcor Micro AU9540) 00000013 ccid_usb.c:591:OpenUSBByName() Using USB bus/device: 2/5 00000004 ccid_usb.c:1149:ControlUSB() request: 0x03 03000179 ccid_usb.c:1162:ControlUSB() control failed (2/5): -7 Resource temporarily unavailable 00000015 ccid_usb.c:1093:get_data_rates() IFD does not support GET_DATA_RATES request: -7 00000954 ccid_usb.c:1202:InterruptRead() before (0) 00100151 ccid_usb.c:1246:InterruptRead() after (0) (2) 00000017 -> 000000 65 00 00 00 00 00 00 00 00 00 00000786 <- 000000 81 00 00 00 00 00 00 01 00 01 00000014 -> 000000 65 00 00 00 00 00 01 00 00 00 00000786 <- 000000 81 00 00 00 00 00 01 01 00 01 00000018 ifdhandler.c:362:IFDHGetCapabilities() tag: 0xFB3, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000004 readerfactory.c:332:RFAddReader() Using the reader polling thread 00000003 ifdhandler.c:1770:IFDHICCPresence() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000005 -> 000000 65 00 00 00 00 00 02 00 00 00 00000794 <- 000000 81 00 00 00 00 00 02 01 00 01 00000012 ifdhandler.c:1891:IFDHICCPresence() Card present 00000056 ifdhandler.c:362:IFDHGetCapabilities() tag: 0xFAE, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000045 ifdhandler.c:450:IFDHGetCapabilities() Reader supports 1 slot(s) 00000033 ifdhandler.c:1770:IFDHICCPresence() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000024 -> 000000 65 00 00 00 00 00 03 00 00 00 00000135 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/003/001 00000067 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/003/001 00000090 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x0951, PID: 0x162B, path: /dev/bus/usb/003/002 00000079 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001 00000054 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001 00000056 hotplug_libudev.c:269:get_driver() Looking for a driver for VID: 0x8087, PID: 0x8000, path: /dev/bus/usb/001/002 00000030 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00000230 <- 000000 81 00 00 00 00 00 03 01 00 01 00000008 ifdhandler.c:1891:IFDHICCPresence() Card present 00000004 ifdhandler.c:1770:IFDHICCPresence() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000003 -> 000000 65 00 00 00 00 00 04 00 00 00 00000704 <- 000000 81 00 00 00 00 00 04 01 00 01 00000007 ifdhandler.c:1891:IFDHICCPresence() Card present 00000004 ifdhandler.c:1122:IFDHPowerICC() action: PowerUp, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000003 -> 000000 62 00 00 00 00 00 05 00 00 00 00009115 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010145 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010202 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010185 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010176 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010137 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010185 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010109 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010093 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010113 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010141 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010084 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010119 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010145 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010090 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010111 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010149 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010110 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010111 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010147 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010091 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010105 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010142 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010083 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010109 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010103 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010090 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010111 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010105 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010099 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010113 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010103 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010079 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00010108 readerfactory.c:1330:RFWaitForReaderInit() Waiting init for reader: Alcor Micro AU9540 00 00 00005665 <- 000000 80 17 00 00 00 00 05 00 00 00 3B 9F 95 81 31 FE 9F 00 65 46 53 05 30 06 71 DF 00 00 00 81 61 12 C4 00000015 eventhandler.c:266:EHStatusHandlerThread() powerState: POWER_STATE_POWERED 00000005 Card ATR: 3B 9F 95 81 31 FE 9F 00 65 46 53 05 30 06 71 DF 00 00 00 81 61 12 C4 00000004 ifdhandler.c:1770:IFDHICCPresence() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000004 -> 000000 65 00 00 00 00 00 06 00 00 00 00000721 <- 000000 81 00 00 00 00 00 06 01 00 01 00000007 ifdhandler.c:1891:IFDHICCPresence() Card absent 00000003 eventhandler.c:335:EHStatusHandlerThread() Card Removed From Alcor Micro AU9540 00 00 00000003 ifdhandler.c:291:IFDHPolling() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 600000 ms 00000003 ccid_usb.c:1202:InterruptRead() before (0) 50646078 winscard_msg_srv.c:230:ProcessEventsServer() Common channel packet arrival 00000015 winscard_msg_srv.c:242:ProcessEventsServer() ProcessCommonChannelRequest detects: 14 00000003 pcscdaemon.c:111:SVCServiceRunLoop() A new context thread creation is requested: 14 00000061 winscard_svc.c:301:ContextThread() Thread is started: dwClientID=14, threadContext @0x1048520 00000020 winscard_svc.c:319:ContextThread() Received command: CMD_VERSION from client 14 00000015 winscard_svc.c:331:ContextThread() Client is protocol version 4:3 00000002 winscard_svc.c:351:ContextThread() CMD_VERSION rv=0x0 for client 14 00000095 winscard_svc.c:319:ContextThread() Received command: ESTABLISH_CONTEXT from client 14 00000025 winscard.c:193:SCardEstablishContext() Establishing Context: 0x37895976 00000003 winscard_svc.c:412:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 14 00000092 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000044 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000038 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000022 winscard_svc.c:319:ContextThread() Received command: CONNECT from client 14 00000008 winscard.c:235:SCardConnect() Attempting Connect to Alcor Micro AU9540 00 00 using protocol: 3 00000003 readerfactory.c:745:RFReaderInfo() RefReader() count was: 1 00000001 winscard.c:422:SCardConnect() Direct access: no protocol selected 00000002 winscard.c:429:SCardConnect() hCard Identity: 38907aaf 00000009 winscard.c:490:SCardConnect() UnrefReader() count was: 2 00000002 winscard_svc.c:453:ContextThread() CONNECT rv=0x0 for client 14 00000020 winscard_svc.c:319:ContextThread() Received command: CONTROL from client 14 00000006 readerfactory.c:772:RFReaderInfoById() RefReader() count was: 1 00000003 ifdhandler.c:1360:IFDHControl() ControlCode: 0x42000D48, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000001 Control TxBuffer: 00000003 Control RxBuffer: 12 04 42 33 00 12 00000002 winscard.c:1350:SCardControl() UnrefReader() count was: 2 00000001 winscard_svc.c:652:ContextThread() CONTROL rv=0x0 for client 14 00000020 winscard_svc.c:319:ContextThread() Received command: DISCONNECT from client 14 00000005 readerfactory.c:772:RFReaderInfoById() RefReader() count was: 1 00000001 winscard.c:850:SCardDisconnect() Active Contexts: 1 00000002 winscard.c:851:SCardDisconnect() dwDisposition: 0 00000001 winscard.c:1016:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD 00000002 ifdhandler.c:362:IFDHGetCapabilities() tag: 0xFB2, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000003 winscard.c:1030:SCardDisconnect() Stopping polling thread 00000001 ifdhandler.c:327:IFDHStopPolling() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000026 winscard.c:1043:SCardDisconnect() UnrefReader() count was: 2 00000004 winscard_svc.c:490:ContextThread() DISCONNECT rv=0x0 for client 14 00000010 ccid_usb.c:1246:InterruptRead() after (0) (3) 00000007 ccid_usb.c:1261:InterruptRead() InterruptRead (2/5): Resource temporarily unavailable 00000022 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000018 winscard_svc.c:319:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 00000009 winscard_svc.c:319:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 00000005 winscard_svc.c:393:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 00000013 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000021 winscard_svc.c:319:ContextThread() Received command: RELEASE_CONTEXT from client 14 00000006 winscard.c:204:SCardReleaseContext() Releasing Context: 0x37895976 00000002 winscard_svc.c:427:ContextThread() RELEASE_CONTEXT rv=0x0 for client 14 00000013 winscard_svc.c:311:ContextThread() Client die: 14 00000010 winscard_svc.c:933:MSGCleanupClient() Thread is stopping: dwClientID=14, threadContext @0x1048520 00000006 winscard_svc.c:939:MSGCleanupClient() Freeing SCONTEXT @0x1048520 00399988 ifdhandler.c:1770:IFDHICCPresence() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000017 -> 000000 65 00 00 00 00 00 07 00 00 00 00000791 <- 000000 81 00 00 00 00 00 07 01 00 01 00000012 ifdhandler.c:1891:IFDHICCPresence() Card present 00000005 ifdhandler.c:1770:IFDHICCPresence() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000004 -> 000000 65 00 00 00 00 00 08 00 00 00 00000806 <- 000000 81 00 00 00 00 00 08 01 00 01 00000013 ifdhandler.c:1891:IFDHICCPresence() Card present 00000004 ifdhandler.c:1122:IFDHPowerICC() action: PowerUp, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000005 -> 000000 62 00 00 00 00 00 09 00 00 00 00348793 <- 000000 80 17 00 00 00 00 09 00 00 00 3B 9F 95 81 31 FE 9F 00 65 46 53 05 30 06 71 DF 00 00 00 81 61 12 C4 00000017 eventhandler.c:381:EHStatusHandlerThread() powerState: POWER_STATE_POWERED 00000003 eventhandler.c:396:EHStatusHandlerThread() Card inserted into Alcor Micro AU9540 00 00 00000005 Card ATR: 3B 9F 95 81 31 FE 9F 00 65 46 53 05 30 06 71 DF 00 00 00 81 61 12 C4 00000005 ifdhandler.c:291:IFDHPolling() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 600000 ms 00000004 ccid_usb.c:1202:InterruptRead() before (0) 07863426 winscard_msg_srv.c:230:ProcessEventsServer() Common channel packet arrival 00000023 winscard_msg_srv.c:242:ProcessEventsServer() ProcessCommonChannelRequest detects: 14 00000003 pcscdaemon.c:111:SVCServiceRunLoop() A new context thread creation is requested: 14 00000049 winscard_svc.c:301:ContextThread() Thread is started: dwClientID=14, threadContext @0x1048520 00000013 winscard_svc.c:319:ContextThread() Received command: CMD_VERSION from client 14 00000005 winscard_svc.c:331:ContextThread() Client is protocol version 4:3 00000003 winscard_svc.c:351:ContextThread() CMD_VERSION rv=0x0 for client 14 00000024 winscard_svc.c:319:ContextThread() Received command: ESTABLISH_CONTEXT from client 14 00000033 winscard.c:193:SCardEstablishContext() Establishing Context: 0x1CBB584D 00000004 winscard_svc.c:412:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 14 00000032 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000023 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000022 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000026 winscard_svc.c:319:ContextThread() Received command: CONNECT from client 14 00000011 winscard.c:235:SCardConnect() Attempting Connect to Alcor Micro AU9540 00 00 using protocol: 3 00000002 readerfactory.c:745:RFReaderInfo() RefReader() count was: 1 00000001 winscard.c:330:SCardConnect() powerState: POWER_STATE_INUSE 00000002 prothandler.c:87:PHSetProtocol() Attempting PTS to T=1 00000003 ifdhandler.c:668:IFDHSetProtocolParameters() protocol T=1, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000002 ifdhandler.c:792:IFDHSetProtocolParameters() Set speed to 115625 bauds 00000002 ifdhandler.c:949:IFDHSetProtocolParameters() BWI/CWI (TB3) present: 0x9F 00000004 ifdhandler.c:983:IFDHSetProtocolParameters() IFSC (TA3) present: 254 00000001 ifdhandler.c:987:IFDHSetProtocolParameters() Timeout: 124152 ms 00000002 commands.c:2207:SetParameters() length: 7 bytes 00000003 -> 000000 61 07 00 00 00 00 0A 01 00 00 95 10 00 9F 00 FE 00 00348321 <- 000000 82 00 00 00 00 00 0A 01 00 00 00000016 ifdhandler.c:1044:IFDHSetProtocolParameters() IFSC (TA3) present: 254 00000003 ifdhandler.c:1057:IFDHSetProtocolParameters() T=1: IFSC=254, IFSD=254 00000003 winscard.c:409:SCardConnect() Active Protocol: T=1 00000003 winscard.c:429:SCardConnect() hCard Identity: 69ba1d48 00000003 winscard.c:490:SCardConnect() UnrefReader() count was: 2 00000005 winscard_svc.c:453:ContextThread() CONNECT rv=0x0 for client 14 00000055 winscard_svc.c:319:ContextThread() Received command: CONTROL from client 14 00000013 readerfactory.c:772:RFReaderInfoById() RefReader() count was: 1 00000005 ifdhandler.c:1360:IFDHControl() ControlCode: 0x42000D48, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000002 Control TxBuffer: 00000005 Control RxBuffer: 12 04 42 33 00 12 00000003 winscard.c:1350:SCardControl() UnrefReader() count was: 2 00000003 winscard_svc.c:652:ContextThread() CONTROL rv=0x0 for client 14 00000041 winscard_svc.c:319:ContextThread() Received command: DISCONNECT from client 14 00000012 readerfactory.c:772:RFReaderInfoById() RefReader() count was: 1 00000003 winscard.c:850:SCardDisconnect() Active Contexts: 1 00000003 winscard.c:851:SCardDisconnect() dwDisposition: 0 00000004 winscard.c:1016:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD 00000004 ifdhandler.c:362:IFDHGetCapabilities() tag: 0xFB2, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000003 winscard.c:1030:SCardDisconnect() Stopping polling thread 00000003 ifdhandler.c:327:IFDHStopPolling() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000051 winscard.c:1043:SCardDisconnect() UnrefReader() count was: 2 00000010 winscard_svc.c:490:ContextThread() DISCONNECT rv=0x0 for client 14 00000019 ccid_usb.c:1246:InterruptRead() after (0) (3) 00000010 ccid_usb.c:1261:InterruptRead() InterruptRead (2/5): Resource temporarily unavailable 00000067 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000038 winscard_svc.c:319:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 00000023 winscard_svc.c:319:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 00000010 winscard_svc.c:393:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 00000025 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000045 winscard_svc.c:319:ContextThread() Received command: RELEASE_CONTEXT from client 14 00000009 winscard.c:204:SCardReleaseContext() Releasing Context: 0x1CBB584D 00000005 winscard_svc.c:427:ContextThread() RELEASE_CONTEXT rv=0x0 for client 14 00000031 winscard_svc.c:311:ContextThread() Client die: 14 00000017 winscard_svc.c:933:MSGCleanupClient() Thread is stopping: dwClientID=14, threadContext @0x1048520 00000006 winscard_svc.c:939:MSGCleanupClient() Freeing SCONTEXT @0x1048520 00399881 ifdhandler.c:1770:IFDHICCPresence() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000016 -> 000000 65 00 00 00 00 00 0B 00 00 00 00000781 <- 000000 81 00 00 00 00 00 0B 01 00 01 00000015 ifdhandler.c:1891:IFDHICCPresence() Card absent 00000005 eventhandler.c:335:EHStatusHandlerThread() Card Removed From Alcor Micro AU9540 00 00 00000009 ifdhandler.c:291:IFDHPolling() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 600000 ms 00000005 ccid_usb.c:1202:InterruptRead() before (0) 00169311 winscard_msg_srv.c:230:ProcessEventsServer() Common channel packet arrival 00000015 winscard_msg_srv.c:242:ProcessEventsServer() ProcessCommonChannelRequest detects: 14 00000003 pcscdaemon.c:111:SVCServiceRunLoop() A new context thread creation is requested: 14 00000032 winscard_svc.c:301:ContextThread() Thread is started: dwClientID=14, threadContext @0x1048520 00000010 winscard_svc.c:319:ContextThread() Received command: CMD_VERSION from client 14 00000006 winscard_svc.c:331:ContextThread() Client is protocol version 4:3 00000004 winscard_svc.c:351:ContextThread() CMD_VERSION rv=0x0 for client 14 00000029 winscard_svc.c:319:ContextThread() Received command: ESTABLISH_CONTEXT from client 14 00000040 winscard.c:193:SCardEstablishContext() Establishing Context: 0x3EDC6D2D 00000005 winscard_svc.c:412:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 14 00000063 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000069 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000046 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000025 winscard_svc.c:319:ContextThread() Received command: CONNECT from client 14 00000012 winscard.c:235:SCardConnect() Attempting Connect to Alcor Micro AU9540 00 00 using protocol: 3 00000006 readerfactory.c:745:RFReaderInfo() RefReader() count was: 1 00000002 winscard.c:422:SCardConnect() Direct access: no protocol selected 00000002 winscard.c:429:SCardConnect() hCard Identity: 7ee25cad 00000003 winscard.c:490:SCardConnect() UnrefReader() count was: 2 00000002 winscard_svc.c:453:ContextThread() CONNECT rv=0x0 for client 14 00000026 winscard_svc.c:319:ContextThread() Received command: CONTROL from client 14 00000009 readerfactory.c:772:RFReaderInfoById() RefReader() count was: 1 00000004 ifdhandler.c:1360:IFDHControl() ControlCode: 0x42000D48, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000002 Control TxBuffer: 00000003 Control RxBuffer: 12 04 42 33 00 12 00000002 winscard.c:1350:SCardControl() UnrefReader() count was: 2 00000002 winscard_svc.c:652:ContextThread() CONTROL rv=0x0 for client 14 00000023 winscard_svc.c:319:ContextThread() Received command: DISCONNECT from client 14 00000007 readerfactory.c:772:RFReaderInfoById() RefReader() count was: 1 00000003 winscard.c:850:SCardDisconnect() Active Contexts: 1 00000002 winscard.c:851:SCardDisconnect() dwDisposition: 0 00000003 winscard.c:1016:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD 00000004 ifdhandler.c:362:IFDHGetCapabilities() tag: 0xFB2, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000003 winscard.c:1030:SCardDisconnect() Stopping polling thread 00000002 ifdhandler.c:327:IFDHStopPolling() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000029 winscard.c:1043:SCardDisconnect() UnrefReader() count was: 2 00000006 winscard_svc.c:490:ContextThread() DISCONNECT rv=0x0 for client 14 00000007 ccid_usb.c:1246:InterruptRead() after (0) (3) 00000006 ccid_usb.c:1261:InterruptRead() InterruptRead (2/5): Resource temporarily unavailable 00000037 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000027 winscard_svc.c:319:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 00000011 winscard_svc.c:319:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 00000006 winscard_svc.c:393:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 00000018 winscard_svc.c:319:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000028 winscard_svc.c:319:ContextThread() Received command: RELEASE_CONTEXT from client 14 00000006 winscard.c:204:SCardReleaseContext() Releasing Context: 0x3EDC6D2D 00000004 winscard_svc.c:427:ContextThread() RELEASE_CONTEXT rv=0x0 for client 14 00000018 winscard_svc.c:311:ContextThread() Client die: 14 00000012 winscard_svc.c:933:MSGCleanupClient() Thread is stopping: dwClientID=14, threadContext @0x1048520 00000004 winscard_svc.c:939:MSGCleanupClient() Freeing SCONTEXT @0x1048520 00399966 ifdhandler.c:1770:IFDHICCPresence() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000020 -> 000000 65 00 00 00 00 00 0C 00 00 00 00000811 <- 000000 81 00 00 00 00 00 0C 01 00 01 00000014 ifdhandler.c:1891:IFDHICCPresence() Card present 00000004 ifdhandler.c:1770:IFDHICCPresence() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000005 -> 000000 65 00 00 00 00 00 0D 00 00 00 00000781 <- 000000 81 00 00 00 00 00 0D 01 00 01 00000012 ifdhandler.c:1891:IFDHICCPresence() Card present 00000005 ifdhandler.c:1122:IFDHPowerICC() action: PowerUp, usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 00000005 -> 000000 62 00 00 00 00 00 0E 00 00 00 00348847 <- 000000 80 17 00 00 00 00 0E 00 00 00 3B 9F 95 81 31 FE 9F 00 65 46 53 05 30 06 71 DF 00 00 00 81 61 12 C4 00000016 eventhandler.c:381:EHStatusHandlerThread() powerState: POWER_STATE_POWERED 00000003 eventhandler.c:396:EHStatusHandlerThread() Card inserted into Alcor Micro AU9540 00 00 00000006 Card ATR: 3B 9F 95 81 31 FE 9F 00 65 46 53 05 30 06 71 DF 00 00 00 81 61 12 C4 00000004 ifdhandler.c:291:IFDHPolling() usb:058f/9540:libudev:0:/dev/bus/usb/002/005 (lun: 0) 600000 ms 00000005 ccid_usb.c:1202:InterruptRead() before (0)