[Pcsclite-muscle] C_GenerateKeyPair failure with ACS CryptoMate (T2), ...shouldwork.html#0x072F0xB106

Carsten Blüggel carsten.blueggel
Sat Apr 15 01:55:13 PDT 2017


Hello,

C_GenerateKeyPair on hardware ACS CryptoMate (T2),

idVendor: 0x072F
idProduct: 0xB106

always fails with bit size > 3328 (... 4096), it succeeds <= 3328.
As comparison, C_GenerateKeyPair on predecessor hardware 
https://pcsclite.alioth.debian.org/ccid/shouldwork.html#0x072F0x90DB 
takes about 3-4 minutes for 4096 bit key pair to complete (with same 
ccid-git, invoked through scriptor) and #0x072F0xB106 seems to be slower,
thus my assumption, it may be something time-related (or a bug in card 
operating system?).

Occasionally #0x072F0xB106 also shows some other strange behavior like 
starting the Linux-User-Logout-countdown (Kubuntu 16.04 xenial) after 
plugging out/removing the USB token.
And once, after a failed C_GenerateKeyPair, card contents were erased.

My trial to change 'TIME_BEFORE_SUICIDE' from 60 to 300 and manually 
installing 1.4.26 libpcsclite and pcscd ended in screwing my system, 
then back with 1.8.14, omitting --auto-exit from pcscd start params 
didn't change the failure issue.
I hope, the following information is complete and a solution is close? 
Thanks in advance, Regards

