Hello Ludovic, > It could be interesting to have a pcscd log as documented > athttps://pcsclite.apdu.fr/#support
Here are the requested infos: $ ykman info Device type: YubiKey 4 [...] Firmware version: 4.3.5 Enabled USB interfaces: OTP+FIDO+CCID Applications OTP Enabled FIDO U2F Enabled OpenPGP Enabled PIV Enabled OATH Enabled FIDO2 Not available $ /usr/sbin/pcscd --version pcsc-lite version 1.8.25. Copyright (C) 1999-2002 by David Corcoran <corco...@musclecard.com>. Copyright (C) 2001-2018 by Ludovic Rousseau <ludovic.rouss...@free.fr>. Copyright (C) 2003-2004 by Damien Sauveron <sauve...@labri.fr>. Report bugs to <pcsclite-mus...@lists.alioth.debian.org>. Enabled features: Linux x86_64-pc-linux-gnu libsystemd serial usb libudev usbdropdir=/usr/lib/pcsc/drivers ipcdir=/var/run/pcscd configdir=/etc/reader.conf.d $ lsb_release -a No LSB modules are available. Distributor ID: Debian Description: Debian GNU/Linux 10 (buster) Release: 10 Codename: buster $ lsusb | grep -i yubi Bus 003 Device 030: ID 1050:0407 Yubico.com Yubikey 4 OTP+U2F+CCID logs attached where I ran the following sequence: mdk@lighthaven$ ssh-add -e /usr/lib/x86_64-linux-gnu/opensc-pkcs11.so; ssh-add -s /usr/lib/x86_64-linux-gnu/opensc-pkcs11.so Card removed: /usr/lib/x86_64-linux-gnu/opensc-pkcs11.so Enter passphrase for PKCS#11: Card added: /usr/lib/x86_64-linux-gnu/opensc-pkcs11.so mdk@lighthaven$ ssh mdk.fr hostname mdk.fr mdk@lighthaven$ ykman info Device type: YubiKey 4 Serial number: 5651488 Firmware version: 4.3.5 Enabled USB interfaces: OTP+FIDO+CCID Applications OTP Enabled FIDO U2F Enabled OpenPGP Enabled PIV Enabled OATH Enabled FIDO2 Not available mdk@lighthaven$ ssh mdk.fr hostname sign_and_send_pubkey: signing failed: agent refused operation m...@mdk.fr: Permission denied (publickey). Don't hesitate if you need me to run any more tests, I'm also available on freenode I'm mdk. Bests, -- Julien Palard https://mdk.fr
00000000 debuglog.c:299:DebugLogSetLevel() debug level=debug 00000012 debuglog.c:320:DebugLogSetCategory() Debug options: APDU [36m00000003[0m [140528118052800] [34mpcscdaemon.c:352:main() Force colored logs[0m [36m00000025[0m [140528118052800] [01;31mutils.c:82:GetDaemonPid() Can't open /var/run/pcscd/pcscd.pid: No such file or directory[0m [36m00000068[0m [140528118052800] configfile.l:284:DBGetReaderListDir() Parsing conf directory: /etc/reader.conf.d [36m00000011[0m [140528118052800] configfile.l:360:DBGetReaderList() Parsing conf file: /etc/reader.conf.d/libccidtwin [36m00000239[0m [140528118052800] configfile.l:321:DBGetReaderListDir() Skipping non regular file: .. [36m00000006[0m [140528118052800] configfile.l:321:DBGetReaderListDir() Skipping non regular file: . [36m00000005[0m [140528118052800] [34mpcscdaemon.c:662:main() pcsc-lite 1.8.25 daemon ready.[0m [36m00004120[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001 [36m00000109[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001 [36m00000113[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x045E, PID: 0x00CB, path: /dev/bus/usb/001/003 [36m00000100[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001 [36m00000109[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x13D3, PID: 0x3414, path: /dev/bus/usb/001/002 [36m00000109[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x13D3, PID: 0x3414, path: /dev/bus/usb/001/002 [36m00000169[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/002/001 [36m00000205[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001 [36m00000109[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001 [36m00000116[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x13BA, PID: 0x0018, path: /dev/bus/usb/003/002 [36m00000113[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x13BA, PID: 0x0018, path: /dev/bus/usb/003/002 [36m00000106[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001 [36m00000115[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x05E3, PID: 0x0610, path: /dev/bus/usb/003/003 [36m00000154[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1050, PID: 0x0407, path: /dev/bus/usb/003/030 [36m00000006[0m [140528118052800] [34mhotplug_libudev.c:440:HPAddDevice() Adding USB device: Yubico YubiKey OTP+FIDO+CCID[0m [36m00000032[0m [140528118052800] [34mreaderfactory.c:1075:RFInitializeReader() Attempting startup of Yubico YubiKey OTP+FIDO+CCID 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so[0m [36m00000184[0m [140528118052800] [34mreaderfactory.c:950:RFBindFunctions() Loading IFD Handler 3.0[0m [36m00000019[0m [140528118052800] [34mifdhandler.c:1961:init_driver() Driver version: 1.4.30[0m [36m00000607[0m [140528118052800] [34mifdhandler.c:1978:init_driver() LogLevel: 0x0003[0m [36m00000005[0m [140528118052800] [34mifdhandler.c:1989:init_driver() DriverOptions: 0x0000[0m [36m00000164[0m [140528118052800] [34mifdhandler.c:2002:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F[0m [36m00000003[0m [140528118052800] [34mifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:1050/0407:libudev:0:/dev/bus/usb/003/030[0m [36m00000003[0m [140528118052800] ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:1050/0407:libudev:0:/dev/bus/usb/003/030 [36m00000010[0m [140528118052800] ccid_usb.c:269:OpenUSBByName() interface_number: 0 [36m00000002[0m [140528118052800] ccid_usb.c:270:OpenUSBByName() usb bus/device: 3/30 [36m00000002[0m [140528118052800] [34mccid_usb.c:302:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist[0m [36m00000586[0m [140528118052800] [34mccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rouss...@free.fr)[0m [36m00000004[0m [140528118052800] [34mccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver[0m [36m00000003[0m [140528118052800] [34mccid_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.[0m [36m00004133[0m [140528118052800] ccid_usb.c:406:OpenUSBByName() Try device: 3/30 [36m00000009[0m [140528118052800] ccid_usb.c:416:OpenUSBByName() vid/pid : 1050/0407 [36m00000003[0m [140528118052800] ccid_usb.c:483:OpenUSBByName() Checking device: 3/30 [36m00000002[0m [140528118052800] ccid_usb.c:554:OpenUSBByName() Trying to open USB bus/device: 3/30 [36m00000047[0m [140528118052800] [34mccid_usb.c:637:OpenUSBByName() Found interface 2 but expecting 0[0m [36m00000003[0m [140528118052800] [34mccid_usb.c:639:OpenUSBByName() Wrong interface for USB device 3/30. Checking next one.[0m [36m00000221[0m [140528118052800] [34mccid_usb.c:189:close_libusb_if_needed() libusb_exit[0m [36m00000156[0m [140528118052800] [34mccid_usb.c:799:OpenUSBByName() Device not found?[0m [36m00000010[0m [140528118052800] [01;31mifdhandler.c:150:CreateChannelByNameOrChannel() failed[0m [36m00000005[0m [140528118052800] [01;31mreaderfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:0:/dev/bus/usb/003/030)[0m [36m00000004[0m [140528118052800] [35mreaderfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.[0m [36m00000003[0m [140528118052800] readerfactory.c:610:RFRemoveReader() UnrefReader() count was: 1 [36m00000003[0m [140528118052800] [34mreaderfactory.c:1126:RFUnInitializeReader() Attempting shutdown of Yubico YubiKey OTP+FIDO+CCID 00 00.[0m [36m00000003[0m [140528118052800] [34mreaderfactory.c:987:RFUnloadReader() Unloading reader driver.[0m [36m00000367[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1050, PID: 0x0407, path: /dev/bus/usb/003/030 [36m00000012[0m [140528118052800] [34mhotplug_libudev.c:440:HPAddDevice() Adding USB device: Yubico YubiKey OTP+FIDO+CCID[0m [36m00000054[0m [140528118052800] [34mreaderfactory.c:1075:RFInitializeReader() Attempting startup of Yubico YubiKey OTP+FIDO+CCID 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so[0m [36m00000239[0m [140528118052800] [34mreaderfactory.c:950:RFBindFunctions() Loading IFD Handler 3.0[0m [36m00000029[0m [140528118052800] [34mifdhandler.c:1961:init_driver() Driver version: 1.4.30[0m [36m00000902[0m [140528118052800] [34mifdhandler.c:1978:init_driver() LogLevel: 0x0003[0m [36m00000012[0m [140528118052800] [34mifdhandler.c:1989:init_driver() DriverOptions: 0x0000[0m [36m00000284[0m [140528118052800] [34mifdhandler.c:2002:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F[0m [36m00000010[0m [140528118052800] [34mifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:1050/0407:libudev:1:/dev/bus/usb/003/030[0m [36m00000005[0m [140528118052800] ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:1050/0407:libudev:1:/dev/bus/usb/003/030 [36m00000008[0m [140528118052800] ccid_usb.c:269:OpenUSBByName() interface_number: 1 [36m00000004[0m [140528118052800] ccid_usb.c:270:OpenUSBByName() usb bus/device: 3/30 [36m00000003[0m [140528118052800] [34mccid_usb.c:302:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist[0m [36m00000913[0m [140528118052800] [34mccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rouss...@free.fr)[0m [36m00000011[0m [140528118052800] [34mccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver[0m [36m00000005[0m [140528118052800] [34mccid_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.[0m [36m00006287[0m [140528118052800] ccid_usb.c:406:OpenUSBByName() Try device: 3/30 [36m00000013[0m [140528118052800] ccid_usb.c:416:OpenUSBByName() vid/pid : 1050/0407 [36m00000005[0m [140528118052800] ccid_usb.c:483:OpenUSBByName() Checking device: 3/30 [36m00000004[0m [140528118052800] ccid_usb.c:554:OpenUSBByName() Trying to open USB bus/device: 3/30 [36m00000073[0m [140528118052800] [34mccid_usb.c:637:OpenUSBByName() Found interface 2 but expecting 1[0m [36m00000008[0m [140528118052800] [34mccid_usb.c:639:OpenUSBByName() Wrong interface for USB device 3/30. Checking next one.[0m [36m00000308[0m [140528118052800] [34mccid_usb.c:189:close_libusb_if_needed() libusb_exit[0m [36m00000127[0m [140528118052800] [34mccid_usb.c:799:OpenUSBByName() Device not found?[0m [36m00000029[0m [140528118052800] [01;31mifdhandler.c:150:CreateChannelByNameOrChannel() failed[0m [36m00000005[0m [140528118052800] [01;31mreaderfactory.c:1106:RFInitializeReader() Open Port 0x200000 Failed (usb:1050/0407:libudev:1:/dev/bus/usb/003/030)[0m [36m00000004[0m [140528118052800] [35mreaderfactory.c:376:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.[0m [36m00000004[0m [140528118052800] readerfactory.c:610:RFRemoveReader() UnrefReader() count was: 1 [36m00000004[0m [140528118052800] [34mreaderfactory.c:1126:RFUnInitializeReader() Attempting shutdown of Yubico YubiKey OTP+FIDO+CCID 00 00.[0m [36m00000003[0m [140528118052800] [34mreaderfactory.c:987:RFUnloadReader() Unloading reader driver.[0m [36m00000392[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1050, PID: 0x0407, path: /dev/bus/usb/003/030 [36m00000013[0m [140528118052800] [34mhotplug_libudev.c:440:HPAddDevice() Adding USB device: Yubico YubiKey OTP+FIDO+CCID[0m [36m00000059[0m [140528118052800] [34mreaderfactory.c:1075:RFInitializeReader() Attempting startup of Yubico YubiKey OTP+FIDO+CCID 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so[0m [36m00000263[0m [140528118052800] [34mreaderfactory.c:950:RFBindFunctions() Loading IFD Handler 3.0[0m [36m00000032[0m [140528118052800] [34mifdhandler.c:1961:init_driver() Driver version: 1.4.30[0m [36m00000895[0m [140528118052800] [34mifdhandler.c:1978:init_driver() LogLevel: 0x0003[0m [36m00000011[0m [140528118052800] [34mifdhandler.c:1989:init_driver() DriverOptions: 0x0000[0m [36m00000258[0m [140528118052800] [34mifdhandler.c:2002:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F[0m [36m00000010[0m [140528118052800] [34mifdhandler.c:110:CreateChannelByNameOrChannel() Lun: 0, device: usb:1050/0407:libudev:2:/dev/bus/usb/003/030[0m [36m00000005[0m [140528118052800] ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:1050/0407:libudev:2:/dev/bus/usb/003/030 [36m00000008[0m [140528118052800] ccid_usb.c:269:OpenUSBByName() interface_number: 2 [36m00000004[0m [140528118052800] ccid_usb.c:270:OpenUSBByName() usb bus/device: 3/30 [36m00000003[0m [140528118052800] [34mccid_usb.c:302:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist[0m [36m00000937[0m [140528118052800] [34mccid_usb.c:320:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rouss...@free.fr)[0m [36m00000012[0m [140528118052800] [34mccid_usb.c:321:OpenUSBByName() ifdProductString: Generic CCID driver[0m [36m00000005[0m [140528118052800] [34mccid_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.[0m [36m00005325[0m [140528118052800] ccid_usb.c:406:OpenUSBByName() Try device: 3/30 [36m00000009[0m [140528118052800] ccid_usb.c:416:OpenUSBByName() vid/pid : 1050/0407 [36m00000004[0m [140528118052800] ccid_usb.c:483:OpenUSBByName() Checking device: 3/30 [36m00000002[0m [140528118052800] ccid_usb.c:554:OpenUSBByName() Trying to open USB bus/device: 3/30 [36m00000085[0m [140528118052800] [34mccid_usb.c:660:OpenUSBByName() Found Vendor/Product: 1050/0407 (Yubico YubiKey OTP+FIDO+CCID)[0m [36m00000009[0m [140528118052800] [34mccid_usb.c:662:OpenUSBByName() Using USB bus/device: 3/30[0m [36m00000004[0m [140528118052800] [34mccid_usb.c:722:OpenUSBByName() bNumDataRatesSupported is 0[0m [36m00000590[0m [140528118052800] ccid_usb.c:1306:InterruptRead() before (0) [36m00101389[0m [140528118052800] ccid_usb.c:1352:InterruptRead() after (0) (2) [36m00000029[0m [140528118052800] -> 000000 65 00 00 00 00 00 00 00 00 00 [36m00000418[0m [140528118052800] <- 000000 81 00 00 00 00 00 00 00 00 00 [36m00000023[0m [140528118052800] -> 000000 65 00 00 00 00 00 01 00 00 00 [36m00000424[0m [140528118052800] <- 000000 81 00 00 00 00 00 01 00 00 00 [36m00000023[0m [140528118052800] [34mifdhandler.c:381:IFDHGetCapabilities() tag: 0xFB3, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000007[0m [140528118052800] [34mreaderfactory.c:396:RFAddReader() Using the reader polling thread[0m [36m00000006[0m [140528118052800] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) [36m00000008[0m [140528118052800] -> 000000 65 00 00 00 00 00 02 00 00 00 [36m00000330[0m [140528118052800] <- 000000 81 00 00 00 00 00 02 00 00 00 [36m00000019[0m [140528118052800] ifdhandler.c:1942:IFDHICCPresence() Card present [36m00000176[0m [140528118052800] [34mifdhandler.c:381:IFDHGetCapabilities() tag: 0xFAE, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000014[0m [140528118052800] [34mifdhandler.c:476:IFDHGetCapabilities() Reader supports 1 slot(s)[0m [36m00000048[0m [140528098866944] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) [36m00000018[0m [140528098866944] -> 000000 65 00 00 00 00 00 03 00 00 00 [36m00000384[0m [140528098866944] <- 000000 81 00 00 00 00 00 03 00 00 00 [36m00000017[0m [140528098866944] ifdhandler.c:1942:IFDHICCPresence() Card present [36m00000008[0m [140528098866944] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) [36m00000009[0m [140528098866944] -> 000000 65 00 00 00 00 00 04 00 00 00 [36m00000120[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x05E3, PID: 0x0610, path: /dev/bus/usb/003/003 [36m00000169[0m [140528098866944] <- 000000 81 00 00 00 00 00 04 00 00 00 [36m00000016[0m [140528098866944] ifdhandler.c:1942:IFDHICCPresence() Card present [36m00000007[0m [140528098866944] [34mifdhandler.c:1154:IFDHPowerICC() action: PowerUp, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000009[0m [140528098866944] -> 000000 62 00 00 00 00 00 05 00 00 00 [36m00000215[0m [140528098866944] <- 000000 80 12 00 00 00 00 05 00 00 00 3B F8 13 00 00 81 31 FE 15 59 75 62 69 6B 65 79 34 D4 [36m00000011[0m [140528098866944] eventhandler.c:289:EHStatusHandlerThread() powerState: POWER_STATE_POWERED [36m00000009[0m [140528098866944] [34mCard ATR: 3B F8 13 00 00 81 31 FE 15 59 75 62 69 6B 65 79 34 D4 [0m [36m00000006[0m [140528098866944] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) [36m00000007[0m [140528098866944] -> 000000 65 00 00 00 00 00 06 00 00 00 [36m00000166[0m [140528098866944] <- 000000 81 00 00 00 00 00 06 00 00 00 [36m00000016[0m [140528098866944] ifdhandler.c:1942:IFDHICCPresence() Card present [36m00000002[0m [140528118052800] hotplug_libudev.c:301:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/004/001 [36m00000006[0m [140528098866944] [34mifdhandler.c:310:IFDHPolling() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) 5000 ms[0m [36m00000024[0m [140528098866944] ccid_usb.c:1306:InterruptRead() before (0) [36m05000597[0m [140528098866944] ccid_usb.c:1352:InterruptRead() after (0) (2) [36m00000027[0m [140528098866944] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) [36m00000012[0m [140528098866944] -> 000000 65 00 00 00 00 00 07 00 00 00 [36m00000332[0m [140528098866944] <- 000000 81 00 00 00 00 00 07 00 00 00 [36m00000022[0m [140528098866944] ifdhandler.c:1942:IFDHICCPresence() Card present [36m00000008[0m [140528098866944] [34mifdhandler.c:1154:IFDHPowerICC() action: PowerDown, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000009[0m [140528098866944] -> 000000 63 00 00 00 00 00 08 00 00 00 [36m00000381[0m [140528098866944] <- 000000 81 00 00 00 00 00 08 02 00 00 [36m00000029[0m [140528098866944] eventhandler.c:482:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED [36m00000009[0m [140528098866944] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) [36m00000009[0m [140528098866944] -> 000000 65 00 00 00 00 00 09 00 00 00 [36m00000335[0m [140528098866944] <- 000000 81 00 00 00 00 00 09 00 00 00 [36m00000021[0m [140528098866944] ifdhandler.c:1942:IFDHICCPresence() Card present [36m00000009[0m [140528098866944] [34mifdhandler.c:310:IFDHPolling() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) 600000 ms[0m [36m00000007[0m [140528098866944] ccid_usb.c:1306:InterruptRead() before (0) [36m07581014[0m [140528118052800] winscard_msg_srv.c:255:ProcessEventsServer() Common channel packet arrival [36m00000042[0m [140528118052800] winscard_msg_srv.c:267:ProcessEventsServer() ProcessCommonChannelRequest detects: 14 [36m00000007[0m [140528118052800] pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 14 [36m00000205[0m [140528082081536] winscard_svc.c:340:ContextThread() Authorized PC/SC client [36m00000019[0m [140528082081536] winscard_svc.c:344:ContextThread() Thread is started: dwClientID=14, threadContext @0x55921e866af0 [36m00000020[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_VERSION from client 14 [36m00000010[0m [140528082081536] winscard_svc.c:374:ContextThread() Client is protocol version 4:4 [36m00000005[0m [140528082081536] winscard_svc.c:394:ContextThread() CMD_VERSION rv=0x0 for client 14 [36m00000143[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: ESTABLISH_CONTEXT from client 14 [36m00000025[0m [140528082081536] winscard.c:215:SCardEstablishContext() Establishing Context: 0x6D1DCECE [36m00000007[0m [140528082081536] winscard_svc.c:459:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 14 [36m00000140[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 [36m00000136[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 [36m00000189[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000052[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000127[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000019[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000117[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CONNECT from client 14 [36m00000023[0m [140528082081536] winscard_svc.c:497:ContextThread() Authorized client for 'Yubico YubiKey OTP+FIDO+CCID 00 00' [36m00000006[0m [140528082081536] winscard.c:259:SCardConnect() Attempting Connect to Yubico YubiKey OTP+FIDO+CCID 00 00 using protocol: 3 [36m00000005[0m [140528082081536] readerfactory.c:821:RFReaderInfo() RefReader() count was: 1 [36m00000008[0m [140528082081536] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) [36m00000010[0m [140528082081536] -> 000000 65 00 00 00 00 00 0A 00 00 00 [36m00000254[0m [140528082081536] <- 000000 81 00 00 00 00 00 0A 00 00 00 [36m00000010[0m [140528082081536] ifdhandler.c:1942:IFDHICCPresence() Card present [36m00000007[0m [140528082081536] [34mifdhandler.c:1154:IFDHPowerICC() action: PowerUp, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000011[0m [140528082081536] -> 000000 62 00 00 00 00 00 0B 00 00 00 [36m00000201[0m [140528082081536] <- 000000 80 12 00 00 00 00 0B 00 00 00 3B F8 13 00 00 81 31 FE 15 59 75 62 69 6B 65 79 34 D4 [36m00000010[0m [140528082081536] winscard.c:332:SCardConnect() power up complete. [36m00000009[0m [140528082081536] Card ATR: 3B F8 13 00 00 81 31 FE 15 59 75 62 69 6B 65 79 34 D4 [36m00000005[0m [140528082081536] winscard.c:352:SCardConnect() powerState: POWER_STATE_IN_USE [36m00000007[0m [140528082081536] [34mprothandler.c:108:PHSetProtocol() Attempting PTS to T=1[0m [36m00000008[0m [140528082081536] [34mifdhandler.c:695:IFDHSetProtocolParameters() protocol T=1, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] ifdhandler.c:709:IFDHSetProtocolParameters() Timeout: 3000 ms [36m00000005[0m [140528082081536] winscard.c:431:SCardConnect() Active Protocol: T=1 [36m00000007[0m [140528082081536] winscard.c:456:SCardConnect() hCard Identity: 5252b099 [36m00000007[0m [140528082081536] winscard.c:518:SCardConnect() UnrefReader() count was: 2 [36m00000009[0m [140528082081536] winscard_svc.c:511:ContextThread() CONNECT rv=0x0 for client 14 [36m00000055[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CONTROL from client 14 [36m00000015[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000006[0m [140528082081536] [34mifdhandler.c:1411:IFDHControl() ControlCode: 0x42000D48, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000004[0m [140528082081536] [34mControl TxBuffer: [0m [36m00000014[0m [140528082081536] [34mControl RxBuffer: 12 04 42 33 00 12 [0m [36m00000005[0m [140528082081536] winscard.c:1359:SCardControl() UnrefReader() count was: 2 [36m00000004[0m [140528082081536] winscard_svc.c:733:ContextThread() CONTROL rv=0x0 for client 14 [36m00000045[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CONTROL from client 14 [36m00000010[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] [34mifdhandler.c:1411:IFDHControl() ControlCode: 0x42330012, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000004[0m [140528082081536] [34mControl TxBuffer: [0m [36m00000015[0m [140528082081536] [34mControl RxBuffer: 01 02 00 00 03 01 00 09 01 00 0B 02 50 10 0C 02 07 04 0A 04 00 00 01 00 [0m [36m00000004[0m [140528082081536] winscard.c:1359:SCardControl() UnrefReader() count was: 2 [36m00000006[0m [140528082081536] winscard_svc.c:733:ContextThread() CONTROL rv=0x0 for client 14 [36m00000047[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CONTROL from client 14 [36m00000010[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000004[0m [140528082081536] [34mifdhandler.c:1411:IFDHControl() ControlCode: 0x42330012, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000005[0m [140528082081536] [34mControl TxBuffer: [0m [36m00000017[0m [140528082081536] [34mControl RxBuffer: 01 02 00 00 03 01 00 09 01 00 0B 02 50 10 0C 02 07 04 0A 04 00 00 01 00 [0m [36m00000005[0m [140528082081536] winscard.c:1359:SCardControl() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:733:ContextThread() CONTROL rv=0x0 for client 14 [36m00000047[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: GET_ATTRIB from client 14 [36m00000011[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000006[0m [140528082081536] [34mifdhandler.c:381:IFDHGetCapabilities() tag: 0x10100, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000005[0m [140528082081536] winscard.c:1434:SCardGetAttrib() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:762:ContextThread() GET_ATTRIB rv=0x0 for client 14 [36m00000038[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: GET_ATTRIB from client 14 [36m00000011[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000004[0m [140528082081536] [34mifdhandler.c:381:IFDHGetCapabilities() tag: 0x10102, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000004[0m [140528082081536] winscard.c:1434:SCardGetAttrib() UnrefReader() count was: 2 [36m00000004[0m [140528082081536] winscard_svc.c:762:ContextThread() GET_ATTRIB rv=0x0 for client 14 [36m00000037[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: DISCONNECT from client 14 [36m00000014[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000004[0m [140528082081536] winscard.c:881:SCardDisconnect() Active Contexts: 1 [36m00000004[0m [140528082081536] winscard.c:882:SCardDisconnect() dwDisposition: 0 [36m00000005[0m [140528082081536] winscard.c:1017:SCardDisconnect() powerState: POWER_STATE_GRACE_PERIOD [36m00000004[0m [140528082081536] [34mifdhandler.c:381:IFDHGetCapabilities() tag: 0xFB2, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000004[0m [140528082081536] [34mwinscard.c:1030:SCardDisconnect() Stopping polling thread[0m [36m00000005[0m [140528082081536] [34mifdhandler.c:346:IFDHStopPolling() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000896[0m [140528082081536] winscard.c:1043:SCardDisconnect() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:548:ContextThread() DISCONNECT rv=0x0 for client 14 [36m00000005[0m [140528098866944] ccid_usb.c:1352:InterruptRead() after (0) (3) [36m00000014[0m [140528098866944] ccid_usb.c:1367:InterruptRead() InterruptRead (3/30): 3 [36m00000080[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000008[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000064[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000008[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000038[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000005[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000041[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000005[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000045[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000006[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000044[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000008[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000043[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000011[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000042[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000008[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000047[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000006[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000055[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000005[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000041[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000008[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000041[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000010[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000046[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CONNECT from client 14 [36m00000012[0m [140528082081536] winscard_svc.c:497:ContextThread() Authorized client for 'Yubico YubiKey OTP+FIDO+CCID 00 00' [36m00000004[0m [140528082081536] winscard.c:259:SCardConnect() Attempting Connect to Yubico YubiKey OTP+FIDO+CCID 00 00 using protocol: 3 [36m00000004[0m [140528082081536] readerfactory.c:821:RFReaderInfo() RefReader() count was: 1 [36m00000005[0m [140528082081536] winscard.c:352:SCardConnect() powerState: POWER_STATE_IN_USE [36m00000006[0m [140528082081536] winscard.c:431:SCardConnect() Active Protocol: T=1 [36m00000007[0m [140528082081536] winscard.c:456:SCardConnect() hCard Identity: 10ee90e0 [36m00000005[0m [140528082081536] winscard.c:518:SCardConnect() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:511:ContextThread() CONNECT rv=0x0 for client 14 [36m00000298[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000015[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000005[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000004[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000004[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000051[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000018[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000004[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000007[0m [140528082081536] [34mAPDU: 00 A4 04 00 06 A0 00 00 00 01 01 [0m [36m00000005[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000005[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes [36m00000010[0m [140528082081536] -> 000000 6F 0B 00 00 00 00 0C 00 00 00 00 A4 04 00 06 A0 00 00 00 01 01 [36m00001009[0m [140528082081536] <- 000000 80 02 00 00 00 00 0C 00 00 00 6A 82 [36m00000011[0m [140528082081536] [34mSW: 6A 82 [0m [36m00000004[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000056[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: END_TRANSACTION from client 14 [36m00000012[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000004[0m [140528082081536] winscard.c:1234:SCardEndTransaction() Status: 0x00000000 [36m00000004[0m [140528082081536] winscard.c:1237:SCardEndTransaction() UnrefReader() count was: 2 [36m00000004[0m [140528082081536] winscard_svc.c:579:ContextThread() END_TRANSACTION rv=0x0 for client 14 [36m00001267[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000013[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000004[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000004[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000004[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000053[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000015[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000005[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000007[0m [140528082081536] [34mAPDU: 00 A4 04 00 0B E8 2B 06 01 04 01 81 C3 1F 02 01 00 [0m [36m00000005[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000004[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 17 bytes [36m00000013[0m [140528082081536] -> 000000 6F 11 00 00 00 00 0D 00 00 00 00 A4 04 00 0B E8 2B 06 01 04 01 81 C3 1F 02 01 00 [36m00001007[0m [140528082081536] <- 000000 80 02 00 00 00 00 0D 00 00 00 6A 82 [36m00000009[0m [140528082081536] [34mSW: 6A 82 [0m [36m00000004[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000172[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: END_TRANSACTION from client 14 [36m00000015[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000005[0m [140528082081536] winscard.c:1234:SCardEndTransaction() Status: 0x00000000 [36m00000003[0m [140528082081536] winscard.c:1237:SCardEndTransaction() UnrefReader() count was: 2 [36m00000004[0m [140528082081536] winscard_svc.c:579:ContextThread() END_TRANSACTION rv=0x0 for client 14 [36m00008898[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000026[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000004[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000176[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000031[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000006[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000011[0m [140528082081536] [34mAPDU: 00 A4 04 0C 0F D2 33 00 00 00 45 73 74 45 49 44 20 76 33 35 [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000005[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 20 bytes [36m00000013[0m [140528082081536] -> 000000 6F 14 00 00 00 00 0E 00 00 00 00 A4 04 0C 0F D2 33 00 00 00 45 73 74 45 49 44 20 76 33 35 [36m00000948[0m [140528082081536] <- 000000 80 02 00 00 00 00 0E 00 00 00 6D 00 [36m00000017[0m [140528082081536] [34mSW: 6D 00 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000128[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: END_TRANSACTION from client 14 [36m00000026[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1234:SCardEndTransaction() Status: 0x00000000 [36m00000004[0m [140528082081536] winscard.c:1237:SCardEndTransaction() UnrefReader() count was: 2 [36m00000006[0m [140528082081536] winscard_svc.c:579:ContextThread() END_TRANSACTION rv=0x0 for client 14 [36m00009125[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000033[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000005[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000171[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000031[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000006[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000007[0m [140528082081536] [34mAPDU: 00 CA DF 30 05 [0m [36m00000008[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000007[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes [36m00000010[0m [140528082081536] -> 000000 6F 05 00 00 00 00 0F 00 00 00 00 CA DF 30 05 [36m00000947[0m [140528082081536] <- 000000 80 02 00 00 00 00 0F 00 00 00 6D 00 [36m00000024[0m [140528082081536] [34mSW: 6D 00 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000162[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: END_TRANSACTION from client 14 [36m00000026[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1234:SCardEndTransaction() Status: 0x00000000 [36m00000005[0m [140528082081536] winscard.c:1237:SCardEndTransaction() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:579:ContextThread() END_TRANSACTION rv=0x0 for client 14 [36m00006665[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000031[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000009[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000004[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000006[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000135[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000031[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000008[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 08 [0m [36m00000009[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000005[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000040[0m [140528082081536] -> 000000 6F 09 00 00 00 00 10 00 00 00 00 CB 3F FF 03 5C 01 7E 08 [36m00000924[0m [140528082081536] <- 000000 80 02 00 00 00 00 10 00 00 00 6D 00 [36m00000017[0m [140528082081536] [34mSW: 6D 00 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000194[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000030[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000006[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000010[0m [140528082081536] [34mAPDU: 00 A4 04 00 09 A0 00 00 03 08 00 00 10 00 00 [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000005[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 15 bytes [36m00000012[0m [140528082081536] -> 000000 6F 0F 00 00 00 00 11 00 00 00 00 A4 04 00 09 A0 00 00 03 08 00 00 10 00 00 [36m00001304[0m [140528082081536] <- 000000 80 15 00 00 00 00 11 00 00 00 61 11 4F 06 00 00 10 00 01 00 79 07 4F 05 A0 00 00 03 08 90 00 [36m00000029[0m [140528082081536] [34mSW: 61 11 4F 06 00 00 10 00 01 00 79 07 4F 05 A0 00 00 03 08 90 00 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000227[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000031[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000008[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 08 [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000011[0m [140528082081536] -> 000000 6F 09 00 00 00 00 12 00 00 00 00 CB 3F FF 03 5C 01 7E 08 [36m00000970[0m [140528082081536] <- 000000 80 0A 00 00 00 00 12 00 00 00 7E 12 4F 0B A0 00 00 03 61 0C [36m00000020[0m [140528082081536] [34mSW: 7E 12 4F 0B A0 00 00 03 61 0C [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000232[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000031[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000006[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000009[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 14 [0m [36m00000006[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000010[0m [140528082081536] -> 000000 6F 09 00 00 00 00 13 00 00 00 00 CB 3F FF 03 5C 01 7E 14 [36m00001052[0m [140528082081536] <- 000000 80 16 00 00 00 00 13 00 00 00 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [36m00000023[0m [140528082081536] [34mSW: 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000196[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: END_TRANSACTION from client 14 [36m00000025[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1234:SCardEndTransaction() Status: 0x00000000 [36m00000005[0m [140528082081536] winscard.c:1237:SCardEndTransaction() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:579:ContextThread() END_TRANSACTION rv=0x0 for client 14 [36m00004201[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000026[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000004[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000006[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000172[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000030[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000008[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 08 [0m [36m00000006[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000008[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000010[0m [140528082081536] -> 000000 6F 09 00 00 00 00 14 00 00 00 00 CB 3F FF 03 5C 01 7E 08 [36m00001082[0m [140528082081536] <- 000000 80 0A 00 00 00 00 14 00 00 00 7E 12 4F 0B A0 00 00 03 61 0C [36m00000026[0m [140528082081536] [34mSW: 7E 12 4F 0B A0 00 00 03 61 0C [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000209[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000032[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000008[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 14 [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000015[0m [140528082081536] -> 000000 6F 09 00 00 00 00 15 00 00 00 00 CB 3F FF 03 5C 01 7E 14 [36m00001162[0m [140528082081536] <- 000000 80 16 00 00 00 00 15 00 00 00 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [36m00000030[0m [140528082081536] [34mSW: 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000228[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000032[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000009[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 00 [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000010[0m [140528082081536] -> 000000 6F 09 00 00 00 00 16 00 00 00 00 CB 3F FF 03 5C 01 7E 00 [36m00001182[0m [140528082081536] <- 000000 80 16 00 00 00 00 16 00 00 00 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [36m00000029[0m [140528082081536] [34mSW: 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [0m [36m00000008[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000219[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000032[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000007[0m [140528082081536] [34mAPDU: 00 FD 00 00 03 [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes [36m00000010[0m [140528082081536] -> 000000 6F 05 00 00 00 00 17 00 00 00 00 FD 00 00 03 [36m00000978[0m [140528082081536] <- 000000 80 05 00 00 00 00 17 00 00 00 04 03 05 90 00 [36m00000019[0m [140528082081536] [34mSW: 04 03 05 90 00 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000006[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000201[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000030[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000009[0m [140528082081536] [34mAPDU: 00 CB 3F FF 05 5C 03 5F C1 0C 08 [0m [36m00000006[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000008[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes [36m00000011[0m [140528082081536] -> 000000 6F 0B 00 00 00 00 18 00 00 00 00 CB 3F FF 05 5C 03 5F C1 0C 08 [36m00000975[0m [140528082081536] <- 000000 80 02 00 00 00 00 18 00 00 00 6A 82 [36m00000019[0m [140528082081536] [34mSW: 6A 82 [0m [36m00000008[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000192[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: END_TRANSACTION from client 14 [36m00000027[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1234:SCardEndTransaction() Status: 0x00000000 [36m00000004[0m [140528082081536] winscard.c:1237:SCardEndTransaction() UnrefReader() count was: 2 [36m00000006[0m [140528082081536] winscard_svc.c:579:ContextThread() END_TRANSACTION rv=0x0 for client 14 [36m00007448[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000032[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000005[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000176[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000031[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000008[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 00 [0m [36m00000008[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000013[0m [140528082081536] -> 000000 6F 09 00 00 00 00 19 00 00 00 00 CB 3F FF 03 5C 01 7E 00 [36m00001065[0m [140528082081536] <- 000000 80 16 00 00 00 00 19 00 00 00 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [36m00000028[0m [140528082081536] [34mSW: 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000246[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000031[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000008[0m [140528082081536] [34mAPDU: 00 CB 3F FF 05 5C 03 5F C1 02 08 [0m [36m00000008[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes [36m00000012[0m [140528082081536] -> 000000 6F 0B 00 00 00 00 1A 00 00 00 00 CB 3F FF 05 5C 03 5F C1 02 08 [36m00001105[0m [140528082081536] <- 000000 80 02 00 00 00 00 1A 00 00 00 6A 82 [36m00000023[0m [140528082081536] [34mSW: 6A 82 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000314[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000032[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000009[0m [140528082081536] [34mAPDU: 00 CB 3F FF 05 5C 03 5F C1 05 08 [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes [36m00000012[0m [140528082081536] -> 000000 6F 0B 00 00 00 00 1B 00 00 00 00 CB 3F FF 05 5C 03 5F C1 05 08 [36m00002481[0m [140528082081536] <- 000000 80 0A 00 00 00 00 1B 00 00 00 53 82 02 BA 70 82 02 B1 61 FF [36m00000026[0m [140528082081536] [34mSW: 53 82 02 BA 70 82 02 B1 61 FF [0m [36m00000008[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000217[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000032[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000008[0m [140528082081536] [34mAPDU: 00 CB 3F FF 05 5C 03 5F C1 05 00 [0m [36m00000008[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes [36m00000011[0m [140528082081536] -> 000000 6F 0B 00 00 00 00 1C 00 00 00 00 CB 3F FF 05 5C 03 5F C1 05 00 [36m00002777[0m [140528082081536] <- 000000 80 00 01 00 00 00 1C 00 00 00 53 82 02 BA 70 82 02 B1 30 82 02 AD 30 82 01 97 A0 03 02 01 02 02 09 00 E2 97 17 E2 41 77 F6 7B 30 0B 06 09 2A 86 48 86 F7 0D 01 01 0B 30 18 31 16 30 14 06 03 55 04 03 0C 0D 4A 75 6C 69 65 6E 20 50 61 6C 61 72 64 30 1E 17 0D 31 37 30 37 33 31 32 31 35 30 30 34 5A 17 0D 31 38 30 37 33 31 32 31 35 30 30 34 5A 30 18 31 16 30 14 06 03 55 04 03 0C 0D 4A 75 6C 69 65 6E 20 50 61 6C 61 72 64 30 82 01 22 30 0D 06 09 2A 86 48 86 F7 0D 01 01 01 05 00 03 82 01 0F 00 30 82 01 0A 02 82 01 01 00 CA 03 B0 E0 4D 0D 06 EF E9 1D B1 7D 25 21 43 80 00 E9 65 C0 64 82 BB CB 3C 4F 13 EF AF FE B5 63 CA BE A4 83 B9 9A B6 72 70 8D 31 61 C2 8F E4 D6 5A 00 1A A5 17 25 D4 50 D7 CB 8F B4 4B AA D7 81 C6 AA B6 41 93 F8 34 E2 75 32 EB 58 44 89 33 27 D6 16 D1 FE 7A E6 31 DA 3A A4 A0 BB 61 FF [36m00000086[0m [140528082081536] [34mSW: 53 82 02 BA 70 82 02 B1 30 82 02 AD 30 82 01 97 A0 03 02 01 02 02 09 00 E2 97 17 E2 41 77 F6 7B 30 0B 06 09 2A 86 48 86 F7 0D 01 01 0B 30 18 31 16 30 14 06 03 55 04 03 0C 0D 4A 75 6C 69 65 6E 20 50 61 6C 61 72 64 30 1E 17 0D 31 37 30 37 33 31 32 31 35 30 30 34 5A 17 0D 31 38 30 37 33 31 32 31 35 30 30 34 5A 30 18 31 16 30 14 06 03 55 04 03 0C 0D 4A 75 6C 69 65 6E 20 50 61 6C 61 72 64 30 82 01 22 30 0D 06 09 2A 86 48 86 F7 0D 01 01 01 05 00 03 82 01 0F 00 30 82 01 0A 02 82 01 01 00 CA 03 B0 E0 4D 0D 06 EF E9 1D B1 7D 25 21 43 80 00 E9 65 C0 64 82 BB CB 3C 4F 13 EF AF FE B5 63 CA BE A4 83 B9 9A B6 72 70 8D 31 61 C2 8F E4 D6 5A 00 1A A5 17 25 D4 50 D7 CB 8F B4 4B AA D7 81 C6 AA B6 41 93 F8 34 E2 75 32 EB 58 44 89 33 27 D6 16 D1 FE 7A E6 31 DA 3A A4 A0 BB 61 FF [0m [36m00000009[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000011[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000329[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000033[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000007[0m [140528082081536] [34mAPDU: 00 C0 00 00 FF [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes [36m00000010[0m [140528082081536] -> 000000 6F 05 00 00 00 00 1D 00 00 00 00 C0 00 00 FF [36m00000910[0m [140528082081536] <- 000000 80 01 01 00 00 00 1D 00 00 00 3D 0C 63 ED 6C 08 7B 6E 10 F8 94 51 9A 0D 7B 19 0A 37 B4 88 C7 AD 12 7E 86 8C 12 F1 A3 02 3E 3A B7 10 3B 54 56 C9 52 E2 9C 26 D0 35 62 F9 A7 D0 2D 0A A1 75 14 43 F1 BE 26 AB E3 43 B4 2E 28 B4 18 75 36 7C 4B D3 84 BC D6 AA 35 FC 0D 4B 0B F2 D7 F2 C2 9E E6 A3 17 0D 0E F8 7E 5B CF B0 1C 4B 92 2E 8F EF 64 F3 08 07 3C AD 5A D6 B4 04 76 D7 8E 72 7D 97 04 2B ED 6B F5 4E 56 51 4B BF AF FD 2B 78 94 DC 42 75 A7 26 99 F3 C5 24 B8 97 9F F1 A5 A7 1F F0 09 19 A1 76 80 BA 94 6C 1B 8B F9 47 97 C8 3E 35 02 03 01 00 01 30 0B 06 09 2A 86 48 86 F7 0D 01 01 0B 03 82 01 01 00 00 13 E9 AA 44 AE 43 D2 AD 38 47 15 4C CE 6F 8D 75 D6 BA 96 82 18 47 8B C8 B0 B9 AC 25 A3 65 42 7E 15 47 07 39 F9 A9 97 B6 8B 89 F4 5B EF D0 82 41 E8 87 FA D6 18 93 82 EB 9A A7 FB 25 9F 07 03 94 27 1C BC 61 C1 [36m00000089[0m [140528082081536] [34mSW: 3D 0C 63 ED 6C 08 7B 6E 10 F8 94 51 9A 0D 7B 19 0A 37 B4 88 C7 AD 12 7E 86 8C 12 F1 A3 02 3E 3A B7 10 3B 54 56 C9 52 E2 9C 26 D0 35 62 F9 A7 D0 2D 0A A1 75 14 43 F1 BE 26 AB E3 43 B4 2E 28 B4 18 75 36 7C 4B D3 84 BC D6 AA 35 FC 0D 4B 0B F2 D7 F2 C2 9E E6 A3 17 0D 0E F8 7E 5B CF B0 1C 4B 92 2E 8F EF 64 F3 08 07 3C AD 5A D6 B4 04 76 D7 8E 72 7D 97 04 2B ED 6B F5 4E 56 51 4B BF AF FD 2B 78 94 DC 42 75 A7 26 99 F3 C5 24 B8 97 9F F1 A5 A7 1F F0 09 19 A1 76 80 BA 94 6C 1B 8B F9 47 97 C8 3E 35 02 03 01 00 01 30 0B 06 09 2A 86 48 86 F7 0D 01 01 0B 03 82 01 01 00 00 13 E9 AA 44 AE 43 D2 AD 38 47 15 4C CE 6F 8D 75 D6 BA 96 82 18 47 8B C8 B0 B9 AC 25 A3 65 42 7E 15 47 07 39 F9 A9 97 B6 8B 89 F4 5B EF D0 82 41 E8 87 FA D6 18 93 82 EB 9A A7 FB 25 9F 07 03 94 27 1C BC 61 C1 [0m [36m00000010[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000011[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000317[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000032[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000007[0m [140528082081536] [34mAPDU: 00 C0 00 00 C1 [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes [36m00000009[0m [140528082081536] -> 000000 6F 05 00 00 00 00 1E 00 00 00 00 C0 00 00 C1 [36m00000700[0m [140528082081536] <- 000000 80 C3 00 00 00 00 1E 00 00 00 E7 AC 83 06 AE 8B 07 CB EE 5A 94 BB 9C D2 0A 99 08 F5 F2 51 F3 18 A2 64 67 8A 21 AF C4 D0 60 28 7B D8 B6 7F 04 DC D3 66 DB 98 FC 25 18 0C AA B9 6F C1 DC 60 DA C4 2D 8A 24 50 A0 49 FF 6F 45 E4 69 B6 B3 8B 7A C0 80 AA 67 37 4F 8F 32 07 00 A5 AB 2F C4 AD 8A DA A3 D7 C2 E2 BA 2B 16 B2 18 E8 E9 BF 3C 86 21 8C B0 91 0F DF C7 C3 AB 24 47 53 36 EF F2 99 42 AB AA 64 36 27 82 4E 5C F5 1B 92 D5 D4 2B 4F 62 3D 7E 68 CF 23 A5 17 C0 B2 76 3B 53 CA BB E3 87 30 4F 7E 16 8E B1 22 25 37 1E 5A 70 3F C2 D3 79 D1 3E 5D 90 FC 7D 8F C3 6A 36 FB 8C AD A9 83 C2 46 8A 86 B0 75 CB F0 71 01 00 FE 00 90 00 [36m00000063[0m [140528082081536] [34mSW: E7 AC 83 06 AE 8B 07 CB EE 5A 94 BB 9C D2 0A 99 08 F5 F2 51 F3 18 A2 64 67 8A 21 AF C4 D0 60 28 7B D8 B6 7F 04 DC D3 66 DB 98 FC 25 18 0C AA B9 6F C1 DC 60 DA C4 2D 8A 24 50 A0 49 FF 6F 45 E4 69 B6 B3 8B 7A C0 80 AA 67 37 4F 8F 32 07 00 A5 AB 2F C4 AD 8A DA A3 D7 C2 E2 BA 2B 16 B2 18 E8 E9 BF 3C 86 21 8C B0 91 0F DF C7 C3 AB 24 47 53 36 EF F2 99 42 AB AA 64 36 27 82 4E 5C F5 1B 92 D5 D4 2B 4F 62 3D 7E 68 CF 23 A5 17 C0 B2 76 3B 53 CA BB E3 87 30 4F 7E 16 8E B1 22 25 37 1E 5A 70 3F C2 D3 79 D1 3E 5D 90 FC 7D 8F C3 6A 36 FB 8C AD A9 83 C2 46 8A 86 B0 75 CB F0 71 01 00 FE 00 90 00 [0m [36m00000008[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000587[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000033[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000006[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000009[0m [140528082081536] [34mAPDU: 00 CB 3F FF 05 5C 03 5F C1 0A 08 [0m [36m00000008[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000005[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes [36m00000011[0m [140528082081536] -> 000000 6F 0B 00 00 00 00 1F 00 00 00 00 CB 3F FF 05 5C 03 5F C1 0A 08 [36m00001121[0m [140528082081536] <- 000000 80 02 00 00 00 00 1F 00 00 00 6A 82 [36m00000023[0m [140528082081536] [34mSW: 6A 82 [0m [36m00000006[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000237[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000032[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000006[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000009[0m [140528082081536] [34mAPDU: 00 CB 3F FF 05 5C 03 5F C1 0B 08 [0m [36m00000008[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes [36m00000012[0m [140528082081536] -> 000000 6F 0B 00 00 00 00 20 00 00 00 00 CB 3F FF 05 5C 03 5F C1 0B 08 [36m00001001[0m [140528082081536] <- 000000 80 02 00 00 00 00 20 00 00 00 6A 82 [36m00000018[0m [140528082081536] [34mSW: 6A 82 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000211[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000030[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000006[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000009[0m [140528082081536] [34mAPDU: 00 CB 3F FF 05 5C 03 5F C1 01 08 [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes [36m00000010[0m [140528082081536] -> 000000 6F 0B 00 00 00 00 21 00 00 00 00 CB 3F FF 05 5C 03 5F C1 01 08 [36m00001015[0m [140528082081536] <- 000000 80 02 00 00 00 00 21 00 00 00 6A 82 [36m00000019[0m [140528082081536] [34mSW: 6A 82 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000280[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: END_TRANSACTION from client 14 [36m00000018[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000006[0m [140528082081536] winscard.c:1234:SCardEndTransaction() Status: 0x00000000 [36m00000004[0m [140528082081536] winscard.c:1237:SCardEndTransaction() UnrefReader() count was: 2 [36m00000005[0m [140528082081536] winscard_svc.c:579:ContextThread() END_TRANSACTION rv=0x0 for client 14 [36m00006944[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 [36m00000143[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_GET_READERS_STATE from client 14 [36m00000082[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000014[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000090[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000014[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000067[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000013[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000071[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000010[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000065[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000076[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000010[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000058[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000008[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000070[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000018[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000169[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000019[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000006[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000078[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000023[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000010[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 00 [0m [36m00000009[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000007[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000017[0m [140528082081536] -> 000000 6F 09 00 00 00 00 22 00 00 00 00 CB 3F FF 03 5C 01 7E 00 [36m00000963[0m [140528082081536] <- 000000 80 16 00 00 00 00 22 00 00 00 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [36m00000022[0m [140528082081536] [34mSW: 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [0m [36m00000008[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000006[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000098[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000023[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000006[0m [140528082081536] [34mAPDU: 00 20 00 80 [0m [36m00000007[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 4 bytes [36m00000014[0m [140528082081536] -> 000000 6F 04 00 00 00 00 23 00 00 00 00 20 00 80 [36m00000873[0m [140528082081536] <- 000000 80 02 00 00 00 00 23 00 00 00 63 C3 [36m00000010[0m [140528082081536] [34mSW: 63 C3 [0m [36m00000006[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000006[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000078[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: END_TRANSACTION from client 14 [36m00000017[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1234:SCardEndTransaction() Status: 0x00000000 [36m00000006[0m [140528082081536] winscard.c:1237:SCardEndTransaction() UnrefReader() count was: 2 [36m00000006[0m [140528082081536] winscard_svc.c:579:ContextThread() END_TRANSACTION rv=0x0 for client 14 [36m00008846[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000030[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000010[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000007[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000143[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000047[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000010[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 00 [0m [36m00000008[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000007[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000016[0m [140528082081536] -> 000000 6F 09 00 00 00 00 24 00 00 00 00 CB 3F FF 03 5C 01 7E 00 [36m00001126[0m [140528082081536] <- 000000 80 16 00 00 00 00 24 00 00 00 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [36m00000031[0m [140528082081536] [34mSW: 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [0m [36m00000009[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000009[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000222[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000034[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000009[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000014[0m [140528082081536] [34mAPDU: 00 20 00 80 08 39 50 2C 6A 2E 6B 77 76 [0m [36m00000009[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 13 bytes [36m00000019[0m [140528082081536] -> 000000 6F 0D 00 00 00 00 25 00 00 00 00 20 00 80 08 39 50 2C 6A 2E 6B 77 76 [36m00009002[0m [140528082081536] <- 000000 80 02 00 00 00 00 25 00 00 00 90 00 [36m00000028[0m [140528082081536] [34mSW: 90 00 [0m [36m00000009[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000009[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000475[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: END_TRANSACTION from client 14 [36m00000032[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000010[0m [140528082081536] winscard.c:1234:SCardEndTransaction() Status: 0x00000000 [36m00000006[0m [140528082081536] winscard.c:1237:SCardEndTransaction() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:579:ContextThread() END_TRANSACTION rv=0x0 for client 14 [36m00296274[0m [140528098866944] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) [36m00000032[0m [140528098866944] -> 000000 65 00 00 00 00 00 26 00 00 00 [36m00000414[0m [140528098866944] <- 000000 81 00 00 00 00 00 26 00 00 00 [36m00000022[0m [140528098866944] ifdhandler.c:1942:IFDHICCPresence() Card present [36m00000056[0m [140528098866944] [34mifdhandler.c:310:IFDHPolling() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) 600000 ms[0m [36m00000008[0m [140528098866944] ccid_usb.c:1306:InterruptRead() before (0) [36m01722861[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000038[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000010[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000008[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000009[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000156[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000030[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000012[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 00 [0m [36m00001021[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000016[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000019[0m [140528082081536] -> 000000 6F 09 00 00 00 00 27 00 00 00 00 CB 3F FF 03 5C 01 7E 00 [36m00001236[0m [140528082081536] <- 000000 80 16 00 00 00 00 27 00 00 00 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [36m00000035[0m [140528082081536] [34mSW: 7E 12 4F 0B A0 00 00 03 08 00 00 10 00 01 00 5F 2F 02 40 00 90 00 [0m [36m00000009[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000286[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000035[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000076[0m [140528082081536] [34mAPDU: 10 87 07 9A FF 7C 82 01 06 82 00 81 82 01 00 00 01 FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF 00 30 21 30 09 06 05 2B 0E 03 02 1A 05 00 04 14 2F 64 75 38 35 BE 69 D7 61 [0m [36m00000009[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 260 bytes [36m00000079[0m [140528082081536] -> 000000 6F 04 01 00 00 00 28 00 00 00 10 87 07 9A FF 7C 82 01 06 82 00 81 82 01 00 00 01 FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF 00 30 21 30 09 06 05 2B 0E 03 02 1A 05 00 04 14 2F 64 75 38 35 BE 69 D7 61 [36m00001114[0m [140528082081536] <- 000000 80 02 00 00 00 00 28 00 00 00 90 00 [36m00000030[0m [140528082081536] [34mSW: 90 00 [0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000009[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000237[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000035[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000011[0m [140528082081536] [34mAPDU: 00 87 07 9A 0B 5A 90 42 F1 51 56 0E 27 87 CB EB 00 [0m [36m00000009[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000006[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 17 bytes [36m00000014[0m [140528082081536] -> 000000 6F 11 00 00 00 00 29 00 00 00 00 87 07 9A 0B 5A 90 42 F1 51 56 0E 27 87 CB EB 00 [36m00250330[0m [140528082081536] <- 000000 80 00 00 00 00 00 29 80 01 00 [36m00000030[0m [140528082081536] commands.c:1552:CCID_Receive() Time extension requested: 0x01 [36m00000006[0m [140528082081536] commands.c:1558:CCID_Receive() New timeout: 3000 ms [36m00291639[0m [140528082081536] <- 000000 80 00 00 00 00 00 29 80 01 00 [36m00000015[0m [140528082081536] commands.c:1552:CCID_Receive() Time extension requested: 0x01 [36m00000003[0m [140528082081536] commands.c:1558:CCID_Receive() New timeout: 3000 ms [36m00291403[0m [140528082081536] <- 000000 80 00 00 00 00 00 29 80 01 00 [36m00000028[0m [140528082081536] commands.c:1552:CCID_Receive() Time extension requested: 0x01 [36m00000009[0m [140528082081536] commands.c:1558:CCID_Receive() New timeout: 3000 ms [36m00291603[0m [140528082081536] <- 000000 80 00 00 00 00 00 29 80 01 00 [36m00000028[0m [140528082081536] commands.c:1552:CCID_Receive() Time extension requested: 0x01 [36m00000009[0m [140528082081536] commands.c:1558:CCID_Receive() New timeout: 3000 ms [36m00329622[0m [140528082081536] <- 000000 80 03 00 00 00 00 29 00 00 00 7C 61 FF [36m00000032[0m [140528082081536] [34mSW: 7C 61 FF [0m [36m00000011[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000011[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000195[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000033[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000009[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000010[0m [140528082081536] [34mAPDU: 00 C0 00 00 FF [0m [36m00000009[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000007[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes [36m00000018[0m [140528082081536] -> 000000 6F 05 00 00 00 00 2A 00 00 00 00 C0 00 00 FF [36m00000923[0m [140528082081536] <- 000000 80 01 01 00 00 00 2A 00 00 00 82 01 04 82 82 01 00 74 3D 40 93 FE 59 05 59 37 BF 99 63 53 99 82 76 AB 86 C5 2B 8B C9 F2 B4 C4 A5 A8 C7 11 03 F7 AC 6B 69 5C 23 46 43 AE 2C 2C 77 A2 1F 52 A3 3A 9D 5B 2D 75 65 16 60 9E E3 9D 0D B1 8A DF 16 74 46 D5 C7 00 C8 AB 3B D9 ED B0 29 9F 43 FD AC CD 7B E2 16 B2 67 1C BD DD FC DC 30 1E 8F 1E 0A 4E 52 06 E3 F2 D7 B8 EC 08 7F 06 6E CC 13 13 C9 89 65 BA 9B 55 C6 09 34 6A DE 12 08 69 DD 06 D6 84 E0 F8 09 9E 4F DB 1B 87 D1 15 20 E8 C7 7A 3C 58 37 BE 1A 4E 12 70 20 24 F2 36 AE 27 CA 47 CE E2 29 5F 7B C4 D8 AB AB 61 C5 A0 BE 11 A9 CE 5C 82 5A F7 DE 47 A0 A5 44 7F B2 4B 3B 01 62 05 C7 C2 1D D4 31 D1 45 FB A8 C7 AE 2F F2 B6 B3 79 17 76 25 60 EE 48 7C 9D C7 0D 45 89 45 B4 C0 35 20 02 E9 92 9D F2 C8 B8 1C 3A 2C EC 64 70 C6 AC E6 43 3D B5 91 F9 26 D3 90 EA 35 61 08 [36m00000149[0m [140528082081536] [34mSW: 82 01 04 82 82 01 00 74 3D 40 93 FE 59 05 59 37 BF 99 63 53 99 82 76 AB 86 C5 2B 8B C9 F2 B4 C4 A5 A8 C7 11 03 F7 AC 6B 69 5C 23 46 43 AE 2C 2C 77 A2 1F 52 A3 3A 9D 5B 2D 75 65 16 60 9E E3 9D 0D B1 8A DF 16 74 46 D5 C7 00 C8 AB 3B D9 ED B0 29 9F 43 FD AC CD 7B E2 16 B2 67 1C BD DD FC DC 30 1E 8F 1E 0A 4E 52 06 E3 F2 D7 B8 EC 08 7F 06 6E CC 13 13 C9 89 65 BA 9B 55 C6 09 34 6A DE 12 08 69 DD 06 D6 84 E0 F8 09 9E 4F DB 1B 87 D1 15 20 E8 C7 7A 3C 58 37 BE 1A 4E 12 70 20 24 F2 36 AE 27 CA 47 CE E2 29 5F 7B C4 D8 AB AB 61 C5 A0 BE 11 A9 CE 5C 82 5A F7 DE 47 A0 A5 44 7F B2 4B 3B 01 62 05 C7 C2 1D D4 31 D1 45 FB A8 C7 AE 2F F2 B6 B3 79 17 76 25 60 EE 48 7C 9D C7 0D 45 89 45 B4 C0 35 20 02 E9 92 9D F2 C8 B8 1C 3A 2C EC 64 70 C6 AC E6 43 3D B5 91 F9 26 D3 90 EA 35 61 08 [0m [36m00000014[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000012[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000275[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000040[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000010[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000062[0m [140528082081536] [34mAPDU: 00 C0 00 00 08 [0m [36m00000013[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000008[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes [36m00000016[0m [140528082081536] -> 000000 6F 05 00 00 00 00 2B 00 00 00 00 C0 00 00 08 [36m00000425[0m [140528082081536] <- 000000 80 0A 00 00 00 00 2B 00 00 00 1C 17 D0 EE 41 0E 69 BE 90 00 [36m00000029[0m [140528082081536] [34mSW: 1C 17 D0 EE 41 0E 69 BE 90 00 [0m [36m00000009[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000011[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 14 [36m00000140[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: END_TRANSACTION from client 14 [36m00000022[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000009[0m [140528082081536] winscard.c:1234:SCardEndTransaction() Status: 0x00000000 [36m00000006[0m [140528082081536] winscard.c:1237:SCardEndTransaction() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:579:ContextThread() END_TRANSACTION rv=0x0 for client 14 [36m03727294[0m [140528118052800] winscard_msg_srv.c:255:ProcessEventsServer() Common channel packet arrival [36m00000014[0m [140528118052800] winscard_msg_srv.c:267:ProcessEventsServer() ProcessCommonChannelRequest detects: 15 [36m00000003[0m [140528118052800] pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 15 [36m00000092[0m [140528073688832] winscard_svc.c:340:ContextThread() Authorized PC/SC client [36m00000004[0m [140528073688832] winscard_svc.c:344:ContextThread() Thread is started: dwClientID=15, threadContext @0x55921e843850 [36m00000020[0m [140528073688832] winscard_svc.c:362:ContextThread() Received command: CMD_VERSION from client 15 [36m00000003[0m [140528073688832] winscard_svc.c:374:ContextThread() Client is protocol version 4:4 [36m00000001[0m [140528073688832] winscard_svc.c:394:ContextThread() CMD_VERSION rv=0x0 for client 15 [36m00000084[0m [140528073688832] winscard_svc.c:362:ContextThread() Received command: ESTABLISH_CONTEXT from client 15 [36m00000006[0m [140528073688832] winscard.c:215:SCardEstablishContext() Establishing Context: 0x62255DA8 [36m00000002[0m [140528073688832] winscard_svc.c:459:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 15 [36m00000098[0m [140528073688832] winscard_svc.c:362:ContextThread() Received command: CMD_GET_READERS_STATE from client 15 [36m00000078[0m [140528073688832] winscard_svc.c:362:ContextThread() Received command: CMD_GET_READERS_STATE from client 15 [36m00000125[0m [140528118052800] winscard_msg_srv.c:255:ProcessEventsServer() Common channel packet arrival [36m00000009[0m [140528118052800] winscard_msg_srv.c:267:ProcessEventsServer() ProcessCommonChannelRequest detects: 16 [36m00000001[0m [140528118052800] pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 16 [36m00000026[0m [140528065296128] winscard_svc.c:340:ContextThread() Authorized PC/SC client [36m00000004[0m [140528065296128] winscard_svc.c:344:ContextThread() Thread is started: dwClientID=16, threadContext @0x55921e8430b0 [36m00000006[0m [140528065296128] winscard_svc.c:362:ContextThread() Received command: CMD_VERSION from client 16 [36m00000003[0m [140528065296128] winscard_svc.c:374:ContextThread() Client is protocol version 4:4 [36m00000002[0m [140528065296128] winscard_svc.c:394:ContextThread() CMD_VERSION rv=0x0 for client 16 [36m00000065[0m [140528065296128] winscard_svc.c:362:ContextThread() Received command: ESTABLISH_CONTEXT from client 16 [36m00000004[0m [140528065296128] winscard.c:215:SCardEstablishContext() Establishing Context: 0x15ACC2F3 [36m00000002[0m [140528065296128] winscard_svc.c:459:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 16 [36m00000036[0m [140528065296128] winscard_svc.c:362:ContextThread() Received command: CONNECT from client 16 [36m00000004[0m [140528065296128] winscard_svc.c:497:ContextThread() Authorized client for 'Yubico YubiKey OTP+FIDO+CCID 00 00' [36m00000001[0m [140528065296128] winscard.c:259:SCardConnect() Attempting Connect to Yubico YubiKey OTP+FIDO+CCID 00 00 using protocol: 3 [36m00000002[0m [140528065296128] readerfactory.c:821:RFReaderInfo() RefReader() count was: 1 [36m00000002[0m [140528065296128] winscard.c:352:SCardConnect() powerState: POWER_STATE_IN_USE [36m00000001[0m [140528065296128] winscard.c:431:SCardConnect() Active Protocol: T=1 [36m00000017[0m [140528065296128] winscard.c:456:SCardConnect() hCard Identity: 52afbb10 [36m00000013[0m [140528065296128] winscard.c:518:SCardConnect() UnrefReader() count was: 2 [36m00000002[0m [140528065296128] winscard_svc.c:511:ContextThread() CONNECT rv=0x0 for client 16 [36m00000203[0m [140528065296128] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 16 [36m00000006[0m [140528065296128] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000002[0m [140528065296128] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000003[0m [140528065296128] [34mAPDU: 00 A4 04 00 08 A0 00 00 05 27 47 11 17 [0m [36m00000003[0m [140528065296128] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000002[0m [140528065296128] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 13 bytes [36m00000004[0m [140528065296128] -> 000000 6F 0D 00 00 00 00 2C 00 00 00 00 A4 04 00 08 A0 00 00 05 27 47 11 17 [36m00001028[0m [140528065296128] <- 000000 80 20 00 00 00 00 2C 00 00 00 56 69 72 74 75 61 6C 20 6D 67 72 20 2D 20 46 57 20 76 65 72 73 69 6F 6E 20 34 2E 33 2E 35 90 00 [36m00000008[0m [140528065296128] [34mSW: 56 69 72 74 75 61 6C 20 6D 67 72 20 2D 20 46 57 20 76 65 72 73 69 6F 6E 20 34 2E 33 2E 35 90 00 [0m [36m00000002[0m [140528065296128] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000002[0m [140528065296128] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 16 [36m00000068[0m [140528065296128] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 16 [36m00000006[0m [140528065296128] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000001[0m [140528065296128] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000002[0m [140528065296128] [34mAPDU: 00 1D 00 00 00 [0m [36m00000002[0m [140528065296128] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000001[0m [140528065296128] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes [36m00000003[0m [140528065296128] -> 000000 6F 05 00 00 00 00 2D 00 00 00 00 1D 00 00 00 [36m00000916[0m [140528065296128] <- 000000 80 0F 00 00 00 00 2D 00 00 00 0C 01 01 FF 02 04 00 56 3C 20 03 01 3F 90 00 [36m00000018[0m [140528065296128] [34mSW: 0C 01 01 FF 02 04 00 56 3C 20 03 01 3F 90 00 [0m [36m00000001[0m [140528065296128] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000002[0m [140528065296128] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 16 [36m00000095[0m [140528065296128] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 16 [36m00000005[0m [140528065296128] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000002[0m [140528065296128] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000002[0m [140528065296128] [34mAPDU: 00 A4 04 00 07 A0 00 00 05 27 20 01 [0m [36m00000001[0m [140528065296128] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000002[0m [140528065296128] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 12 bytes [36m00000004[0m [140528065296128] -> 000000 6F 0C 00 00 00 00 2E 00 00 00 00 A4 04 00 07 A0 00 00 05 27 20 01 [36m00001081[0m [140528065296128] <- 000000 80 0C 00 00 00 00 2E 00 00 00 04 03 05 01 05 00 06 0F 00 00 90 00 [36m00000007[0m [140528065296128] [34mSW: 04 03 05 01 05 00 06 0F 00 00 90 00 [0m [36m00000002[0m [140528065296128] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000002[0m [140528065296128] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x0 for client 16 [36m00000326[0m [140528065296128] winscard_svc.c:362:ContextThread() Received command: DISCONNECT from client 16 [36m00000007[0m [140528065296128] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000002[0m [140528065296128] winscard.c:881:SCardDisconnect() Active Contexts: 2 [36m00000002[0m [140528065296128] winscard.c:882:SCardDisconnect() dwDisposition: 2 [36m00000002[0m [140528065296128] ifdhandler.c:1821:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0) [36m00000003[0m [140528065296128] -> 000000 65 00 00 00 00 00 2F 00 00 00 [36m00000268[0m [140528065296128] <- 000000 81 00 00 00 00 00 2F 00 00 00 [36m00000020[0m [140528065296128] ifdhandler.c:1942:IFDHICCPresence() Card present [36m00000002[0m [140528065296128] [34mifdhandler.c:1154:IFDHPowerICC() action: PowerDown, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000004[0m [140528065296128] -> 000000 63 00 00 00 00 00 30 00 00 00 [36m00000205[0m [140528065296128] <- 000000 81 00 00 00 00 00 30 02 00 00 [36m00000006[0m [140528065296128] winscard.c:904:SCardDisconnect() powerState: POWER_STATE_UNPOWERED [36m00000002[0m [140528065296128] winscard.c:1043:SCardDisconnect() UnrefReader() count was: 2 [36m00000002[0m [140528065296128] winscard_svc.c:548:ContextThread() DISCONNECT rv=0x0 for client 16 [36m00000112[0m [140528065296128] winscard_svc.c:362:ContextThread() Received command: RELEASE_CONTEXT from client 16 [36m00000005[0m [140528065296128] winscard.c:229:SCardReleaseContext() Releasing Context: 0x15ACC2F3 [36m00000002[0m [140528065296128] winscard_svc.c:474:ContextThread() RELEASE_CONTEXT rv=0x0 for client 16 [36m00000012[0m [140528065296128] winscard_svc.c:354:ContextThread() Client die: 16 [36m00000006[0m [140528065296128] winscard_svc.c:1055:MSGCleanupClient() Thread is stopping: dwClientID=16, threadContext @0x55921e8430b0 [36m00000001[0m [140528065296128] winscard_svc.c:1061:MSGCleanupClient() Freeing SCONTEXT @0x55921e8430b0 [36m00018602[0m [140528073688832] winscard_svc.c:354:ContextThread() Client die: 15 [36m00000016[0m [140528073688832] winscard.c:229:SCardReleaseContext() Releasing Context: 0x62255DA8 [36m00000003[0m [140528073688832] winscard_svc.c:1055:MSGCleanupClient() Thread is stopping: dwClientID=15, threadContext @0x55921e843850 [36m00000002[0m [140528073688832] winscard_svc.c:1061:MSGCleanupClient() Freeing SCONTEXT @0x55921e843850 [36m01365262[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000039[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000009[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000010[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x80100068 for client 14 [36m00000133[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000024[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000186[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000025[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000135[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000018[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000117[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000013[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000140[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: RECONNECT from client 14 [36m00000022[0m [140528082081536] winscard.c:532:SCardReconnect() Attempting reconnect to token. [36m00000008[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000009[0m [140528082081536] [34mprothandler.c:108:PHSetProtocol() Attempting PTS to T=1[0m [36m00000011[0m [140528082081536] [34mifdhandler.c:695:IFDHSetProtocolParameters() protocol T=1, usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000007[0m [140528082081536] ifdhandler.c:709:IFDHSetProtocolParameters() Timeout: 3000 ms [36m00000006[0m [140528082081536] winscard.c:720:SCardReconnect() powerState: POWER_STATE_IN_USE [36m00000007[0m [140528082081536] winscard.c:734:SCardReconnect() Active Protocol: T=1 [36m00000006[0m [140528082081536] winscard.c:824:SCardReconnect() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:530:ContextThread() RECONNECT rv=0x0 for client 14 [36m00000055[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: BEGIN_TRANSACTION from client 14 [36m00000017[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1082:SCardBeginTransaction() Status: 0x00000000 [36m00000006[0m [140528082081536] winscard.c:1085:SCardBeginTransaction() UnrefReader() count was: 2 [36m00000006[0m [140528082081536] winscard_svc.c:563:ContextThread() BEGIN_TRANSACTION rv=0x0 for client 14 [36m00000072[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000024[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000008[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000012[0m [140528082081536] [34mAPDU: 00 CB 3F FF 03 5C 01 7E 00 [0m [36m00000009[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000008[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 9 bytes [36m00000017[0m [140528082081536] -> 000000 6F 09 00 00 00 00 31 00 00 00 00 CB 3F FF 03 5C 01 7E 00 [36m00000298[0m [140528082081536] <- 000000 81 00 00 00 00 00 31 40 FC 00 [36m00000011[0m [140528082081536] [35mcommands.c:1523:CCID_Receive Overrun error[0m [36m00000006[0m [140528082081536] [34mSW: [0m [36m00000007[0m [140528082081536] [35mifdwrapper.c:543:IFDTransmit() Card not transacted: 612[0m [36m00000007[0m [140528082081536] [35mwinscard.c:1620:SCardTransmit() Card not transacted: 0x80100016[0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000008[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x80100016 for client 14 [36m00000058[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000010[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000069[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000014[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000051[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000050[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000048[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000058[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000012[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000048[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000050[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000067[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: TRANSMIT from client 14 [36m00000023[0m [140528082081536] readerfactory.c:848:RFReaderInfoById() RefReader() count was: 1 [36m00000007[0m [140528082081536] winscard.c:1595:SCardTransmit() Send Protocol: T=1 [36m00000015[0m [140528082081536] [34mAPDU: 00 A4 04 00 09 A0 00 00 03 08 00 00 10 00 00 [0m [36m00000008[0m [140528082081536] [34mifdhandler.c:1303:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/003/030 (lun: 0)[0m [36m00000007[0m [140528082081536] commands.c:1623:CmdXfrBlockAPDU_extended() T=0 (extended): 15 bytes [36m00000020[0m [140528082081536] -> 000000 6F 0F 00 00 00 00 32 00 00 00 00 A4 04 00 09 A0 00 00 03 08 00 00 10 00 00 [36m00000214[0m [140528082081536] <- 000000 81 00 00 00 00 00 32 40 FC 00 [36m00000009[0m [140528082081536] [35mcommands.c:1523:CCID_Receive Overrun error[0m [36m00000006[0m [140528082081536] [34mSW: [0m [36m00000006[0m [140528082081536] [35mifdwrapper.c:543:IFDTransmit() Card not transacted: 612[0m [36m00000006[0m [140528082081536] [35mwinscard.c:1620:SCardTransmit() Card not transacted: 0x80100016[0m [36m00000007[0m [140528082081536] winscard.c:1640:SCardTransmit() UnrefReader() count was: 2 [36m00000007[0m [140528082081536] winscard_svc.c:683:ContextThread() TRANSMIT rv=0x80100016 for client 14 [36m00000056[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000010[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000059[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000049[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000049[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000074[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000021[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000071[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14 [36m00000104[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 14 [36m00000009[0m [140528082081536] winscard_svc.c:832:MSGSendReaderStates() Send reader states: 14 [36m00000051[0m [140528082081536] winscard_svc.c:362:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 14 [36m00000010[0m [140528082081536] winscard_svc.c:440:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 14