00000000 debuglog.c:299:DebugLogSetLevel() debug level=debug 00000012 debuglog.c:320:DebugLogSetCategory() Debug options: APDU 00000003 [140334173120448] pcscdaemon.c:353:main() Force colored logs 00000017 [140334173120448] utils.c:81:GetDaemonPid() Can't open /run/pcscd/pcscd.pid: No such file or directory 00000089 [140334173120448] configfile.l:293:DBGetReaderListDir() Parsing conf directory: /etc/reader.conf.d 00000017 [140334173120448] configfile.l:329:DBGetReaderListDir() Skipping non regular file: . 00000002 [140334173120448] configfile.l:329:DBGetReaderListDir() Skipping non regular file: .. 00000002 [140334173120448] configfile.l:369:DBGetReaderList() Parsing conf file: /etc/reader.conf.d/libccidtwin 00000028 [140334173120448] pcscdaemon.c:663:main() pcsc-lite 1.8.26 daemon ready. 00002192 [140334173120448] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/002/001 00000106 [140334173120448] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/002/001 00000110 [140334173120448] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x80EE, PID: 0x0021, path: /dev/bus/usb/002/002 00000083 [140334173120448] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/002/001 00000089 [140334173120448] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x08E6, PID: 0x3437, path: /dev/bus/usb/002/003 00000003 [140334173120448] hotplug_libudev.c:440:HPAddDevice() Adding USB device: Gemalto PC Twin Reader 00000021 [140334173120448] readerfactory.c:1074:RFInitializeReader() Attempting startup of Gemalto PC Twin Reader 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so 00000119 [140334173120448] readerfactory.c:950:RFBindFunctions() Loading IFD Handler 3.0 00000012 [140334173120448] ifdhandler.c:1961:init_driver() Driver version: 1.4.31 00000388 [140334173120448] ifdhandler.c:1978:init_driver() LogLevel: 0x0003 00000003 [140334173120448] ifdhandler.c:1989:init_driver() DriverOptions: 0x0000 00000106 [140334173120448] ifdhandler.c:2002:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F 00000003 [140334173120448] ifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 00000002 [140334173120448] ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 00000005 [140334173120448] ccid_usb.c:269:OpenUSBByName() interface_number: 0 00000002 [140334173120448] ccid_usb.c:270:OpenUSBByName() usb bus/device: 2/3 00000002 [140334173120448] ccid_usb.c:302:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist 00000437 [140334173120448] ccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr) 00000007 [140334173120448] ccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver 00000003 [140334173120448] ccid_usb.c:322: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. 00001556 [140334173120448] ccid_usb.c:406:OpenUSBByName() Try device: 2/3 00000022 [140334173120448] ccid_usb.c:416:OpenUSBByName() vid/pid : 08E6/3437 00000002 [140334173120448] ccid_usb.c:482:OpenUSBByName() Checking device: 2/3 00000002 [140334173120448] ccid_usb.c:553:OpenUSBByName() Trying to open USB bus/device: 2/3 00092233 [140334173120448] ccid_usb.c:659:OpenUSBByName() Found Vendor/Product: 08E6/3437 (Gemalto PC Twin Reader) 00000028 [140334173120448] ccid_usb.c:661:OpenUSBByName() Using USB bus/device: 2/3 00000006 [140334173120448] ccid_usb.c:722:OpenUSBByName() bNumDataRatesSupported is 0 00014409 [140334173120448] ccid_usb.c:1306:InterruptRead() before (0), timeout: 100 ms 00128144 [140334173120448] ccid_usb.c:1352:InterruptRead() after (0) (2) 00000050 [140334173120448] -> 000000 65 00 00 00 00 00 00 00 00 00 00024912 [140334173120448] <- 000000 81 00 00 00 00 00 00 01 00 00 00000011 [140334173120448] -> 000000 65 00 00 00 00 00 01 00 00 00 00009752 [140334173120448] <- 000000 81 00 00 00 00 00 01 01 00 00 00000008 [140334173120448] -> 000000 6B 01 00 00 00 00 02 00 00 00 6A 00009871 [140334173120448] <- 000000 83 00 00 00 00 00 02 41 0A 00 00000023 [140334173120448] commands.c:1004:CmdEscapeCheck error on byte 10 00000003 [140334173120448] ccid.c:239:set_gemalto_firmware_features() GET_FIRMWARE_FEATURES failed: 612, len=0 00000005 [140334173120448] ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB3, usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000002 [140334173120448] readerfactory.c:396:RFAddReader() Using the reader polling thread 00000002 [140334173120448] ifdhandler.c:1821:IFDHICCPresence() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000004 [140334173120448] -> 000000 65 00 00 00 00 00 03 00 00 00 00009202 [140334173120448] <- 000000 81 00 00 00 00 00 03 01 00 00 00000009 [140334173120448] ifdhandler.c:1941:IFDHICCPresence() Card present 00000036 [140334173120448] ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFAE, usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000002 [140334173120448] ifdhandler.c:476:IFDHGetCapabilities() Reader supports 1 slot(s) 00000041 [140334156093184] ifdhandler.c:1821:IFDHICCPresence() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000016 [140334156093184] -> 000000 65 00 00 00 00 00 04 00 00 00 00000215 [140334173120448] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001 00000077 [140334173120448] readerfactory.c:1409:RFWaitForReaderInit() Waiting init for reader: Gemalto PC Twin Reader 00 00 00008212 [140334156093184] <- 000000 81 00 00 00 00 00 04 01 00 00 00000033 [140334156093184] ifdhandler.c:1941:IFDHICCPresence() Card present 00000015 [140334156093184] ifdhandler.c:1821:IFDHICCPresence() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000014 [140334156093184] -> 000000 65 00 00 00 00 00 05 00 00 00 00001784 [140334173120448] readerfactory.c:1409:RFWaitForReaderInit() Waiting init for reader: Gemalto PC Twin Reader 00 00 00008887 [140334156093184] <- 000000 81 00 00 00 00 00 05 01 00 00 00000045 [140334156093184] ifdhandler.c:1941:IFDHICCPresence() Card present 00000011 [140334156093184] ifdhandler.c:1153:IFDHPowerICC() action: PowerUp, usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000016 [140334156093184] -> 000000 62 00 00 00 00 00 06 01 00 00 00001810 [140334173120448] readerfactory.c:1409:RFWaitForReaderInit() Waiting init for reader: Gemalto PC Twin Reader 00 00 00010064 [140334173120448] readerfactory.c:1409:RFWaitForReaderInit() Waiting init for reader: Gemalto PC Twin Reader 00 00 00010795 [140334173120448] readerfactory.c:1409:RFWaitForReaderInit() Waiting init for reader: Gemalto PC Twin Reader 00 00 00010425 [140334173120448] readerfactory.c:1409:RFWaitForReaderInit() Waiting init for reader: Gemalto PC Twin Reader 00 00 00009592 [140334156093184] <- 000000 80 09 00 00 00 00 06 00 00 00 3B 65 00 00 20 63 CB BD 80 00000063 [140334156093184] eventhandler.c:289:EHStatusHandlerThread() powerState: POWER_STATE_POWERED 00000102 [140334156093184] Card ATR: 3B 65 00 00 20 63 CB BD 80  00000028 [140334156093184] ifdhandler.c:1821:IFDHICCPresence() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000015 [140334156093184] -> 000000 65 00 00 00 00 00 07 00 00 00 00016680 [140334156093184] <- 000000 81 00 00 00 00 00 07 00 00 00 00000034 [140334156093184] ifdhandler.c:1941:IFDHICCPresence() Card present 00000014 [140334156093184] ifdhandler.c:309:IFDHPolling() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 5000 ms 00000009 [140334156093184] ccid_usb.c:1306:InterruptRead() before (0), timeout: 5000 ms 05017187 [140334156093184] ccid_usb.c:1352:InterruptRead() after (0) (2) 00000037 [140334156093184] ifdhandler.c:1821:IFDHICCPresence() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000023 [140334156093184] -> 000000 65 00 00 00 00 00 08 00 00 00 00024410 [140334156093184] <- 000000 81 00 00 00 00 00 08 00 00 00 00000009 [140334156093184] ifdhandler.c:1941:IFDHICCPresence() Card present 00000002 [140334156093184] ifdhandler.c:1153:IFDHPowerICC() action: PowerDown, usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000004 [140334156093184] -> 000000 63 00 00 00 00 00 09 00 00 00 00010058 [140334156093184] <- 000000 81 00 00 00 00 00 09 01 00 00 00000008 [140334156093184] eventhandler.c:482:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED 00000002 [140334156093184] ifdhandler.c:1821:IFDHICCPresence() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000002 [140334156093184] -> 000000 65 00 00 00 00 00 0A 00 00 00 00011863 [140334156093184] <- 000000 81 00 00 00 00 00 0A 01 00 00 00000036 [140334156093184] ifdhandler.c:1941:IFDHICCPresence() Card present 00000020 [140334156093184] ifdhandler.c:309:IFDHPolling() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 600000 ms 00000002 [140334156093184] ccid_usb.c:1306:InterruptRead() before (0), timeout: 600000 ms 16962431 [140334173120448] winscard_msg_srv.c:256:ProcessEventsServer() Common channel packet arrival 00000012 [140334173120448] winscard_msg_srv.c:267:ProcessEventsServer() ProcessCommonChannelRequest detects: 14 00000002 [140334173120448] pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 14 00000125 [140334139307776] winscard_svc.c:340:ContextThread() Authorized PC/SC client 00000007 [140334139307776] winscard_svc.c:343:ContextThread() Thread is started: dwClientID=14, threadContext @0x560ceede14c0 00000005 [140334139307776] winscard_svc.c:361:ContextThread() Received command: CMD_VERSION from client 14 00000003 [140334139307776] winscard_svc.c:373:ContextThread() Client is protocol version 4:4 00000003 [140334139307776] winscard_svc.c:396:ContextThread() CMD_VERSION rv=0x0 for client 14 00000100 [140334139307776] winscard_svc.c:361:ContextThread() Received command: ESTABLISH_CONTEXT from client 14 00000010 [140334139307776] winscard.c:215:SCardEstablishContext() Establishing Context: 0x5D6FEA69 00000002 [140334139307776] winscard_svc.c:461:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 14 00000132 [140334139307776] winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000122 [140334139307776] winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 00000114 [140334139307776] winscard_svc.c:361:ContextThread() Received command: CONNECT from client 14 00000007 [140334139307776] winscard_svc.c:499:ContextThread() Authorized client for 'Gemalto PC Twin Reader 00 00' 00000002 [140334139307776] winscard.c:258:SCardConnect() Attempting Connect to Gemalto PC Twin Reader 00 00 using protocol: 3 00000002 [140334139307776] readerfactory.c:821:RFReaderInfo() RefReader() count was: 1 00000002 [140334139307776] ifdhandler.c:1821:IFDHICCPresence() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000003 [140334139307776] -> 000000 65 00 00 00 00 00 0B 00 00 00 00025060 [140334139307776] <- 000000 81 00 00 00 00 00 0B 01 00 00 00000013 [140334139307776] ifdhandler.c:1941:IFDHICCPresence() Card present 00000004 [140334139307776] ifdhandler.c:1153:IFDHPowerICC() action: PowerUp, usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000004 [140334139307776] -> 000000 62 00 00 00 00 00 0C 01 00 00 00042866 [140334139307776] <- 000000 80 09 00 00 00 00 0C 00 00 00 3B 65 00 00 20 63 CB BD 80 00000111 [140334139307776] winscard.c:332:SCardConnect() power up complete. 00000022 [140334139307776] Card ATR: 3B 65 00 00 20 63 CB BD 80 00000020 [140334139307776] winscard.c:352:SCardConnect() powerState: POWER_STATE_IN_USE 00000013 [140334139307776] prothandler.c:107:PHSetProtocol() Attempting PTS to T=0 00000020 [140334139307776] ifdhandler.c:694:IFDHSetProtocolParameters() protocol T=0, usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000014 [140334139307776] towitoko/atr.c:357:ATR_GetDefaultProtocol() no default protocol found in ATR. Using T=0 00000019 [140334139307776] ifdhandler.c:1058:IFDHSetProtocolParameters() Communication timeout: 232133 ms 00000012 [140334139307776] commands.c:2254:SetParameters() length: 5 bytes 00000022 [140334139307776] -> 000000 61 05 00 00 00 00 0D 00 00 00 11 00 00 0A 00 00020968 [140334139307776] <- 000000 82 05 00 00 00 00 0D 00 00 00 11 00 00 0A 00 00000048 [140334139307776] winscard.c:430:SCardConnect() Active Protocol: T=0 00000013 [140334139307776] winscard.c:456:SCardConnect() hCard Identity: 43a84c49 00000018 [140334139307776] winscard.c:518:SCardConnect() UnrefReader() count was: 2 00000011 [140334139307776] winscard_svc.c:513:ContextThread() CONNECT rv=0x0 for client 14 15047460 [140334139307776] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 14 00000015 [140334139307776] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 00000004 [140334139307776] winscard.c:1595:SCardTransmit() Send Protocol: T=0 00000007 [140334139307776] APDU: 00 A4 04 00 06 31 50 41 59 2E 53 00  00000004 [140334139307776] ifdhandler.c:1302:IFDHTransmitToICC() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000003 [140334139307776] commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 12 bytes 00000008 [140334139307776] -> 000000 6F 0C 00 00 00 00 0E 00 00 00 00 A4 04 00 06 31 50 41 59 2E 53 00 00058585 [140334139307776] <- 000000 80 02 00 00 00 00 0E 00 00 00 61 1E 00000025 [140334139307776] SW: 61 1E  00000003 [140334139307776] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 00000003 [140334139307776] winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 14 40105919 [140334139307776] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 14 00000020 [140334139307776] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 00000004 [140334139307776] winscard.c:1595:SCardTransmit() Send Protocol: T=0 00000004 [140334139307776] APDU: 00 C0 00 00 1E  00000005 [140334139307776] ifdhandler.c:1302:IFDHTransmitToICC() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000003 [140334139307776] commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 5 bytes 00000006 [140334139307776] -> 000000 6F 05 00 00 00 00 0F 00 00 00 00 C0 00 00 1E 00065449 [140334139307776] <- 000000 80 20 00 00 00 00 0F 00 00 00 6F 1C 84 0E 31 50 41 59 2E 53 59 53 2E 44 44 46 30 31 A5 0A 88 01 01 5F 2D 04 66 72 65 6E 90 00 00000019 [140334139307776] SW: 6F 1C 84 0E 31 50 41 59 2E 53 59 53 2E 44 44 46 30 31 A5 0A 88 01 01 5F 2D 04 66 72 65 6E 90 00  00000003 [140334139307776] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 00000003 [140334139307776] winscard_svc.c:685:ContextThread() TRANSMIT rv=0x0 for client 14 06775299 [140334139307776] winscard_svc.c:354:ContextThread() Client die: 14 00000015 [140334139307776] winscard.c:229:SCardReleaseContext() Releasing Context: 0x5D6FEA69 00000004 [140334139307776] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 00000002 [140334139307776] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 2 00000002 [140334139307776] winscard.c:881:SCardDisconnect() Active Contexts: 1 00000001 [140334139307776] winscard.c:882:SCardDisconnect() dwDisposition: 0 00000003 [140334139307776] winscard.c:1017:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD 00000003 [140334139307776] ifdhandler.c:380:IFDHGetCapabilities() tag: 0xFB2, usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000002 [140334139307776] winscard.c:1030:SCardDisconnect() Stopping polling thread 00000002 [140334139307776] ifdhandler.c:345:IFDHStopPolling() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00009459 [140334139307776] winscard.c:1043:SCardDisconnect() UnrefReader() count was: 3 00000016 [140334139307776] winscard_svc.c:931:MSGRemoveContext() UnrefReader() count was: 2 00000004 [140334139307776] winscard_svc.c:1055:MSGCleanupClient() Thread is stopping: dwClientID=14, threadContext @0x560ceede14c0 00000002 [140334139307776] winscard_svc.c:1063:MSGCleanupClient() Freeing SCONTEXT @0x560ceede14c0 00000256 [140334156093184] ccid_usb.c:1352:InterruptRead() after (0) (3) 00000004 [140334156093184] ccid_usb.c:1365:InterruptRead() InterruptRead (2/3): 3 00400578 [140334156093184] eventhandler.c:494:EHStatusHandlerThread() powerState: POWER_STATE_POWERED 00000038 [140334156093184] ifdhandler.c:1821:IFDHICCPresence() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000023 [140334156093184] -> 000000 65 00 00 00 00 00 10 00 00 00 00017844 [140334156093184] <- 000000 81 00 00 00 00 00 10 00 00 00 00000005 [140334156093184] ifdhandler.c:1941:IFDHICCPresence() Card present 00000003 [140334156093184] ifdhandler.c:309:IFDHPolling() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 5000 ms 00000002 [140334156093184] ccid_usb.c:1306:InterruptRead() before (0), timeout: 5000 ms 05019827 [140334156093184] ccid_usb.c:1352:InterruptRead() after (0) (2) 00000102 [140334156093184] ifdhandler.c:1821:IFDHICCPresence() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000025 [140334156093184] -> 000000 65 00 00 00 00 00 11 00 00 00 00024707 [140334156093184] <- 000000 81 00 00 00 00 00 11 00 00 00 00000010 [140334156093184] ifdhandler.c:1941:IFDHICCPresence() Card present 00000004 [140334156093184] ifdhandler.c:1153:IFDHPowerICC() action: PowerDown, usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000005 [140334156093184] -> 000000 63 00 00 00 00 00 12 00 00 00 00011648 [140334156093184] <- 000000 81 00 00 00 00 00 12 01 00 00 00000014 [140334156093184] eventhandler.c:482:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED 00000004 [140334156093184] ifdhandler.c:1821:IFDHICCPresence() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 00000006 [140334156093184] -> 000000 65 00 00 00 00 00 13 00 00 00 00010752 [140334156093184] <- 000000 81 00 00 00 00 00 13 01 00 00 00000013 [140334156093184] ifdhandler.c:1941:IFDHICCPresence() Card present 00000003 [140334156093184] ifdhandler.c:309:IFDHPolling() usb:08e6/3437:libudev:0:/dev/bus/usb/002/003 (lun: 0) 600000 ms 00000002 [140334156093184] ccid_usb.c:1306:InterruptRead() before (0), timeout: 600000 ms