Carsten Bl?ggel


      Versions

  * CCID driver version: git, commit
    af00591a4a3c437045cc9923f6477e6f2e467bf4 (includes "Add ACS
    CryptoMate (T2)" dated 2017-03-22, initiated by my eMail to L. Rousseau)
  * pcsc-lite version:  1.8.14-1ubuntu1.16.04.1 , the latest
    distribution's version
  * smart card reader name: ACS CryptoMate (T2),
    https://pcsclite.alioth.debian.org/ccid/shouldwork.html#0x072F0xB106
  * the output of the command "|/usr/sbin/pcscd --version|"


      pcsc-lite version 1.8.14.
      Copyright (C) 1999-2002 by David Corcoran <corcoran at musclecard.com>.
      Copyright (C) 2001-2011 by Ludovic Rousseau
      <ludovic.rousseau at free.fr>.
      Copyright (C) 2003-2004 by Damien Sauveron <sauveron at labri.fr>.
      Report bugs to <muscle at lists.musclecard.com>.
      Enabled features: Linux x86_64-pc-linux-gnu serial usb libudev
      usbdropdir=/usr/lib/pcsc/drivers ipcdir=/var/run/pcscd
      configdir=/etc/reader.conf.d


      Platform

  * Operating system or GNU/Linux distribution name and version: Kubuntu
    16.04 (xenial), Linux tuxim 4.4.0-72-generic #93-Ubuntu SMP Fri Mar
    31 14:07:41 UTC 2017 x86_64 x86_64 x86_64 GNU/Linux
  * Smart card middleware name and version: scriptor from pcsc-tools (or
    same failure issue when using OpenSC + my driver
    https://github.com/carblue/acos5_64)
  * Reader manufacturer name and reader model name: same as before, also
    called Advanced Card Systems: CryptoMate Nano Cryptographic USB
    token (ACOS5T2)
  * Smart card name: ACS ACOS5-64 V3.00, set to operation mode
    Non-FIPS/64K, which allows up to 4096 bit RSA key pair in steps of 256


      Log

I'll supply 3 logs, all with same software and Log-setup (pcscd killed, 
restarted), except different key sizes and log3 is from different USB token:

log1_failed_0x072F0xB106_CryptoMate_T2 shows the failure reported;  
requests 3584-bit RSA key pair generation, CRT for sign+decrypt APDU: 00 
46 00 00 02 1C 06
log2_succeeded_0x072F0xB106_CryptoMate_T2  requests 3328-bit RSA key 
pair gen., CRT for sign+decrypt APDU: 00 46 00 00 02 1A 06

log3_succeeded_0x072F0x90DB_CryptoMate64, predecessor hardware as 
comparison, requests 4096-bit RSA key pair gen., CRT for sign+decrypt 
APDU: 00 46 00 00 02 20 06

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.alioth.debian.org/pipermail/pcsclite-muscle/attachments/20170415/f28ead07/attachment-0001.html>
-------------- next part --------------
00000000 debuglog.c:291:DebugLogSetLevel() debug level=debug
00000080 debuglog.c:312:DebugLogSetCategory() Debug options: APDU
00000008 pcscdaemon.c:268:main() Force colored logs
00000045 configfile.l:355:DBGetReaderList() Parsing conf file: /etc/reader.conf.d
00000010 pcscdaemon.c:569:main() pcsc-lite 1.8.14 daemon ready.
00007804 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000153 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000149 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC31C, path: /dev/bus/usb/003/005
00000143 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC31C, path: /dev/bus/usb/003/005
00000133 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000140 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D50, PID: 0x6086, path: /dev/bus/usb/003/006
00000138 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D50, PID: 0x6086, path: /dev/bus/usb/003/006
00000132 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000141 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x072F, PID: 0xB106, path: /dev/bus/usb/003/002
00000011 hotplug_libudev.c:435:HPAddDevice() Adding USB device: ACS CryptoMate (T2)
00000050 readerfactory.c:1012:RFInitializeReader() Attempting startup of ACS CryptoMate (T2) 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
00000201 readerfactory.c:897:RFBindFunctions() Loading IFD Handler 3.0
00000034 ifdhandler.c:1965:init_driver() Driver version: 1.4.26
00000985 ifdhandler.c:1982:init_driver() LogLevel: 0x0003
00000017 ifdhandler.c:1993:init_driver() DriverOptions: 0x0000
00000260 ifdhandler.c:2006:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000008 ifdhandler.c:111:CreateChannelByNameOrChannel() Lun: 0, device: usb:072f/b106:libudev:0:/dev/bus/usb/003/002
00000010 ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:072f/b106:libudev:0:/dev/bus/usb/003/002
00000013 ccid_usb.c:269:OpenUSBByName() interface_number: 0
00000004 ccid_usb.c:270:OpenUSBByName() usb bus/device: 3/2
00000004 ccid_usb.c:302:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
00000974 ccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau at free.fr)
00000008 ccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver
00000005 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.
00009440 ccid_usb.c:406:OpenUSBByName() Try device: 3/2
00000012 ccid_usb.c:416:OpenUSBByName() vid/pid : 072F/B106
00000004 ccid_usb.c:483:OpenUSBByName() Checking device: 3/2
00000003 ccid_usb.c:554:OpenUSBByName() Trying to open USB bus/device: 3/2
00101499 ccid_usb.c:656:OpenUSBByName() Found Vendor/Product: 072F/B106 (ACS CryptoMate (T2))
00000014 ccid_usb.c:658:OpenUSBByName() Using USB bus/device: 3/2
00000005 ccid_usb.c:717:OpenUSBByName() bNumDataRatesSupported is 0
00000871 ccid_usb.c:1296:InterruptRead() before (0)
00003849 ccid_usb.c:1342:InterruptRead() after (0) (0)
00000015 NotifySlotChange: 50 03 
00000007 -> 000000 65 00 00 00 00 00 00 00 00 00 
00000231 <- 000000 81 00 00 00 00 00 00 01 00 01 
00000010 -> 000000 65 00 00 00 00 00 01 00 00 00 
00000224 <- 000000 81 00 00 00 00 00 01 01 00 01 
00000015 ifdhandler.c:382:IFDHGetCapabilities() tag: 0xFB3, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000005 readerfactory.c:355:RFAddReader() Using the reader polling thread
00000005 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 65 00 00 00 00 00 02 00 00 00 
00000219 <- 000000 81 00 00 00 00 00 02 01 00 01 
00000008 ifdhandler.c:1946:IFDHICCPresence() Card present
00000063 ifdhandler.c:382:IFDHGetCapabilities() tag: 0xFAE, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000009 ifdhandler.c:477:IFDHGetCapabilities() Reader supports 1 slot(s)
00000027 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000013 -> 000000 65 00 00 00 00 00 03 00 00 00 
00000268 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000049 <- 000000 81 00 00 00 00 00 03 01 00 01 
00000009 ifdhandler.c:1946:IFDHICCPresence() Card present
00000006 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 65 00 00 00 00 00 04 00 00 00 
00000116 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x0930, PID: 0x6545, path: /dev/bus/usb/003/003
00000129 <- 000000 81 00 00 00 00 00 04 01 00 01 
00000007 ifdhandler.c:1946:IFDHICCPresence() Card present
00000004 ifdhandler.c:1158:IFDHPowerICC() action: PowerUp, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000007 -> 000000 62 00 00 00 00 00 05 01 00 00 
00000010 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000155 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x062A, PID: 0x7223, path: /dev/bus/usb/003/004
00000142 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x062A, PID: 0x7223, path: /dev/bus/usb/003/004
00000202 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/004/001
00000193 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000131 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000141 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x8087, PID: 0x800A, path: /dev/bus/usb/001/002
00000223 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/005/001
00000140 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/005/001
00000153 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000194 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/005/003
00000159 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/005/003
00000155 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/005/003
00000148 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000200 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x04F9, PID: 0x0027, path: /dev/bus/usb/005/004
00000153 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000163 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x04A9, PID: 0x190A, path: /dev/bus/usb/005/005
00000151 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000225 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/006/001
00000288 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/007/001
00000303 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/008/001
00000198 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001
00000134 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001
00000146 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x8087, PID: 0x8002, path: /dev/bus/usb/002/002
00000064 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010081 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010081 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010074 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010073 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010076 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010084 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010100 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010101 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010104 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010093 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010122 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010086 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010079 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010112 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010087 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010102 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010088 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010106 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010106 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010088 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010088 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010091 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010100 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010115 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010095 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010104 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010096 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010071 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010104 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010106 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010121 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010101 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010113 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010097 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010100 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010116 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010127 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010102 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010117 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010112 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00009333 <- 000000 80 13 00 00 00 00 05 00 00 00 3B BE 96 00 00 41 05 30 00 00 00 00 00 00 00 00 00 90 00 
00000022 eventhandler.c:288:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
00000008 Card ATR: 3B BE 96 00 00 41 05 30 00 00 00 00 00 00 00 00 00 90 00 
00000007 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 65 00 00 00 00 00 06 00 00 00 
00000259 <- 000000 81 00 00 00 00 00 06 00 00 00 
00000007 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:311:IFDHPolling() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0) 5000 ms
00000004 ccid_usb.c:1296:InterruptRead() before (0)
05000097 ccid_usb.c:1342:InterruptRead() after (0) (2)
00000016 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000009 -> 000000 65 00 00 00 00 00 07 00 00 00 
00000256 <- 000000 81 00 00 00 00 00 07 00 00 00 
00000009 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:1158:IFDHPowerICC() action: PowerDown, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 63 00 00 00 00 00 08 00 00 00 
00000826 <- 000000 81 00 00 00 00 00 08 01 00 01 
00000014 eventhandler.c:481:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
00000005 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 65 00 00 00 00 00 09 00 00 00 
00000251 <- 000000 81 00 00 00 00 00 09 01 00 01 
00000008 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:311:IFDHPolling() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0) 600000 ms
00000004 ccid_usb.c:1296:InterruptRead() before (0)
00188706 winscard_msg_srv.c:253:ProcessEventsServer() Common channel packet arrival
00000033 winscard_msg_srv.c:265:ProcessEventsServer() ProcessCommonChannelRequest detects: 12
00000005 pcscdaemon.c:134:SVCServiceRunLoop() A new context thread creation is requested: 12
00000091 winscard_svc.c:331:ContextThread() Authorized PC/SC client
00000015 winscard_svc.c:335:ContextThread() Thread is started: dwClientID=12, threadContext @0x2402aa0
00000016 winscard_svc.c:353:ContextThread() Received command: CMD_VERSION from client 12
00000006 winscard_svc.c:365:ContextThread() Client is protocol version 4:3
00000004 winscard_svc.c:385:ContextThread() CMD_VERSION rv=0x0 for client 12
00000066 winscard_svc.c:353:ContextThread() Received command: ESTABLISH_CONTEXT from client 12
00000022 winscard.c:215:SCardEstablishContext() Establishing Context: 0x6A4EFF61
00000004 winscard_svc.c:446:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 12
00000093 winscard_svc.c:353:ContextThread() Received command: CMD_GET_READERS_STATE from client 12
00000038 winscard_svc.c:353:ContextThread() Received command: CMD_GET_READERS_STATE from client 12
00000093 winscard_svc.c:353:ContextThread() Received command: CONNECT from client 12
00000008 winscard_svc.c:484:ContextThread() Authorized client for 'ACS CryptoMate (T2) 00 00'
00000004 winscard.c:257:SCardConnect() Attempting Connect to ACS CryptoMate (T2) 00 00 using protocol: 3
00000005 readerfactory.c:768:RFReaderInfo() RefReader() count was: 1
00000006 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000008 -> 000000 65 00 00 00 00 00 0A 00 00 00 
00000275 <- 000000 81 00 00 00 00 00 0A 01 00 01 
00000009 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:1158:IFDHPowerICC() action: PowerUp, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000007 -> 000000 62 00 00 00 00 00 0B 01 00 00 
00690540 <- 000000 80 13 00 00 00 00 0B 00 00 00 3B BE 96 00 00 41 05 30 00 00 00 00 00 00 00 00 00 90 00 
00000023 winscard.c:332:SCardConnect() power up complete.
00000008 Card ATR: 3B BE 96 00 00 41 05 30 00 00 00 00 00 00 00 00 00 90 00 
00000004 winscard.c:352:SCardConnect() powerState: POWER_STATE_INUSE
00000005 prothandler.c:110:PHSetProtocol() Attempting PTS to T=0
00000006 ifdhandler.c:696:IFDHSetProtocolParameters() protocol T=0, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 ifdhandler.c:822:IFDHSetProtocolParameters() Set speed to 300000 bauds
00000004 ifdhandler.c:1062:IFDHSetProtocolParameters() Communication timeout: 266240 ms
00000003 commands.c:2254:SetParameters() length: 5 bytes
00000007 -> 000000 61 05 00 00 00 00 0C 00 00 00 96 00 00 0A 00 
00017302 <- 000000 82 05 00 00 00 00 0C 00 00 00 96 00 00 0A 00 
00000018 winscard.c:431:SCardConnect() Active Protocol: T=0
00000006 winscard.c:451:SCardConnect() hCard Identity: 1098fa53
00000005 winscard.c:512:SCardConnect() UnrefReader() count was: 2
00000006 winscard_svc.c:498:ContextThread() CONNECT rv=0x0 for client 12
00000264 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000015 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000004 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000005 APDU: 00 A4 00 00 02 41 00 
00000005 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000009 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 7 bytes
00000007 -> 000000 6F 07 00 00 00 00 0D 00 00 00 00 A4 00 00 02 41 00 
00016095 <- 000000 80 02 00 00 00 00 0D 00 00 00 61 32 
00000029 SW: 61 32 
00000005 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000005 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000252 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000015 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000005 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000004 APDU: 00 C0 00 00 32 
00000007 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000004 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 5 bytes
00000006 -> 000000 6F 05 00 00 00 00 0E 00 00 00 00 C0 00 00 32 
00006484 <- 000000 80 34 00 00 00 00 0E 00 00 00 6F 30 83 02 41 00 88 01 00 8A 01 05 82 02 38 00 8D 02 41 03 84 10 41 43 4F 53 50 4B 43 53 2D 31 35 76 31 2E 30 30 8C 08 7F FF FF 01 01 01 01 01 AB 00 90 00 
00000024 SW: 6F 30 83 02 41 00 88 01 00 8A 01 05 82 02 38 00 8D 02 41 03 84 10 41 43 4F 53 50 4B 43 53 2D 31 35 76 31 2E 30 30 8C 08 7F FF FF 01 01 01 01 01 AB 00 90 00 
00000004 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000006 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000232 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000013 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000004 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000006 APDU: 00 20 00 81 08 31 32 33 34 35 36 37 38 
00000004 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000005 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 13 bytes
00000007 -> 000000 6F 0D 00 00 00 00 0F 00 00 00 00 20 00 81 08 31 32 33 34 35 36 37 38 
00093267 <- 000000 80 02 00 00 00 00 0F 00 00 00 90 00 
00000019 SW: 90 00 
00000008 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000005 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000274 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000016 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000005 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000007 APDU: 00 22 01 B6 0A 80 01 10 81 02 41 33 95 01 80 
00000005 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000005 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 15 bytes
00000008 -> 000000 6F 0F 00 00 00 00 10 00 00 00 00 22 01 B6 0A 80 01 10 81 02 41 33 95 01 80 
00030363 <- 000000 80 02 00 00 00 00 10 00 00 00 90 00 
00000018 SW: 90 00 
00000005 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000005 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000241 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000017 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000004 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000008 APDU: 00 22 01 B6 0A 80 01 10 81 02 41 F3 95 01 40 
00000008 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000004 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 15 bytes
00000009 -> 000000 6F 0F 00 00 00 00 11 00 00 00 00 22 01 B6 0A 80 01 10 81 02 41 F3 95 01 40 
00031132 <- 000000 80 02 00 00 00 00 11 00 00 00 90 00 
00000017 SW: 90 00 
00000005 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000006 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000279 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000017 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000005 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000005 APDU: 00 46 00 00 02 1C 06 
00000006 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000005 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 7 bytes
00000007 -> 000000 6F 07 00 00 00 00 12 00 00 00 00 46 00 00 02 1C 06 
00919651 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000028 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928614 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928526 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000016 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928583 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000016 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928497 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000015 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928546 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928562 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928477 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000015 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928532 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000016 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928496 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000011 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928464 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000015 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928379 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000013 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928491 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000015 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928476 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000012 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928388 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928375 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928386 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928387 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928370 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928320 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928326 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928360 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928326 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928350 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928365 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928349 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928386 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928241 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928307 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928398 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928278 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928320 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000024 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928373 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928360 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928318 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928340 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928345 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928423 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928344 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928364 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928365 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928451 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928417 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928397 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928473 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928315 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928335 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928373 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928367 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928460 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928274 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000016 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928471 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928285 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000012 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928420 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000017 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928415 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928415 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928474 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928364 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928403 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928401 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000022 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928324 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000023 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928346 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928434 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928425 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928446 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000021 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928397 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000021 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928466 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000023 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928337 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000021 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928428 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928388 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928391 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000017 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928340 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928357 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928382 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928371 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928436 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928506 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928549 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928448 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928499 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928447 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928363 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000024 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928439 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000017 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928461 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928462 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000018 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928470 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000021 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928440 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928541 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000021 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928363 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928480 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928504 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928445 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928457 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928462 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928459 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928323 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928494 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000016 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928462 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928432 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928460 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928517 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000025 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928480 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928403 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928288 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000016 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928436 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928439 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000026 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928428 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928537 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928405 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928490 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928469 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928534 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928538 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928536 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928576 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928520 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928556 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928514 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928571 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928482 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928496 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928516 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000016 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928576 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928526 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928550 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928551 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928543 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928562 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928547 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928566 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928491 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928526 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928548 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928485 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928493 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928475 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928544 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928492 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000027 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928512 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928556 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000021 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928507 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928548 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928530 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928523 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000022 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928467 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928495 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000022 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928499 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000023 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928537 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000022 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928399 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000023 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928451 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000023 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928420 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000023 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928491 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000022 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928491 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928585 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000022 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928531 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000023 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928580 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928550 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928440 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928565 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
01330160 <- 000000 80 00 00 00 00 00 12 40 FE 00 
00000023 commands.c:1523:CCID_Receive Card absent or mute
00000007 SW: 
00000006 ifdwrapper.c:550:IFDTransmit() Card not transacted: 612
00000005 winscard.c:1633:SCardTransmit() Card not transacted: 0x80100016
00000005 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000008 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x80100016 for client 12
00000185 winscard_svc.c:353:ContextThread() Received command: DISCONNECT from client 12
00000013 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000004 winscard.c:872:SCardDisconnect() Active Contexts: 1
00000003 winscard.c:873:SCardDisconnect() dwDisposition: 0
00000005 winscard.c:1038:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD
00000006 ifdhandler.c:382:IFDHGetCapabilities() tag: 0xFB2, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000003 winscard.c:1052:SCardDisconnect() Stopping polling thread
00000004 ifdhandler.c:347:IFDHStopPolling() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000055 winscard.c:1065:SCardDisconnect() UnrefReader() count was: 2
00000012 winscard_svc.c:535:ContextThread() DISCONNECT rv=0x0 for client 12
00000001 ccid_usb.c:1342:InterruptRead() after (0) (3)
00000016 ccid_usb.c:1357:InterruptRead() InterruptRead (3/2): LIBUSB_TRANSFER_CANCELLED
00000074 winscard_svc.c:353:ContextThread() Received command: RELEASE_CONTEXT from client 12
00000010 winscard.c:226:SCardReleaseContext() Releasing Context: 0x6A4EFF61
00000004 winscard_svc.c:461:ContextThread() RELEASE_CONTEXT rv=0x0 for client 12
00000037 winscard_svc.c:345:ContextThread() Client die: 12
00000021 winscard_svc.c:1000:MSGCleanupClient() Thread is stopping: dwClientID=12, threadContext @0x2402aa0
00000005 winscard_svc.c:1006:MSGCleanupClient() Freeing SCONTEXT @0x2402aa0
00399940 eventhandler.c:493:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
00000017 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000009 -> 000000 65 00 00 00 00 00 13 00 00 00 
00000273 <- 000000 81 00 00 00 00 00 13 00 00 00 
00000013 ifdhandler.c:1946:IFDHICCPresence() Card present
00000006 ifdhandler.c:311:IFDHPolling() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0) 5000 ms
00000005 ccid_usb.c:1296:InterruptRead() before (0)
05000091 ccid_usb.c:1342:InterruptRead() after (0) (2)
00000017 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000009 -> 000000 65 00 00 00 00 00 14 00 00 00 
00000239 <- 000000 81 00 00 00 00 00 14 00 00 00 
00000011 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:1158:IFDHPowerICC() action: PowerDown, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 63 00 00 00 00 00 15 00 00 00 
00000845 <- 000000 81 00 00 00 00 00 15 01 00 01 
00000013 eventhandler.c:481:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
00000005 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 65 00 00 00 00 00 16 00 00 00 
00000239 <- 000000 81 00 00 00 00 00 16 01 00 01 
00000008 ifdhandler.c:1946:IFDHICCPresence() Card present
00000006 ifdhandler.c:311:IFDHPolling() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0) 600000 ms
00000004 ccid_usb.c:1296:InterruptRead() before (0)
-------------- next part --------------
00000000 debuglog.c:291:DebugLogSetLevel() debug level=debug
00000094 debuglog.c:312:DebugLogSetCategory() Debug options: APDU
00000010 pcscdaemon.c:268:main() Force colored logs
00000071 configfile.l:355:DBGetReaderList() Parsing conf file: /etc/reader.conf.d
00000017 pcscdaemon.c:569:main() pcsc-lite 1.8.14 daemon ready.
00008043 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000177 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000173 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC31C, path: /dev/bus/usb/003/005
00000168 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC31C, path: /dev/bus/usb/003/005
00000155 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000162 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D50, PID: 0x6086, path: /dev/bus/usb/003/006
00000164 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D50, PID: 0x6086, path: /dev/bus/usb/003/006
00000153 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000164 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x072F, PID: 0xB106, path: /dev/bus/usb/003/002
00000013 hotplug_libudev.c:435:HPAddDevice() Adding USB device: ACS CryptoMate (T2)
00000066 readerfactory.c:1012:RFInitializeReader() Attempting startup of ACS CryptoMate (T2) 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
00000209 readerfactory.c:897:RFBindFunctions() Loading IFD Handler 3.0
00000047 ifdhandler.c:1965:init_driver() Driver version: 1.4.26
00000983 ifdhandler.c:1982:init_driver() LogLevel: 0x0003
00000012 ifdhandler.c:1993:init_driver() DriverOptions: 0x0000
00000306 ifdhandler.c:2006:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000010 ifdhandler.c:111:CreateChannelByNameOrChannel() Lun: 0, device: usb:072f/b106:libudev:0:/dev/bus/usb/003/002
00000012 ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:072f/b106:libudev:0:/dev/bus/usb/003/002
00000023 ccid_usb.c:269:OpenUSBByName() interface_number: 0
00000007 ccid_usb.c:270:OpenUSBByName() usb bus/device: 3/2
00000006 ccid_usb.c:302:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
00000929 ccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau at free.fr)
00000009 ccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver
00000007 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.
00009244 ccid_usb.c:406:OpenUSBByName() Try device: 3/2
00000012 ccid_usb.c:416:OpenUSBByName() vid/pid : 072F/B106
00000006 ccid_usb.c:483:OpenUSBByName() Checking device: 3/2
00000005 ccid_usb.c:554:OpenUSBByName() Trying to open USB bus/device: 3/2
00101943 ccid_usb.c:656:OpenUSBByName() Found Vendor/Product: 072F/B106 (ACS CryptoMate (T2))
00000012 ccid_usb.c:658:OpenUSBByName() Using USB bus/device: 3/2
00000005 ccid_usb.c:717:OpenUSBByName() bNumDataRatesSupported is 0
00000788 ccid_usb.c:1296:InterruptRead() before (0)
00010986 ccid_usb.c:1342:InterruptRead() after (0) (0)
00000013 NotifySlotChange: 50 03 
00000007 -> 000000 65 00 00 00 00 00 00 00 00 00 
00000259 <- 000000 81 00 00 00 00 00 00 01 00 01 
00000011 -> 000000 65 00 00 00 00 00 01 00 00 00 
00000217 <- 000000 81 00 00 00 00 00 01 01 00 01 
00000011 ifdhandler.c:382:IFDHGetCapabilities() tag: 0xFB3, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000003 readerfactory.c:355:RFAddReader() Using the reader polling thread
00000004 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000004 -> 000000 65 00 00 00 00 00 02 00 00 00 
00000209 <- 000000 81 00 00 00 00 00 02 01 00 01 
00000005 ifdhandler.c:1946:IFDHICCPresence() Card present
00000050 ifdhandler.c:382:IFDHGetCapabilities() tag: 0xFAE, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 ifdhandler.c:477:IFDHGetCapabilities() Reader supports 1 slot(s)
00000025 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000018 -> 000000 65 00 00 00 00 00 03 00 00 00 
00000197 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000107 <- 000000 81 00 00 00 00 00 03 01 00 01 
00000009 ifdhandler.c:1946:IFDHICCPresence() Card present
00000004 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x0930, PID: 0x6545, path: /dev/bus/usb/003/003
00000003 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000009 -> 000000 65 00 00 00 00 00 04 00 00 00 
00000086 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000104 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x062A, PID: 0x7223, path: /dev/bus/usb/003/004
00000056 <- 000000 81 00 00 00 00 00 04 01 00 01 
00000008 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:1158:IFDHPowerICC() action: PowerUp, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000009 -> 000000 62 00 00 00 00 00 05 01 00 00 
00000024 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x062A, PID: 0x7223, path: /dev/bus/usb/003/004
00000147 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/004/001
00000153 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000094 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000099 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x8087, PID: 0x800A, path: /dev/bus/usb/001/002
00000157 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/005/001
00000098 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/005/001
00000106 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000111 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/005/003
00000109 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/005/003
00000108 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/005/003
00000104 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000109 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x04F9, PID: 0x0027, path: /dev/bus/usb/005/004
00000102 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000110 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x04A9, PID: 0x190A, path: /dev/bus/usb/005/005
00000102 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000152 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/006/001
00000154 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/007/001
00000149 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/008/001
00000131 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001
00000092 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001
00000128 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x8087, PID: 0x8002, path: /dev/bus/usb/002/002
00000047 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010080 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010079 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010098 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010093 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010081 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010077 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010089 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010094 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010075 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010096 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010080 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010099 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010098 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010097 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010078 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010094 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010098 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010097 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010094 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010089 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010121 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010095 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010104 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010095 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010104 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010095 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010103 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010091 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010127 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010087 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010102 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010092 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010119 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010113 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010094 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010115 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010109 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00010111 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate (T2) 00 00
00000928 <- 000000 80 13 00 00 00 00 05 00 00 00 3B BE 96 00 00 41 05 30 00 00 00 00 00 00 00 00 00 90 00 
00000042 eventhandler.c:288:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
00000008 Card ATR: 3B BE 96 00 00 41 05 30 00 00 00 00 00 00 00 00 00 90 00 
00000008 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 65 00 00 00 00 00 06 00 00 00 
00000262 <- 000000 81 00 00 00 00 00 06 00 00 00 
00000007 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:311:IFDHPolling() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0) 5000 ms
00000004 ccid_usb.c:1296:InterruptRead() before (0)
05000105 ccid_usb.c:1342:InterruptRead() after (0) (2)
00000017 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000008 -> 000000 65 00 00 00 00 00 07 00 00 00 
00000255 <- 000000 81 00 00 00 00 00 07 00 00 00 
00000008 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:1158:IFDHPowerICC() action: PowerDown, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 63 00 00 00 00 00 08 00 00 00 
00000853 <- 000000 81 00 00 00 00 00 08 01 00 01 
00000013 eventhandler.c:481:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
00000006 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 65 00 00 00 00 00 09 00 00 00 
00000222 <- 000000 81 00 00 00 00 00 09 01 00 01 
00000008 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:311:IFDHPolling() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0) 600000 ms
00000004 ccid_usb.c:1296:InterruptRead() before (0)
02845697 winscard_msg_srv.c:253:ProcessEventsServer() Common channel packet arrival
00000034 winscard_msg_srv.c:265:ProcessEventsServer() ProcessCommonChannelRequest detects: 12
00000006 pcscdaemon.c:134:SVCServiceRunLoop() A new context thread creation is requested: 12
00000084 winscard_svc.c:331:ContextThread() Authorized PC/SC client
00000010 winscard_svc.c:335:ContextThread() Thread is started: dwClientID=12, threadContext @0x1b31aa0
00000014 winscard_svc.c:353:ContextThread() Received command: CMD_VERSION from client 12
00000006 winscard_svc.c:365:ContextThread() Client is protocol version 4:3
00000003 winscard_svc.c:385:ContextThread() CMD_VERSION rv=0x0 for client 12
00000057 winscard_svc.c:353:ContextThread() Received command: ESTABLISH_CONTEXT from client 12
00000027 winscard.c:215:SCardEstablishContext() Establishing Context: 0x6913802D
00000007 winscard_svc.c:446:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 12
00000088 winscard_svc.c:353:ContextThread() Received command: CMD_GET_READERS_STATE from client 12
00000038 winscard_svc.c:353:ContextThread() Received command: CMD_GET_READERS_STATE from client 12
00000125 winscard_svc.c:353:ContextThread() Received command: CONNECT from client 12
00000014 winscard_svc.c:484:ContextThread() Authorized client for 'ACS CryptoMate (T2) 00 00'
00000005 winscard.c:257:SCardConnect() Attempting Connect to ACS CryptoMate (T2) 00 00 using protocol: 3
00000004 readerfactory.c:768:RFReaderInfo() RefReader() count was: 1
00000008 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000008 -> 000000 65 00 00 00 00 00 0A 00 00 00 
00000306 <- 000000 81 00 00 00 00 00 0A 01 00 01 
00000009 ifdhandler.c:1946:IFDHICCPresence() Card present
00000004 ifdhandler.c:1158:IFDHPowerICC() action: PowerUp, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000007 -> 000000 62 00 00 00 00 00 0B 01 00 00 
00690520 <- 000000 80 13 00 00 00 00 0B 00 00 00 3B BE 96 00 00 41 05 30 00 00 00 00 00 00 00 00 00 90 00 
00000021 winscard.c:332:SCardConnect() power up complete.
00000008 Card ATR: 3B BE 96 00 00 41 05 30 00 00 00 00 00 00 00 00 00 90 00 
00000004 winscard.c:352:SCardConnect() powerState: POWER_STATE_INUSE
00000005 prothandler.c:110:PHSetProtocol() Attempting PTS to T=0
00000006 ifdhandler.c:696:IFDHSetProtocolParameters() protocol T=0, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000005 ifdhandler.c:822:IFDHSetProtocolParameters() Set speed to 300000 bauds
00000004 ifdhandler.c:1062:IFDHSetProtocolParameters() Communication timeout: 266240 ms
00000004 commands.c:2254:SetParameters() length: 5 bytes
00000007 -> 000000 61 05 00 00 00 00 0C 00 00 00 96 00 00 0A 00 
00017259 <- 000000 82 05 00 00 00 00 0C 00 00 00 96 00 00 0A 00 
00000018 winscard.c:431:SCardConnect() Active Protocol: T=0
00000007 winscard.c:451:SCardConnect() hCard Identity: 6245f115
00000006 winscard.c:512:SCardConnect() UnrefReader() count was: 2
00000007 winscard_svc.c:498:ContextThread() CONNECT rv=0x0 for client 12
00000324 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000023 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000006 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000006 APDU: 00 A4 00 00 02 41 00 
00000005 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000009 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 7 bytes
00000008 -> 000000 6F 07 00 00 00 00 0D 00 00 00 00 A4 00 00 02 41 00 
00012520 <- 000000 80 02 00 00 00 00 0D 00 00 00 61 32 
00000026 SW: 61 32 
00000006 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000006 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000360 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000013 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000004 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000004 APDU: 00 C0 00 00 32 
00000005 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000005 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 5 bytes
00000006 -> 000000 6F 05 00 00 00 00 0E 00 00 00 00 C0 00 00 32 
00003679 <- 000000 80 34 00 00 00 00 0E 00 00 00 6F 30 83 02 41 00 88 01 00 8A 01 05 82 02 38 00 8D 02 41 03 84 10 41 43 4F 53 50 4B 43 53 2D 31 35 76 31 2E 30 30 8C 08 7F FF FF 01 01 01 01 01 AB 00 90 00 
00000016 SW: 6F 30 83 02 41 00 88 01 00 8A 01 05 82 02 38 00 8D 02 41 03 84 10 41 43 4F 53 50 4B 43 53 2D 31 35 76 31 2E 30 30 8C 08 7F FF FF 01 01 01 01 01 AB 00 90 00 
00000004 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000003 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000306 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000009 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000002 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000002 APDU: 00 20 00 81 08 31 32 33 34 35 36 37 38 
00000003 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000002 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 13 bytes
00000004 -> 000000 6F 0D 00 00 00 00 0F 00 00 00 00 20 00 81 08 31 32 33 34 35 36 37 38 
00105509 <- 000000 80 02 00 00 00 00 0F 00 00 00 90 00 
00000019 SW: 90 00 
00000007 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000006 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000284 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000011 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000004 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000005 APDU: 00 22 01 B6 0A 80 01 10 81 02 41 33 95 01 80 
00000004 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000005 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 15 bytes
00000007 -> 000000 6F 0F 00 00 00 00 10 00 00 00 00 22 01 B6 0A 80 01 10 81 02 41 33 95 01 80 
00033168 <- 000000 80 02 00 00 00 00 10 00 00 00 90 00 
00000012 SW: 90 00 
00000003 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000004 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000285 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000010 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000002 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000004 APDU: 00 22 01 B6 0A 80 01 10 81 02 41 F3 95 01 40 
00000004 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000002 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 15 bytes
00000005 -> 000000 6F 0F 00 00 00 00 11 00 00 00 00 22 01 B6 0A 80 01 10 81 02 41 F3 95 01 40 
00034216 <- 000000 80 02 00 00 00 00 11 00 00 00 90 00 
00000021 SW: 90 00 
00000006 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000006 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000323 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000009 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000002 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000003 APDU: 00 46 00 00 02 1A 06 
00000003 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000003 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 7 bytes
00000004 -> 000000 6F 07 00 00 00 00 12 00 00 00 00 46 00 00 02 1A 06 
00916806 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000032 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928370 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928370 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928353 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000026 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928299 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928361 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928334 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928326 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000017 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928396 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000014 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928390 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928331 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928292 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928356 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928378 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928358 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928330 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928335 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928276 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928324 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928308 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928325 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928427 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928327 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000020 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928433 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928397 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928400 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928365 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928298 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928334 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928357 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928334 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928408 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928297 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928259 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000016 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928318 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928399 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928315 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928356 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928337 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928313 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928330 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928515 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928440 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000009 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928407 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928343 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928358 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928411 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928312 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928355 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928381 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928390 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928328 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000007 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928334 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928377 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000030 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928407 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000018 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928450 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928453 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000031 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928438 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928351 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928443 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928318 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928351 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000017 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928324 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928488 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000004 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928463 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000006 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00928415 <- 000000 80 00 00 00 00 00 12 80 01 00 
00000019 commands.c:1552:CCID_Receive() Time extension requested: 0x01
00000005 commands.c:1558:CCID_Receive() New timeout: 266240 ms
00308711 <- 000000 80 02 00 00 00 00 12 00 00 00 90 00 
00000020 SW: 90 00 
00000006 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000006 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000276 winscard_svc.c:353:ContextThread() Received command: DISCONNECT from client 12
00000013 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000004 winscard.c:872:SCardDisconnect() Active Contexts: 1
00000005 winscard.c:873:SCardDisconnect() dwDisposition: 0
00000004 winscard.c:1038:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD
00000006 ifdhandler.c:382:IFDHGetCapabilities() tag: 0xFB2, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000004 winscard.c:1052:SCardDisconnect() Stopping polling thread
00000007 ifdhandler.c:347:IFDHStopPolling() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000061 winscard.c:1065:SCardDisconnect() UnrefReader() count was: 2
00000008 winscard_svc.c:535:ContextThread() DISCONNECT rv=0x0 for client 12
00000014 ccid_usb.c:1342:InterruptRead() after (0) (3)
00000022 ccid_usb.c:1357:InterruptRead() InterruptRead (3/2): LIBUSB_TRANSFER_CANCELLED
00000065 winscard_svc.c:353:ContextThread() Received command: RELEASE_CONTEXT from client 12
00000015 winscard.c:226:SCardReleaseContext() Releasing Context: 0x6913802D
00000006 winscard_svc.c:461:ContextThread() RELEASE_CONTEXT rv=0x0 for client 12
00000042 winscard_svc.c:345:ContextThread() Client die: 12
00000025 winscard_svc.c:1000:MSGCleanupClient() Thread is stopping: dwClientID=12, threadContext @0x1b31aa0
00000010 winscard_svc.c:1006:MSGCleanupClient() Freeing SCONTEXT @0x1b31aa0
00399919 eventhandler.c:493:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
00000018 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000008 -> 000000 65 00 00 00 00 00 13 00 00 00 
00000285 <- 000000 81 00 00 00 00 00 13 00 00 00 
00000011 ifdhandler.c:1946:IFDHICCPresence() Card present
00000006 ifdhandler.c:311:IFDHPolling() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0) 5000 ms
00000006 ccid_usb.c:1296:InterruptRead() before (0)
05000111 ccid_usb.c:1342:InterruptRead() after (0) (2)
00000016 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000009 -> 000000 65 00 00 00 00 00 14 00 00 00 
00000260 <- 000000 81 00 00 00 00 00 14 00 00 00 
00000007 ifdhandler.c:1946:IFDHICCPresence() Card present
00000004 ifdhandler.c:1158:IFDHPowerICC() action: PowerDown, usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000020 -> 000000 63 00 00 00 00 00 15 00 00 00 
00000837 <- 000000 81 00 00 00 00 00 15 01 00 01 
00000013 eventhandler.c:481:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
00000006 ifdhandler.c:1825:IFDHICCPresence() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0)
00000006 -> 000000 65 00 00 00 00 00 16 00 00 00 
00000251 <- 000000 81 00 00 00 00 00 16 01 00 01 
00000007 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:311:IFDHPolling() usb:072f/b106:libudev:0:/dev/bus/usb/003/002 (lun: 0) 600000 ms
00000005 ccid_usb.c:1296:InterruptRead() before (0)
-------------- next part --------------
00000000 debuglog.c:291:DebugLogSetLevel() debug level=debug
00000095 debuglog.c:312:DebugLogSetCategory() Debug options: APDU
00000008 pcscdaemon.c:268:main() Force colored logs
00000055 configfile.l:355:DBGetReaderList() Parsing conf file: /etc/reader.conf.d
00000010 pcscdaemon.c:569:main() pcsc-lite 1.8.14 daemon ready.
00007559 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000137 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000137 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC31C, path: /dev/bus/usb/003/005
00000128 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC31C, path: /dev/bus/usb/003/005
00000120 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000127 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D50, PID: 0x6086, path: /dev/bus/usb/003/006
00000128 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D50, PID: 0x6086, path: /dev/bus/usb/003/006
00000119 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000128 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x072F, PID: 0x90DB, path: /dev/bus/usb/003/007
00000010 hotplug_libudev.c:435:HPAddDevice() Adding USB device: ACS CryptoMate64
00000045 readerfactory.c:1012:RFInitializeReader() Attempting startup of ACS CryptoMate64 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
00000184 readerfactory.c:897:RFBindFunctions() Loading IFD Handler 3.0
00000032 ifdhandler.c:1965:init_driver() Driver version: 1.4.26
00000896 ifdhandler.c:1982:init_driver() LogLevel: 0x0003
00000008 ifdhandler.c:1993:init_driver() DriverOptions: 0x0000
00000251 ifdhandler.c:2006:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000008 ifdhandler.c:111:CreateChannelByNameOrChannel() Lun: 0, device: usb:072f/90db:libudev:0:/dev/bus/usb/003/007
00000009 ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:072f/90db:libudev:0:/dev/bus/usb/003/007
00000011 ccid_usb.c:269:OpenUSBByName() interface_number: 0
00000004 ccid_usb.c:270:OpenUSBByName() usb bus/device: 3/7
00000004 ccid_usb.c:302:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
00000932 ccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau at free.fr)
00000008 ccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver
00000005 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.
00008938 ccid_usb.c:406:OpenUSBByName() Try device: 3/7
00000011 ccid_usb.c:416:OpenUSBByName() vid/pid : 072F/90DB
00000004 ccid_usb.c:483:OpenUSBByName() Checking device: 3/7
00000003 ccid_usb.c:554:OpenUSBByName() Trying to open USB bus/device: 3/7
00102550 ccid_usb.c:656:OpenUSBByName() Found Vendor/Product: 072F/90DB (ACS CryptoMate64)
00000014 ccid_usb.c:658:OpenUSBByName() Using USB bus/device: 3/7
00000004 ccid_usb.c:717:OpenUSBByName() bNumDataRatesSupported is 0
00000802 ccid_usb.c:1296:InterruptRead() before (0)
00100117 ccid_usb.c:1342:InterruptRead() after (0) (2)
00000018 -> 000000 65 00 00 00 00 00 00 00 00 00 
00000505 <- 000000 81 00 00 00 00 00 00 01 00 00 
00000010 -> 000000 65 00 00 00 00 00 01 00 00 00 
00000452 <- 000000 81 00 00 00 00 00 01 01 00 00 
00000019 ifdhandler.c:382:IFDHGetCapabilities() tag: 0xFB3, usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000004 readerfactory.c:355:RFAddReader() Using the reader polling thread
00000004 ifdhandler.c:1825:IFDHICCPresence() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000005 -> 000000 65 00 00 00 00 00 02 00 00 00 
00000507 <- 000000 81 00 00 00 00 00 02 01 00 00 
00000013 ifdhandler.c:1946:IFDHICCPresence() Card present
00000060 ifdhandler.c:382:IFDHGetCapabilities() tag: 0xFAE, usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000011 ifdhandler.c:477:IFDHGetCapabilities() Reader supports 1 slot(s)
00000020 ifdhandler.c:1825:IFDHICCPresence() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000018 -> 000000 65 00 00 00 00 00 03 00 00 00 
00000269 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000133 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x0930, PID: 0x6545, path: /dev/bus/usb/003/003
00000075 <- 000000 81 00 00 00 00 00 03 01 00 00 
00000014 ifdhandler.c:1946:IFDHICCPresence() Card present
00000007 ifdhandler.c:1825:IFDHICCPresence() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000006 -> 000000 65 00 00 00 00 00 04 00 00 00 
00000016 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000119 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x062A, PID: 0x7223, path: /dev/bus/usb/003/004
00000114 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x062A, PID: 0x7223, path: /dev/bus/usb/003/004
00000164 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/004/001
00000034 <- 000000 81 00 00 00 00 00 04 01 00 00 
00000008 ifdhandler.c:1946:IFDHICCPresence() Card present
00000004 ifdhandler.c:1158:IFDHPowerICC() action: PowerUp, usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000006 -> 000000 62 00 00 00 00 00 05 01 00 00 
00000132 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000114 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000118 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x8087, PID: 0x800A, path: /dev/bus/usb/001/002
00000187 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/005/001
00000121 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/005/001
00000137 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000143 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/005/003
00000138 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/005/003
00000137 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x046D, PID: 0xC52B, path: /dev/bus/usb/005/003
00000132 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000138 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x04F9, PID: 0x0027, path: /dev/bus/usb/005/004
00000130 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000137 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x04A9, PID: 0x190A, path: /dev/bus/usb/005/005
00000130 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x2109, PID: 0x3431, path: /dev/bus/usb/005/002
00000193 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/006/001
00000195 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/007/001
00000187 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/008/001
00000167 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001
00000118 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/002/001
00000153 hotplug_libudev.c:296:get_driver() Looking for a driver for VID: 0x8087, PID: 0x8002, path: /dev/bus/usb/002/002
00000057 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010096 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010106 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010106 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010101 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010103 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010095 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010097 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010105 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010118 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010106 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010106 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010089 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010108 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010105 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010112 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010106 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010107 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00010110 readerfactory.c:1353:RFWaitForReaderInit() Waiting init for reader: ACS CryptoMate64 00 00
00007857 <- 000000 80 13 00 00 00 00 05 00 00 00 3B BE 96 00 00 41 05 20 00 00 00 00 00 00 00 00 00 90 00 
00000024 eventhandler.c:288:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
00000007 Card ATR: 3B BE 96 00 00 41 05 20 00 00 00 00 00 00 00 00 00 90 00 
00000007 ifdhandler.c:1825:IFDHICCPresence() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000005 -> 000000 65 00 00 00 00 00 06 00 00 00 
00000469 <- 000000 81 00 00 00 00 00 06 00 00 00 
00000014 ifdhandler.c:1946:IFDHICCPresence() Card present
00000005 ifdhandler.c:311:IFDHPolling() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0) 5000 ms
00000005 ccid_usb.c:1296:InterruptRead() before (0)
05000141 ccid_usb.c:1342:InterruptRead() after (0) (2)
00000018 ifdhandler.c:1825:IFDHICCPresence() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000012 -> 000000 65 00 00 00 00 00 07 00 00 00 
00000444 <- 000000 81 00 00 00 00 00 07 00 00 00 
00000009 ifdhandler.c:1946:IFDHICCPresence() Card present
00000006 ifdhandler.c:1158:IFDHPowerICC() action: PowerDown, usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000009 -> 000000 63 00 00 00 00 00 08 00 00 00 
00097646 <- 000000 81 00 00 00 00 00 08 01 00 00 
00000017 eventhandler.c:481:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
00000008 ifdhandler.c:1825:IFDHICCPresence() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000009 -> 000000 65 00 00 00 00 00 09 00 00 00 
00000481 <- 000000 81 00 00 00 00 00 09 01 00 00 
00000013 ifdhandler.c:1946:IFDHICCPresence() Card present
00000007 ifdhandler.c:311:IFDHPolling() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0) 600000 ms
00000006 ccid_usb.c:1296:InterruptRead() before (0)
06737269 winscard_msg_srv.c:253:ProcessEventsServer() Common channel packet arrival
00000032 winscard_msg_srv.c:265:ProcessEventsServer() ProcessCommonChannelRequest detects: 12
00000005 pcscdaemon.c:134:SVCServiceRunLoop() A new context thread creation is requested: 12
00000092 winscard_svc.c:331:ContextThread() Authorized PC/SC client
00000016 winscard_svc.c:335:ContextThread() Thread is started: dwClientID=12, threadContext @0xe49050
00000017 winscard_svc.c:353:ContextThread() Received command: CMD_VERSION from client 12
00000017 winscard_svc.c:365:ContextThread() Client is protocol version 4:3
00000004 winscard_svc.c:385:ContextThread() CMD_VERSION rv=0x0 for client 12
00000069 winscard_svc.c:353:ContextThread() Received command: ESTABLISH_CONTEXT from client 12
00000022 winscard.c:215:SCardEstablishContext() Establishing Context: 0x71FBE0D8
00000004 winscard_svc.c:446:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 12
00000080 winscard_svc.c:353:ContextThread() Received command: CMD_GET_READERS_STATE from client 12
00000037 winscard_svc.c:353:ContextThread() Received command: CMD_GET_READERS_STATE from client 12
00000075 winscard_svc.c:353:ContextThread() Received command: CONNECT from client 12
00000006 winscard_svc.c:484:ContextThread() Authorized client for 'ACS CryptoMate64 00 00'
00000004 winscard.c:257:SCardConnect() Attempting Connect to ACS CryptoMate64 00 00 using protocol: 3
00000004 readerfactory.c:768:RFReaderInfo() RefReader() count was: 1
00000008 ifdhandler.c:1825:IFDHICCPresence() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000008 -> 000000 65 00 00 00 00 00 0A 00 00 00 
00000538 <- 000000 81 00 00 00 00 00 0A 01 00 00 
00000016 ifdhandler.c:1946:IFDHICCPresence() Card present
00000007 ifdhandler.c:1158:IFDHPowerICC() action: PowerUp, usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000007 -> 000000 62 00 00 00 00 00 0B 01 00 00 
00273339 <- 000000 80 13 00 00 00 00 0B 00 00 00 3B BE 96 00 00 41 05 20 00 00 00 00 00 00 00 00 00 90 00 
00000023 winscard.c:332:SCardConnect() power up complete.
00000008 Card ATR: 3B BE 96 00 00 41 05 20 00 00 00 00 00 00 00 00 00 90 00 
00000005 winscard.c:352:SCardConnect() powerState: POWER_STATE_INUSE
00000005 prothandler.c:110:PHSetProtocol() Attempting PTS to T=0
00000006 ifdhandler.c:696:IFDHSetProtocolParameters() protocol T=0, usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000005 ifdhandler.c:822:IFDHSetProtocolParameters() Set speed to 250000 bauds
00000004 towitoko/atr.c:334:ATR_GetDefaultProtocol() T=0 Protocol Found
00000003 towitoko/atr.c:342:ATR_GetDefaultProtocol() default protocol: T=0
00000004 PPS: Sending request: FF 10 96 79 
00000007 -> 000000 6F 04 00 00 00 00 0C 00 00 00 FF 10 96 79 
00010349 <- 000000 80 04 00 00 00 00 0C 00 00 00 FF 10 96 79 
00000017 PPS: Receiving confirm: FF 10 96 79 
00000012 ifdhandler.c:1062:IFDHSetProtocolParameters() Communication timeout: 319488 ms
00000005 commands.c:2254:SetParameters() length: 5 bytes
00000006 -> 000000 61 05 00 00 00 00 0D 00 00 00 96 00 00 0A 00 
00001358 <- 000000 82 05 00 00 00 00 0D 00 00 00 96 00 01 0A 00 
00000017 winscard.c:431:SCardConnect() Active Protocol: T=0
00000007 winscard.c:451:SCardConnect() hCard Identity: 10366187
00000006 winscard.c:512:SCardConnect() UnrefReader() count was: 2
00000007 winscard_svc.c:498:ContextThread() CONNECT rv=0x0 for client 12
00000312 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000022 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000005 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000008 APDU: 00 A4 00 00 02 41 00 
00000006 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000006 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 7 bytes
00000008 -> 000000 6F 07 00 00 00 00 0E 00 00 00 00 A4 00 00 02 41 00 
00007883 <- 000000 80 02 00 00 00 00 0E 00 00 00 61 32 
00000025 SW: 61 32 
00000005 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000006 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000320 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000023 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000005 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000005 APDU: 00 C0 00 00 32 
00000005 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000005 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 5 bytes
00000007 -> 000000 6F 05 00 00 00 00 0F 00 00 00 00 C0 00 00 32 
00003886 <- 000000 80 34 00 00 00 00 0F 00 00 00 6F 30 83 02 41 00 88 01 00 8A 01 05 82 02 38 00 8D 02 41 03 84 10 41 43 4F 53 50 4B 43 53 2D 31 35 76 31 2E 30 30 8C 08 7F 03 FF 00 01 01 01 01 AB 00 90 00 
00000029 SW: 6F 30 83 02 41 00 88 01 00 8A 01 05 82 02 38 00 8D 02 41 03 84 10 41 43 4F 53 50 4B 43 53 2D 31 35 76 31 2E 30 30 8C 08 7F 03 FF 00 01 01 01 01 AB 00 90 00 
00000005 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000006 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000261 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000014 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000005 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000006 APDU: 00 20 00 81 08 31 32 33 34 35 36 37 38 
00000005 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000004 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 13 bytes
00000009 -> 000000 6F 0D 00 00 00 00 10 00 00 00 00 20 00 81 08 31 32 33 34 35 36 37 38 
00074566 <- 000000 80 02 00 00 00 00 10 00 00 00 90 00 
00000025 SW: 90 00 
00000006 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000007 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000345 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000016 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000005 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000007 APDU: 00 22 01 B6 0A 80 01 10 81 02 41 33 95 01 80 
00000005 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000005 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 15 bytes
00000012 -> 000000 6F 0F 00 00 00 00 11 00 00 00 00 22 01 B6 0A 80 01 10 81 02 41 33 95 01 80 
00021077 <- 000000 80 02 00 00 00 00 11 00 00 00 90 00 
00000017 SW: 90 00 
00000005 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000006 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000305 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000019 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000005 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000007 APDU: 00 22 01 B6 0A 80 01 10 81 02 41 F3 95 01 40 
00000005 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000005 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 15 bytes
00000008 -> 000000 6F 0F 00 00 00 00 12 00 00 00 00 22 01 B6 0A 80 01 10 81 02 41 F3 95 01 40 
00027341 <- 000000 80 02 00 00 00 00 12 00 00 00 90 00 
00000020 SW: 90 00 
00000006 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000006 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000248 winscard_svc.c:353:ContextThread() Received command: TRANSMIT from client 12
00000025 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000005 winscard.c:1608:SCardTransmit() Send Protocol: T=0
00000005 APDU: 00 46 00 00 02 20 06 
00000005 ifdhandler.c:1307:IFDHTransmitToICC() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000004 commands.c:1755:CmdXfrBlockTPDU_T0() T=0: 7 bytes
00000007 -> 000000 6F 07 00 00 00 00 13 00 00 00 00 46 00 00 02 20 06 
99999999 <- 000000 80 02 00 00 00 00 13 00 00 00 90 00 
00000020 SW: 90 00 
00000007 winscard.c:1653:SCardTransmit() UnrefReader() count was: 2
00000007 winscard_svc.c:659:ContextThread() TRANSMIT rv=0x0 for client 12
00000256 winscard_svc.c:353:ContextThread() Received command: DISCONNECT from client 12
00000013 readerfactory.c:795:RFReaderInfoById() RefReader() count was: 1
00000005 winscard.c:872:SCardDisconnect() Active Contexts: 1
00000003 winscard.c:873:SCardDisconnect() dwDisposition: 0
00000006 winscard.c:1038:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD
00000006 ifdhandler.c:382:IFDHGetCapabilities() tag: 0xFB2, usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000004 winscard.c:1052:SCardDisconnect() Stopping polling thread
00000004 ifdhandler.c:347:IFDHStopPolling() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000044 winscard.c:1065:SCardDisconnect() UnrefReader() count was: 2
00000009 winscard_svc.c:535:ContextThread() DISCONNECT rv=0x0 for client 12
00000003 ccid_usb.c:1342:InterruptRead() after (0) (3)
00000013 ccid_usb.c:1357:InterruptRead() InterruptRead (3/7): LIBUSB_TRANSFER_CANCELLED
00000080 winscard_svc.c:353:ContextThread() Received command: RELEASE_CONTEXT from client 12
00000009 winscard.c:226:SCardReleaseContext() Releasing Context: 0x71FBE0D8
00000004 winscard_svc.c:461:ContextThread() RELEASE_CONTEXT rv=0x0 for client 12
00000038 winscard_svc.c:345:ContextThread() Client die: 12
00000020 winscard_svc.c:1000:MSGCleanupClient() Thread is stopping: dwClientID=12, threadContext @0xe49050
00000005 winscard_svc.c:1006:MSGCleanupClient() Freeing SCONTEXT @0xe49050
00399945 eventhandler.c:493:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
00000018 ifdhandler.c:1825:IFDHICCPresence() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000008 -> 000000 65 00 00 00 00 00 14 00 00 00 
00000496 <- 000000 81 00 00 00 00 00 14 00 00 00 
00000013 ifdhandler.c:1946:IFDHICCPresence() Card present
00000007 ifdhandler.c:311:IFDHPolling() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0) 5000 ms
00000004 ccid_usb.c:1296:InterruptRead() before (0)
05000083 ccid_usb.c:1342:InterruptRead() after (0) (2)
00000017 ifdhandler.c:1825:IFDHICCPresence() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000009 -> 000000 65 00 00 00 00 00 15 00 00 00 
00000467 <- 000000 81 00 00 00 00 00 15 00 00 00 
00000012 ifdhandler.c:1946:IFDHICCPresence() Card present
00000006 ifdhandler.c:1158:IFDHPowerICC() action: PowerDown, usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000007 -> 000000 63 00 00 00 00 00 16 00 00 00 
00097709 <- 000000 81 00 00 00 00 00 16 01 00 00 
00000018 eventhandler.c:481:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
00000006 ifdhandler.c:1825:IFDHICCPresence() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0)
00000007 -> 000000 65 00 00 00 00 00 17 00 00 00 
00000493 <- 000000 81 00 00 00 00 00 17 01 00 00 
00000013 ifdhandler.c:1946:IFDHICCPresence() Card present
00000007 ifdhandler.c:311:IFDHPolling() usb:072f/90db:libudev:0:/dev/bus/usb/003/007 (lun: 0) 600000 ms
00000004 ccid_usb.c:1296:InterruptRead() before (0)



More information about the pcsclite-muscle mailing list