log.txt

00000000 debuglog.c:299:DebugLogSetLevel() debug level=debug
00000025 debuglog.c:320:DebugLogSetCategory() Debug options: APDU
00000004 [140058651311104] pcscdaemon.c:353:main() Force colored logs
00000137 [140058651311104] configfile.l:293:DBGetReaderListDir() Parsing conf directory: /etc/reader.conf.d
00000020 [140058651311104] configfile.l:369:DBGetReaderList() Parsing conf file: /etc/reader.conf.d/libccidtwin
00000043 [140058651311104] configfile.l:329:DBGetReaderListDir() Skipping non regular file: .
00000004 [140058651311104] configfile.l:329:DBGetReaderListDir() Skipping non regular file: ..
00000008 [140058651311104] pcscdaemon.c:663:main() pcsc-lite 1.9.1 daemon ready.
00008379 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000137 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000137 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/001/006
00000130 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/001/006
00000128 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/001/006
00000121 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000132 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x8087, PID: 0x0AAA, path: /dev/bus/usb/001/004
00000131 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x8087, PID: 0x0AAA, path: /dev/bus/usb/001/004
00000120 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000133 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x072F, PID: 0x90CC, path: /dev/bus/usb/001/010
00000007 [140058651311104] hotplug_libudev.c:440:HPAddDevice() Adding USB device: ACS ACR 38U-CCID
00000034 [140058651311104] readerfactory.c:1079:RFInitializeReader() Attempting startup of ACS ACR 38U-CCID 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
00014826 [140058651311104] readerfactory.c:954:RFBindFunctions() Loading IFD Handler 3.0
00000021 [140058651311104] ifdhandler.c:2025:init_driver() Driver version: 1.4.34
00000553 [140058651311104] ifdhandler.c:2042:init_driver() LogLevel: 0x0003
00000005 [140058651311104] ifdhandler.c:2053:init_driver() DriverOptions: 0x0000
00000169 [140058651311104] ifdhandler.c:2066:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000004 [140058651311104] ifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:072f/90cc:libudev:0:/dev/bus/usb/001/010
00000004 [140058651311104] ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:072f/90cc:libudev:0:/dev/bus/usb/001/010
00000005 [140058651311104] ccid_usb.c:269:OpenUSBByName() interface_number: 0
00000002 [140058651311104] ccid_usb.c:270:OpenUSBByName() usb bus/device: 1/10
00000002 [140058651311104] ccid_usb.c:302:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
00000560 [140058651311104] ccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr)
00000005 [140058651311104] ccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver
00000002 [140058651311104] 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.
00005890 [140058651311104] ccid_usb.c:406:OpenUSBByName() Try device: 1/10
00000007 [140058651311104] ccid_usb.c:416:OpenUSBByName() vid/pid : 072F/90CC
00000002 [140058651311104] ccid_usb.c:482:OpenUSBByName() Checking device: 1/10
00000002 [140058651311104] ccid_usb.c:553:OpenUSBByName() Trying to open USB bus/device: 1/10
00111963 [140058651311104] ccid_usb.c:659:OpenUSBByName() Found Vendor/Product: 072F/90CC (ACS ACR 38U-CCID)
00000026 [140058651311104] ccid_usb.c:661:OpenUSBByName() Using USB bus/device: 1/10
00000008 [140058651311104] ccid_usb.c:722:OpenUSBByName() bNumDataRatesSupported is 0
00001349 [140058651311104] ccid_usb.c:1328:InterruptRead() before (0), timeout: 100 ms
00002355 [140058651311104] ccid_usb.c:1374:InterruptRead() after (0) (0)
00000025 [140058651311104] NotifySlotChange: 50 02 
00000012 [140058651311104] -> 000000 65 00 00 00 00 00 00 00 00 00 
00000572 [140058651311104] <- 000000 81 00 00 00 00 00 00 02 00 00 
00000031 [140058651311104] -> 000000 65 00 00 00 00 00 01 00 00 00 
00000567 [140058651311104] <- 000000 81 00 00 00 00 00 01 02 00 00 
00000030 [140058651311104] ifdhandler.c:388:IFDHGetCapabilities() tag: 0xFB3, usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000009 [140058651311104] readerfactory.c:400:RFAddReader() Using the reader polling thread
00000007 [140058651311104] ifdhandler.c:1885:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000010 [140058651311104] -> 000000 65 00 00 00 00 00 02 00 00 00 
00000668 [140058651311104] <- 000000 81 00 00 00 00 00 02 02 00 00 
00000032 [140058651311104] ifdhandler.c:2005:IFDHICCPresence() Card absent
00000214 [140058651311104] ifdhandler.c:388:IFDHGetCapabilities() tag: 0xFAE, usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000021 [140058651311104] ifdhandler.c:484:IFDHGetCapabilities() Reader supports 1 slot(s)
00000061 [140058634163776] ifdhandler.c:1885:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000027 [140058634163776] -> 000000 65 00 00 00 00 00 03 00 00 00 
00000701 [140058634163776] <- 000000 81 00 00 00 00 00 03 02 00 00 
00000030 [140058634163776] ifdhandler.c:2005:IFDHICCPresence() Card absent
00000010 [140058634163776] ifdhandler.c:1885:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000010 [140058634163776] -> 000000 65 00 00 00 00 00 04 00 00 00 
00000039 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000518 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x08BB, PID: 0x2902, path: /dev/bus/usb/001/002
00000009 [140058634163776] <- 000000 81 00 00 00 00 00 04 02 00 00 
00000047 [140058634163776] ifdhandler.c:2005:IFDHICCPresence() Card absent
00000009 [140058634163776] ifdhandler.c:317:IFDHPolling() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0) 600000 ms
00000010 [140058634163776] ccid_usb.c:1328:InterruptRead() before (0), timeout: 600000 ms
00000370 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x08BB, PID: 0x2902, path: /dev/bus/usb/001/002
00000450 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x08BB, PID: 0x2902, path: /dev/bus/usb/001/002
00000428 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x08BB, PID: 0x2902, path: /dev/bus/usb/001/002
00000695 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/002/001
00001188 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000510 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000503 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0451, PID: 0x8442, path: /dev/bus/usb/003/002
00000537 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x046D, PID: 0x081B, path: /dev/bus/usb/003/003
00000513 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x046D, PID: 0x081B, path: /dev/bus/usb/003/003
00000565 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x046D, PID: 0x081B, path: /dev/bus/usb/003/003
00000527 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x046D, PID: 0x081B, path: /dev/bus/usb/003/003
00000514 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0451, PID: 0x8442, path: /dev/bus/usb/003/002
00000539 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x045E, PID: 0x07A5, path: /dev/bus/usb/003/004
00000543 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x045E, PID: 0x07A5, path: /dev/bus/usb/003/004
00000569 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x045E, PID: 0x07A5, path: /dev/bus/usb/003/004
00000529 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0451, PID: 0x8442, path: /dev/bus/usb/003/002
00000579 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0451, PID: 0x82FF, path: /dev/bus/usb/003/005
00000506 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x0451, PID: 0x8442, path: /dev/bus/usb/003/002
00001138 [140058651311104] hotplug_libudev.c:299:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/004/001
04721309 [140058634163776] ccid_usb.c:1374:InterruptRead() after (0) (0)
00000032 [140058634163776] NotifySlotChange: 50 03 
00000011 [140058634163776] ifdhandler.c:1885:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000010 [140058634163776] -> 000000 65 00 00 00 00 00 05 00 00 00 
00000600 [140058634163776] <- 000000 81 00 00 00 00 00 05 01 00 00 
00000027 [140058634163776] ifdhandler.c:2005:IFDHICCPresence() Card present
00000009 [140058634163776] ifdhandler.c:1885:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000011 [140058634163776] -> 000000 65 00 00 00 00 00 06 00 00 00 
00000612 [140058634163776] <- 000000 81 00 00 00 00 00 06 01 00 00 
00000027 [140058634163776] ifdhandler.c:2005:IFDHICCPresence() Card present
00000010 [140058634163776] ifdhandler.c:1183:IFDHPowerICC() action: PowerUp, usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000011 [140058634163776] -> 000000 62 00 00 00 00 00 07 01 00 00 
00053016 [140058634163776] <- 000000 80 06 00 00 00 00 07 00 00 00 3B 04 A2 13 10 91 
00000019 [140058634163776] eventhandler.c:406:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
00000004 [140058634163776] eventhandler.c:423:EHStatusHandlerThread() Card inserted into ACS ACR 38U-CCID 00 00
00000005 [140058634163776] Card ATR: 3B 04 A2 13 10 91 
00000005 [140058634163776] ifdhandler.c:317:IFDHPolling() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0) 5000 ms
00000004 [140058634163776] ccid_usb.c:1328:InterruptRead() before (0), timeout: 5000 ms
05000220 [140058634163776] ccid_usb.c:1374:InterruptRead() after (0) (2)
00000042 [140058634163776] ifdhandler.c:1885:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000016 [140058634163776] -> 000000 65 00 00 00 00 00 08 00 00 00 
00000543 [140058634163776] <- 000000 81 00 00 00 00 00 08 00 00 00 
00000022 [140058634163776] ifdhandler.c:2005:IFDHICCPresence() Card present
00000010 [140058634163776] ifdhandler.c:1183:IFDHPowerICC() action: PowerDown, usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000010 [140058634163776] -> 000000 63 00 00 00 00 00 09 00 00 00 
00097751 [140058634163776] <- 000000 81 00 00 00 00 00 09 01 00 00 
00000009 [140058634163776] eventhandler.c:482:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
00000003 [140058634163776] ifdhandler.c:1885:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000003 [140058634163776] -> 000000 65 00 00 00 00 00 0A 00 00 00 
00000569 [140058634163776] <- 000000 81 00 00 00 00 00 0A 01 00 00 
00000011 [140058634163776] ifdhandler.c:2005:IFDHICCPresence() Card present
00000003 [140058634163776] ifdhandler.c:317:IFDHPolling() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0) 600000 ms
00000003 [140058634163776] ccid_usb.c:1328:InterruptRead() before (0), timeout: 600000 ms
09256894 [140058651311104] winscard_msg_srv.c:256:ProcessEventsServer() Common channel packet arrival
00000015 [140058651311104] winscard_msg_srv.c:267:ProcessEventsServer() ProcessCommonChannelRequest detects: 12
00000002 [140058651311104] pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 12
00000076 [140058547975744] winscard_svc.c:340:ContextThread() Authorized PC/SC client
00000005 [140058547975744] winscard_svc.c:343:ContextThread() Thread is started: dwClientID=12, threadContext @0x5650458fb6d0
00000006 [140058547975744] winscard_svc.c:361:ContextThread() Received command: CMD_VERSION from client 12
00000003 [140058547975744] winscard_svc.c:373:ContextThread() Client is protocol version 4:4
00000001 [140058547975744] winscard_svc.c:396:ContextThread() CMD_VERSION rv=0x0 for client 12
00000095 [140058547975744] winscard_svc.c:361:ContextThread() Received command: ESTABLISH_CONTEXT from client 12
00000007 [140058547975744] winscard.c:215:SCardEstablishContext() Establishing Context: 0x760C7BE1
00000001 [140058547975744] winscard_svc.c:461:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 12
00000118 [140058547975744] winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 12
00000091 [140058547975744] winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 12
00000105 [140058547975744] winscard_svc.c:361:ContextThread() Received command: CONNECT from client 12
00000006 [140058547975744] winscard_svc.c:499:ContextThread() Authorized client for 'ACS ACR 38U-CCID 00 00'
00000002 [140058547975744] winscard.c:258:SCardConnect() Attempting Connect to ACS ACR 38U-CCID 00 00 using protocol: 3
00000001 [140058547975744] readerfactory.c:825:RFReaderInfo() RefReader() count was: 1
00000003 [140058547975744] ifdhandler.c:1885:IFDHICCPresence() usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000002 [140058547975744] -> 000000 65 00 00 00 00 00 0B 00 00 00 
00000506 [140058547975744] <- 000000 81 00 00 00 00 00 0B 01 00 00 
00000005 [140058547975744] ifdhandler.c:2005:IFDHICCPresence() Card present
00000002 [140058547975744] ifdhandler.c:1183:IFDHPowerICC() action: PowerUp, usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000003 [140058547975744] -> 000000 62 00 00 00 00 00 0C 01 00 00 
00150619 [140058547975744] <- 000000 80 06 00 00 00 00 0C 00 00 00 3B 04 A2 13 10 91 
00000036 [140058547975744] winscard.c:332:SCardConnect() power up complete.
00000008 [140058547975744] Card ATR: 3B 04 A2 13 10 91 
00000006 [140058547975744] winscard.c:352:SCardConnect() powerState: POWER_STATE_IN_USE
00000007 [140058547975744] prothandler.c:107:PHSetProtocol() Attempting PTS to T=0
00000009 [140058547975744] ifdhandler.c:715:IFDHSetProtocolParameters() protocol T=0, usb:072f/90cc:libudev:0:/dev/bus/usb/001/010 (lun: 0)
00000007 [140058547975744] towitoko/atr.c:357:ATR_GetDefaultProtocol() no default protocol found in ATR. Using T=0
00000007 [140058547975744] ifdhandler.c:1088:IFDHSetProtocolParameters() Communication timeout: 232133 ms
00000006 [140058547975744] commands.c:2286:SetParameters() length: 5 bytes
00000012 [140058547975744] -> 000000 61 05 00 00 00 00 0D 00 00 00 11 00 00 0A 00 
00000662 [140058547975744] <- 000000 82 00 00 00 00 00 0D 40 00 00 
00000028 [140058547975744] commands.c:2312:SetParameters Command not supported or not allowed
00000008 [140058547975744] prothandler.c:129:PHSetProtocol() PTS protocol failed (614)
00000007 [140058547975744] winscard.c:518:SCardConnect() UnrefReader() count was: 2
00000010 [140058547975744] winscard_svc.c:519:ContextThread() CONNECT rv=0x80100066 for client 12
00000343 [140058547975744] winscard_svc.c:361:ContextThread() Received command: RELEASE_CONTEXT from client 12
00000034 [140058547975744] winscard.c:229:SCardReleaseContext() Releasing Context: 0x760C7BE1
00000010 [140058547975744] winscard_svc.c:476:ContextThread() RELEASE_CONTEXT rv=0x0 for client 12
00000225 [140058547975744] winscard_svc.c:354:ContextThread() Client die: 12
00000055 [140058547975744] winscard_svc.c:1061:MSGCleanupClient() Thread is stopping: dwClientID=12, threadContext @0x5650458fb6d0
00000008 [140058547975744] winscard_svc.c:1069:MSGCleanupClient() Freeing SCONTEXT @0x5650458fb6d0