00000000 debuglog.c:390:DebugLogSetLevel() debug level=debug
00000010 debuglog.c:411:DebugLogSetCategory() Debug options: APDU
00000001 [140412453652480] pcscdaemon.c:356:main() Force colored logs
00000073 [140412453652480] configfile.l:293:DBGetReaderListDir() Parsing conf directory: /etc/reader.conf.d
00000022 [140412453652480] configfile.l:329:DBGetReaderListDir() Skipping non regular file: .
00000002 [140412453652480] configfile.l:329:DBGetReaderListDir() Skipping non regular file: ..
00000001 [140412453652480] configfile.l:369:DBGetReaderList() Parsing conf file: /etc/reader.conf.d/ifdnfc.pacsave
00000019 [140412453652480] configfile.l:369:DBGetReaderList() Parsing conf file: /etc/reader.conf.d/libccidtwin
00000020 [140412453652480] pcscdaemon.c:666:main() pcsc-lite 1.9.9 daemon ready.
00006128 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000089 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000120 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0A5C, PID: 0x4500, path: /dev/bus/usb/001/007
00000113 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x05AC, PID: 0x828D, path: /dev/bus/usb/001/014
00000111 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x05AC, PID: 0x828D, path: /dev/bus/usb/001/014
00000110 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x05AC, PID: 0x828D, path: /dev/bus/usb/001/014
00000110 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x05AC, PID: 0x828D, path: /dev/bus/usb/001/014
00000102 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0A5C, PID: 0x4500, path: /dev/bus/usb/001/007
00000072 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000080 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0B05, PID: 0x1872, path: /dev/bus/usb/001/008
00000072 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000078 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x056A, PID: 0x033B, path: /dev/bus/usb/001/002
00000081 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x056A, PID: 0x033B, path: /dev/bus/usb/001/002
00000079 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x056A, PID: 0x033B, path: /dev/bus/usb/001/002
00000072 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000079 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0424, PID: 0x2134, path: /dev/bus/usb/001/003
00000086 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x3297, PID: 0x4974, path: /dev/bus/usb/001/005
00000084 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x3297, PID: 0x4974, path: /dev/bus/usb/001/005
00000085 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x3297, PID: 0x4974, path: /dev/bus/usb/001/005
00000078 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0424, PID: 0x2134, path: /dev/bus/usb/001/003
00000071 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000079 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0909, PID: 0x004D, path: /dev/bus/usb/001/004
00000078 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0909, PID: 0x004D, path: /dev/bus/usb/001/004
00000079 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0909, PID: 0x004D, path: /dev/bus/usb/001/004
00000077 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0909, PID: 0x004D, path: /dev/bus/usb/001/004
00000072 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/001/001
00000079 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x2822, path: /dev/bus/usb/001/006
00000086 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x2822, path: /dev/bus/usb/001/009
00000092 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x072F, PID: 0x2200, path: /dev/bus/usb/001/013
00000003 [140412453652480] hotplug_libudev.c:421:HPAddDevice() Adding USB device: ACS ACR122U
00000022 [140412453652480] readerfactory.c:1093:RFInitializeReader() Attempting startup of ACS ACR122U 00 00 using /usr/lib/pcsc/drivers/ifd-acsccid.bundle/Contents/Linux/libacsccid.so
00000178 [140412453652480] readerfactory.c:968:RFBindFunctions() Loading IFD Handler 3.0
00000011 [140412453652480] ifdhandler.c:3027:init_driver() Driver version: 1.1.9
00000169 [140412453652480] ifdhandler.c:3044:init_driver() LogLevel: 0x0003
00000002 [140412453652480] ifdhandler.c:3055:init_driver() DriverOptions: 0x0000
00000002 [140412453652480] ifdhandler.c:3063:init_driver() ACSDriverOptions: 0x0003
00000016 [140412453652480] ifdhandler.c:3071:init_driver() ACR38CardVoltage: 0
00000001 [140412453652480] ifdhandler.c:3079:init_driver() ACR38CardType: 0
00000015 [140412453652480] ifdhandler.c:3092:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000002 [140412453652480] ifdhandler.c:162:CreateChannelByNameOrChannel() Lun: 0, device: usb:072f/2200:libudev:0:/dev/bus/usb/001/013
00000002 [140412453652480] ccid_usb.c:264:OpenUSBByName() Reader index: 0, Device: usb:072f/2200:libudev:0:/dev/bus/usb/001/013
00000003 [140412453652480] ccid_usb.c:296:OpenUSBByName() interface_number: 0
00000001 [140412453652480] ccid_usb.c:297:OpenUSBByName() usb bus/device: 1/13
00000001 [140412453652480] ccid_usb.c:329:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-acsccid.bundle/Contents/Info.plist
00000116 [140412453652480] ccid_usb.c:347:OpenUSBByName() ifdManufacturerString: Advanced Card Systems Ltd.
00000002 [140412453652480] ccid_usb.c:348:OpenUSBByName() ifdProductString: ACS CCID driver
00000001 [140412453652480] ccid_usb.c:349: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.
00009038 [140412453652480] ccid_usb.c:433:OpenUSBByName() Try device: 1/13
00000003 [140412453652480] ccid_usb.c:443:OpenUSBByName() vid/pid : 072F/2200
00000001 [140412453652480] ccid_usb.c:538:OpenUSBByName() Checking device: 1/13
00000001 [140412453652480] ccid_usb.c:652:OpenUSBByName() Trying to open USB bus/device: 1/13
00124581 [140412453652480] ccid_usb.c:765:OpenUSBByName() Found Vendor/Product: 072F/2200 (ACS ACR122U)
00000014 [140412453652480] ccid_usb.c:767:OpenUSBByName() Using USB bus/device: 1/13
00000006 [140412453652480] ccid_usb.c:953:OpenUSBByName() bNumDataRatesSupported is 0
00000628 [140412453652480] -> 000000 63 00 00 00 00 00 00 00 00 00 
00000011 [140412436547264] ccid_usb.c:1965:Multi_PollingProc() Multi_PollingProc (1/13): thread starting
00000018 [140412436547264] ccid_usb.c:1972:Multi_PollingProc() Multi_PollingProc (1/13): waiting
00009460 [140412436547264] ccid_usb.c:2053:Multi_PollingProc() Multi_PollingProc (1/13): OK
00000018 [140412436547264] NotifySlotChange: 50 03 
00000010 [140412436547264] ccid_usb.c:2105:Multi_PollingProc() 1/13: Slot 0: 0x00
00000007 [140412436547264] ccid_usb.c:2129:Multi_PollingProc() slot 0 status: 3
00000007 [140412436547264] ccid_usb.c:2131:Multi_PollingProc() ICC present, status changed
00000005 [140412436547264] ccid_usb.c:2129:Multi_PollingProc() slot 1 status: 0
00000006 [140412436547264] ccid_usb.c:2131:Multi_PollingProc() ICC absent, no change
00000005 [140412436547264] ccid_usb.c:2129:Multi_PollingProc() slot 2 status: 0
00000006 [140412436547264] ccid_usb.c:2131:Multi_PollingProc() ICC absent, no change
00000005 [140412436547264] ccid_usb.c:2129:Multi_PollingProc() slot 3 status: 0
00000005 [140412436547264] ccid_usb.c:2131:Multi_PollingProc() ICC absent, no change
00000007 [140412436547264] ccid_usb.c:2153:Multi_PollingProc() Multi_PollingProc (1/13): Broadcast to slot(s)
00000006 [140412436547264] ccid_usb.c:1972:Multi_PollingProc() Multi_PollingProc (1/13): waiting
00026750 [140412453652480] <- 000000 81 00 00 00 00 00 00 02 81 00 
00005063 [140412436547264] ccid_usb.c:2053:Multi_PollingProc() Multi_PollingProc (1/13): OK
00000005 [140412436547264] NotifySlotChange: 50 02 
00000002 [140412436547264] ccid_usb.c:2105:Multi_PollingProc() 1/13: Slot 0: 0x02
00000002 [140412436547264] ccid_usb.c:2129:Multi_PollingProc() slot 0 status: 2
00000002 [140412436547264] ccid_usb.c:2131:Multi_PollingProc() ICC absent, status changed
00000001 [140412436547264] ccid_usb.c:2129:Multi_PollingProc() slot 1 status: 0
00000001 [140412436547264] ccid_usb.c:2131:Multi_PollingProc() ICC absent, no change
00000001 [140412436547264] ccid_usb.c:2129:Multi_PollingProc() slot 2 status: 0
00000002 [140412436547264] ccid_usb.c:2131:Multi_PollingProc() ICC absent, no change
00000001 [140412436547264] ccid_usb.c:2129:Multi_PollingProc() slot 3 status: 0
00000001 [140412436547264] ccid_usb.c:2131:Multi_PollingProc() ICC absent, no change
00000001 [140412436547264] ccid_usb.c:2153:Multi_PollingProc() Multi_PollingProc (1/13): Broadcast to slot(s)
00000002 [140412436547264] ccid_usb.c:1972:Multi_PollingProc() Multi_PollingProc (1/13): waiting
00995054 [140412453652480] ccid_usb.c:2252:Multi_InterruptRead() Multi_InterruptRead (0), timeout: 100 ms
00100159 [140412453652480] ccid_usb.c:2304:Multi_InterruptRead() Multi_InterruptRead (0), status=2
00000026 [140412453652480] -> 000000 65 00 00 00 00 00 01 00 00 00 
00018887 [140412453652480] <- 000000 81 00 00 00 00 00 01 02 81 00 
00000021 [140412453652480] ifdhandler.c:348:CreateChannelByNameOrChannel() dwFeatures: 0x00020040
00000008 [140412453652480] ifdhandler.c:349:CreateChannelByNameOrChannel() wLcdLayout: 0x0000
00000007 [140412453652480] ifdhandler.c:350:CreateChannelByNameOrChannel() bPINSupport: 0x00
00000005 [140412453652480] ifdhandler.c:351:CreateChannelByNameOrChannel() dwMaxCCIDMessageLength: 271
00000005 [140412453652480] ifdhandler.c:352:CreateChannelByNameOrChannel() dwMaxIFSD: 256
00000006 [140412453652480] ifdhandler.c:353:CreateChannelByNameOrChannel() dwDefaultClock: 4000
00000006 [140412453652480] ifdhandler.c:354:CreateChannelByNameOrChannel() dwMaxDataRate: 250000
00000005 [140412453652480] ifdhandler.c:355:CreateChannelByNameOrChannel() bMaxSlotIndex: 0
00000009 [140412453652480] ifdhandler.c:356:CreateChannelByNameOrChannel() bCurrentSlotIndex: 0
00000006 [140412453652480] ifdhandler.c:357:CreateChannelByNameOrChannel() bInterfaceProtocol: 0x00
00000005 [140412453652480] ifdhandler.c:358:CreateChannelByNameOrChannel() bNumEndpoints: 3
00000005 [140412453652480] ifdhandler.c:359:CreateChannelByNameOrChannel() bVoltageSupport: 0x07
00000010 [140412453652480] ifdhandler.c:524:IFDHGetCapabilities() tag: 0xFB3, usb:072f/2200:libudev:0:/dev/bus/usb/001/013 (lun: 0)
00000007 [140412453652480] readerfactory.c:398:RFAddReader() Using the reader polling thread
00000007 [140412453652480] ifdhandler.c:2788:IFDHICCPresence() usb:072f/2200:libudev:0:/dev/bus/usb/001/013 (lun: 0)
00000010 [140412453652480] -> 000000 65 00 00 00 00 00 02 00 00 00 
00000405 [140412453652480] <- 000000 81 00 00 00 00 00 02 02 81 00 
00000018 [140412453652480] ifdhandler.c:3007:IFDHICCPresence() Card absent
00000132 [140412453652480] ifdhandler.c:524:IFDHGetCapabilities() tag: 0xFAE, usb:072f/2200:libudev:0:/dev/bus/usb/001/013 (lun: 0)
00000010 [140412453652480] ifdhandler.c:633:IFDHGetCapabilities() Reader supports 1 slot(s)
00000086 [140412428154560] ifdhandler.c:2788:IFDHICCPresence() usb:072f/2200:libudev:0:/dev/bus/usb/001/013 (lun: 0)
00000034 [140412428154560] -> 000000 65 00 00 00 00 00 03 00 00 00 
00000468 [140412428154560] <- 000000 81 00 00 00 00 00 03 02 81 00 
00000020 [140412428154560] ifdhandler.c:3007:IFDHICCPresence() Card absent
00000010 [140412428154560] ifdhandler.c:2788:IFDHICCPresence() usb:072f/2200:libudev:0:/dev/bus/usb/001/013 (lun: 0)
00000010 [140412428154560] -> 000000 65 00 00 00 00 00 04 00 00 00 
00000436 [140412428154560] <- 000000 81 00 00 00 00 00 04 02 81 00 
00000020 [140412428154560] ifdhandler.c:3007:IFDHICCPresence() Card absent
00000010 [140412428154560] ifdhandler.c:453:IFDHPolling() usb:072f/2200:libudev:0:/dev/bus/usb/001/013 (lun: 0) 600000 ms
00000008 [140412428154560] ccid_usb.c:2252:Multi_InterruptRead() Multi_InterruptRead (0), timeout: 600000 ms
00000098 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x2822, path: /dev/bus/usb/001/009
00000475 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x2822, path: /dev/bus/usb/001/006
00000481 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x174C, PID: 0x2364, path: /dev/bus/usb/001/011
00000475 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x2822, path: /dev/bus/usb/001/006
00000776 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/002/001
00000414 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/002/001
00000437 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0424, PID: 0x5534, path: /dev/bus/usb/002/002
00000473 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x046D, PID: 0x085E, path: /dev/bus/usb/002/004
00000464 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x046D, PID: 0x085E, path: /dev/bus/usb/002/004
00000464 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x046D, PID: 0x085E, path: /dev/bus/usb/002/004
00000500 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x046D, PID: 0x085E, path: /dev/bus/usb/002/004
00000464 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x046D, PID: 0x085E, path: /dev/bus/usb/002/004
00000459 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x046D, PID: 0x085E, path: /dev/bus/usb/002/004
00000427 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0424, PID: 0x5534, path: /dev/bus/usb/002/002
00000394 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/002/001
00000336 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x0822, path: /dev/bus/usb/002/003
00000112 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x0822, path: /dev/bus/usb/002/005
00000147 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x0BDA, PID: 0x0306, path: /dev/bus/usb/002/007
00000140 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x0822, path: /dev/bus/usb/002/005
00000095 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x0822, path: /dev/bus/usb/002/003
00000172 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x174C, PID: 0x2362, path: /dev/bus/usb/002/006
00000081 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x0822, path: /dev/bus/usb/002/003
00000088 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x174C, PID: 0x2362, path: /dev/bus/usb/002/008
00000079 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x2109, PID: 0x0822, path: /dev/bus/usb/002/003
00000206 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/003/001
00000168 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/004/001
00000165 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0002, path: /dev/bus/usb/005/001
00000170 [140412453652480] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0003, path: /dev/bus/usb/006/001
01995453 [140412345906880] hotplug_libudev.c:647:HPEstablishUSBNotifications() USB Device add
00000130 [140412345906880] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1050, PID: 0x0407, path: /dev/bus/usb/001/024
00000006 [140412345906880] hotplug_libudev.c:421:HPAddDevice() Adding USB device: Yubico YubiKey OTP+FIDO+CCID
00000057 [140412345906880] readerfactory.c:1093:RFInitializeReader() Attempting startup of Yubico YubiKey OTP+FIDO+CCID 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
00000106 [140412345906880] readerfactory.c:968:RFBindFunctions() Loading IFD Handler 3.0
00000026 [140412345906880] ifdhandler.c:2102:init_driver() Driver version: 1.5.2
00000520 [140412345906880] ifdhandler.c:2124:init_driver() LogLevel: 0x0003
00000003 [140412345906880] ifdhandler.c:2135:init_driver() DriverOptions: 0x0000
00000138 [140412345906880] ifdhandler.c:2148:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000002 [140412345906880] ifdhandler.c:111:CreateChannelByNameOrChannel() Lun: 0, device: usb:1050/0407:libudev:0:/dev/bus/usb/001/024
00000002 [140412345906880] ccid_usb.c:254:OpenUSBByName() Reader index: 0, Device: usb:1050/0407:libudev:0:/dev/bus/usb/001/024
00000003 [140412345906880] ccid_usb.c:286:OpenUSBByName() interface_number: 0
00000001 [140412345906880] ccid_usb.c:287:OpenUSBByName() usb bus/device: 1/24
00000002 [140412345906880] ccid_usb.c:324:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
00000413 [140412345906880] ccid_usb.c:342:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr)
00000003 [140412345906880] ccid_usb.c:343:OpenUSBByName() ifdProductString: Generic CCID driver
00000001 [140412345906880] ccid_usb.c:344: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.
00011114 [140412345906880] ccid_usb.c:428:OpenUSBByName() Try device: 1/24
00000005 [140412345906880] ccid_usb.c:438:OpenUSBByName() vid/pid : 1050/0407
00000002 [140412345906880] ccid_usb.c:513:OpenUSBByName() Checking device: 1/24
00000001 [140412345906880] ccid_usb.c:584:OpenUSBByName() Trying to open USB bus/device: 1/24
00000033 [140412345906880] ccid_usb.c:667:OpenUSBByName() Found interface 2 but expecting 0
00000002 [140412345906880] ccid_usb.c:669:OpenUSBByName() Wrong interface for USB device 1/24. Checking next one.
00000153 [140412345906880] ccid_usb.c:205:close_libusb_if_needed() libusb_exit
00000008 [140412345906880] ccid_usb.c:835:OpenUSBByName() Device not found?
00000002 [140412345906880] ifdhandler.c:156:CreateChannelByNameOrChannel() failed
00000001 [140412345906880] readerfactory.c:1134:RFInitializeReader() Open Port 0x200001 Failed (usb:1050/0407:libudev:0:/dev/bus/usb/001/024)
00000002 [140412345906880] readerfactory.c:378:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
00000002 [140412345906880] readerfactory.c:628:RFRemoveReader() UnrefReader() count was: 1
00000001 [140412345906880] readerfactory.c:1147:RFUnInitializeReader() Attempting shutdown of Yubico YubiKey OTP+FIDO+CCID 00 00.
00000001 [140412345906880] readerfactory.c:1005:RFUnloadReader() Unloading reader driver.
00000047 [140412345906880] hotplug_libudev.c:647:HPEstablishUSBNotifications() USB Device add
00000154 [140412345906880] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1050, PID: 0x0407, path: /dev/bus/usb/001/024
00000004 [140412345906880] hotplug_libudev.c:421:HPAddDevice() Adding USB device: Yubico YubiKey OTP+FIDO+CCID
00000023 [140412345906880] readerfactory.c:1093:RFInitializeReader() Attempting startup of Yubico YubiKey OTP+FIDO+CCID 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
00000071 [140412345906880] readerfactory.c:968:RFBindFunctions() Loading IFD Handler 3.0
00000008 [140412345906880] ifdhandler.c:2102:init_driver() Driver version: 1.5.2
00000375 [140412345906880] ifdhandler.c:2124:init_driver() LogLevel: 0x0003
00000002 [140412345906880] ifdhandler.c:2135:init_driver() DriverOptions: 0x0000
00000134 [140412345906880] ifdhandler.c:2148:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000001 [140412345906880] ifdhandler.c:111:CreateChannelByNameOrChannel() Lun: 0, device: usb:1050/0407:libudev:1:/dev/bus/usb/001/024
00000002 [140412345906880] ccid_usb.c:254:OpenUSBByName() Reader index: 0, Device: usb:1050/0407:libudev:1:/dev/bus/usb/001/024
00000003 [140412345906880] ccid_usb.c:286:OpenUSBByName() interface_number: 1
00000002 [140412345906880] ccid_usb.c:287:OpenUSBByName() usb bus/device: 1/24
00000001 [140412345906880] ccid_usb.c:324:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
00000385 [140412345906880] ccid_usb.c:342:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr)
00000002 [140412345906880] ccid_usb.c:343:OpenUSBByName() ifdProductString: Generic CCID driver
00000002 [140412345906880] ccid_usb.c:344: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.
00009609 [140412345906880] ccid_usb.c:428:OpenUSBByName() Try device: 1/24
00000004 [140412345906880] ccid_usb.c:438:OpenUSBByName() vid/pid : 1050/0407
00000001 [140412345906880] ccid_usb.c:513:OpenUSBByName() Checking device: 1/24
00000001 [140412345906880] ccid_usb.c:584:OpenUSBByName() Trying to open USB bus/device: 1/24
00000029 [140412345906880] ccid_usb.c:667:OpenUSBByName() Found interface 2 but expecting 1
00000002 [140412345906880] ccid_usb.c:669:OpenUSBByName() Wrong interface for USB device 1/24. Checking next one.
00000148 [140412345906880] ccid_usb.c:205:close_libusb_if_needed() libusb_exit
00000007 [140412345906880] ccid_usb.c:835:OpenUSBByName() Device not found?
00000001 [140412345906880] ifdhandler.c:156:CreateChannelByNameOrChannel() failed
00000002 [140412345906880] readerfactory.c:1134:RFInitializeReader() Open Port 0x200001 Failed (usb:1050/0407:libudev:1:/dev/bus/usb/001/024)
00000001 [140412345906880] readerfactory.c:378:RFAddReader() Yubico YubiKey OTP+FIDO+CCID init failed.
00000001 [140412345906880] readerfactory.c:628:RFRemoveReader() UnrefReader() count was: 1
00000002 [140412345906880] readerfactory.c:1147:RFUnInitializeReader() Attempting shutdown of Yubico YubiKey OTP+FIDO+CCID 00 00.
00000001 [140412345906880] readerfactory.c:1005:RFUnloadReader() Unloading reader driver.
00000039 [140412345906880] hotplug_libudev.c:647:HPEstablishUSBNotifications() USB Device add
00000203 [140412345906880] hotplug_libudev.c:300:get_driver() Looking for a driver for VID: 0x1050, PID: 0x0407, path: /dev/bus/usb/001/024
00000005 [140412345906880] hotplug_libudev.c:421:HPAddDevice() Adding USB device: Yubico YubiKey OTP+FIDO+CCID
00000020 [140412345906880] readerfactory.c:1093:RFInitializeReader() Attempting startup of Yubico YubiKey OTP+FIDO+CCID 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so
00000072 [140412345906880] readerfactory.c:968:RFBindFunctions() Loading IFD Handler 3.0
00000008 [140412345906880] ifdhandler.c:2102:init_driver() Driver version: 1.5.2
00000381 [140412345906880] ifdhandler.c:2124:init_driver() LogLevel: 0x0003
00000003 [140412345906880] ifdhandler.c:2135:init_driver() DriverOptions: 0x0000
00000137 [140412345906880] ifdhandler.c:2148:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000002 [140412345906880] ifdhandler.c:111:CreateChannelByNameOrChannel() Lun: 0, device: usb:1050/0407:libudev:2:/dev/bus/usb/001/024
00000002 [140412345906880] ccid_usb.c:254:OpenUSBByName() Reader index: 0, Device: usb:1050/0407:libudev:2:/dev/bus/usb/001/024
00000002 [140412345906880] ccid_usb.c:286:OpenUSBByName() interface_number: 2
00000001 [140412345906880] ccid_usb.c:287:OpenUSBByName() usb bus/device: 1/24
00000002 [140412345906880] ccid_usb.c:324:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
00000393 [140412345906880] ccid_usb.c:342:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau@free.fr)
00000003 [140412345906880] ccid_usb.c:343:OpenUSBByName() ifdProductString: Generic CCID driver
00000001 [140412345906880] ccid_usb.c:344: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.
00009875 [140412345906880] ccid_usb.c:428:OpenUSBByName() Try device: 1/24
00000003 [140412345906880] ccid_usb.c:438:OpenUSBByName() vid/pid : 1050/0407
00000002 [140412345906880] ccid_usb.c:513:OpenUSBByName() Checking device: 1/24
00000001 [140412345906880] ccid_usb.c:584:OpenUSBByName() Trying to open USB bus/device: 1/24
00000033 [140412345906880] ccid_usb.c:690:OpenUSBByName() Found Vendor/Product: 1050/0407 (Yubico YubiKey OTP+FIDO+CCID)
00000001 [140412345906880] ccid_usb.c:692:OpenUSBByName() Using USB bus/device: 1/24
00000002 [140412345906880] ccid_usb.c:757:OpenUSBByName() bNumDataRatesSupported is 0
00000447 [140412345906880] ccid_usb.c:1486:InterruptRead() before (0), timeout: 100 ms
00012485 [140412345906880] ccid_usb.c:1532:InterruptRead() after (0) (0)
00000004 [140412345906880] NotifySlotChange: 50 03 
00000002 [140412345906880] -> 000000 65 00 00 00 00 00 00 00 00 00 
00000145 [140412345906880] <- 000000 81 00 00 00 00 00 00 00 00 00 
00000005 [140412345906880] -> 000000 65 00 00 00 00 00 01 00 00 00 
00000151 [140412345906880] <- 000000 81 00 00 00 00 00 01 00 00 00 
00000005 [140412345906880] ifdhandler.c:389:IFDHGetCapabilities() tag: 0xFB3, usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412345906880] readerfactory.c:398:RFAddReader() Using the reader polling thread
00000001 [140412345906880] ifdhandler.c:1955:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412345906880] -> 000000 65 00 00 00 00 00 02 00 00 00 
00000150 [140412345906880] <- 000000 81 00 00 00 00 00 02 00 00 00 
00000004 [140412345906880] ifdhandler.c:2081:IFDHICCPresence() Card present
00000028 [140412345906880] ifdhandler.c:389:IFDHGetCapabilities() tag: 0xFAE, usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412345906880] ifdhandler.c:487:IFDHGetCapabilities() Reader supports 1 slot(s)
00000036 [140412337514176] ifdhandler.c:1955:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000004 [140412337514176] -> 000000 65 00 00 00 00 00 03 00 00 00 
00000155 [140412337514176] <- 000000 81 00 00 00 00 00 03 00 00 00 
00000004 [140412337514176] ifdhandler.c:2081:IFDHICCPresence() Card present
00000001 [140412337514176] ifdhandler.c:1955:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412337514176] -> 000000 65 00 00 00 00 00 04 00 00 00 
00000196 [140412337514176] <- 000000 81 00 00 00 00 00 04 00 00 00 
00000003 [140412337514176] ifdhandler.c:2081:IFDHICCPresence() Card present
00000002 [140412337514176] ifdhandler.c:1246:IFDHPowerICC() action: PowerUp, usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412337514176] -> 000000 62 00 00 00 00 00 05 00 00 00 
00000855 [140412337514176] <- 000000 80 17 00 00 00 00 05 00 00 00 3B FD 13 00 00 81 31 FE 15 80 73 C0 21 C0 57 59 75 62 69 4B 65 79 40 
00000004 [140412337514176] eventhandler.c:289:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
00000003 [140412337514176] Card ATR: 3B FD 13 00 00 81 31 FE 15 80 73 C0 21 C0 57 59 75 62 69 4B 65 79 40 
00000001 [140412337514176] ifdhandler.c:1955:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412337514176] -> 000000 65 00 00 00 00 00 06 00 00 00 
00000196 [140412337514176] <- 000000 81 00 00 00 00 00 06 00 00 00 
00000003 [140412337514176] ifdhandler.c:2081:IFDHICCPresence() Card present
00000002 [140412337514176] ifdhandler.c:318:IFDHPolling() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0) 5000 ms
00000001 [140412337514176] ccid_usb.c:1486:InterruptRead() before (0), timeout: 5000 ms
03265663 [140412453652480] winscard_msg_srv.c:256:ProcessEventsServer() Common channel packet arrival
00000011 [140412453652480] winscard_msg_srv.c:267:ProcessEventsServer() ProcessCommonChannelRequest detects: 15
00000001 [140412453652480] pcscdaemon.c:133:SVCServiceRunLoop() A new context thread creation is requested: 15
00007026 [140412329121472] winscard_svc.c:340:ContextThread() Authorized PC/SC client
00000004 [140412329121472] winscard_svc.c:343:ContextThread() Thread is started: dwClientID=15, threadContext @0x55bb373eb2d0
00000007 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_VERSION from client 15
00000004 [140412329121472] winscard_svc.c:373:ContextThread() Client is protocol version 4:4
00000001 [140412329121472] winscard_svc.c:396:ContextThread() CMD_VERSION for client 15, rv=SCARD_S_SUCCESS
00000028 [140412329121472] winscard_svc.c:361:ContextThread() Received command: ESTABLISH_CONTEXT from client 15
00000006 [140412329121472] winscard.c:215:SCardEstablishContext() Establishing Context: 0x7B7815C7
00000002 [140412329121472] winscard_svc.c:465:ContextThread() ESTABLISH_CONTEXT for client 15, rv=SCARD_S_SUCCESS
00000024 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 15
00000022 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 15
00000081 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CONNECT from client 15
00005902 [140412329121472] winscard_svc.c:503:ContextThread() Authorized client for 'ACS ACR122U 00 00'
00000004 [140412329121472] winscard.c:258:SCardConnect() Attempting Connect to ACS ACR122U 00 00 using protocol: 3
00000002 [140412329121472] readerfactory.c:839:RFReaderInfo() RefReader() count was: 1
00000001 [140412329121472] winscard.c:312:SCardConnect() Card Not Inserted
00000001 [140412329121472] winscard.c:518:SCardConnect() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:523:ContextThread() CONNECT for client 15, rv=SCARD_E_NO_SMARTCARD
00000145 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CONNECT from client 15
00005644 [140412329121472] winscard_svc.c:503:ContextThread() Authorized client for 'Yubico YubiKey OTP+FIDO+CCID 00 00'
00000004 [140412329121472] winscard.c:258:SCardConnect() Attempting Connect to Yubico YubiKey OTP+FIDO+CCID 00 00 using protocol: 3
00000001 [140412329121472] readerfactory.c:839:RFReaderInfo() RefReader() count was: 1
00000002 [140412329121472] winscard.c:352:SCardConnect() powerState: POWER_STATE_IN_USE
00000001 [140412329121472] prothandler.c:107:PHSetProtocol() Attempting PTS to T=1
00000003 [140412329121472] ifdhandler.c:743:IFDHSetProtocolParameters() protocol T=1, usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] ifdhandler.c:1039:IFDHSetProtocolParameters() BWI/CWI (TB3) present: 0x15
00000002 [140412329121472] ifdhandler.c:1074:IFDHSetProtocolParameters() IFSC (TA3) present: 254
00000001 [140412329121472] ifdhandler.c:1078:IFDHSetProtocolParameters() Timeout: 1510 ms
00000001 [140412329121472] ifdhandler.c:1081:IFDHSetProtocolParameters() Skip SetParameters
00000001 [140412329121472] winscard.c:430:SCardConnect() Active Protocol: T=1
00000003 [140412329121472] winscard.c:456:SCardConnect() hCard Identity: 73d37b71
00000001 [140412329121472] winscard.c:518:SCardConnect() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:523:ContextThread() CONNECT for client 15, rv=SCARD_S_SUCCESS
00000056 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CONTROL from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000003 [140412329121472] ifdhandler.c:1506:IFDHControl() ControlCode: 0x42000D48, usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] Control TxBuffer: 
00000002 [140412329121472] Control RxBuffer: 12 04 42 33 00 12 
00000002 [140412329121472] winscard.c:1360:SCardControl() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:743:ContextThread() CONTROL for client 15, rv=SCARD_S_SUCCESS
00000057 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CONTROL from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000003 [140412329121472] ifdhandler.c:1506:IFDHControl() ControlCode: 0x42330012, usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] Control TxBuffer: 
00000005 [140412329121472] Control 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 
00000002 [140412329121472] winscard.c:1360:SCardControl() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:743:ContextThread() CONTROL for client 15, rv=SCARD_S_SUCCESS
00000135 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_GET_READERS_STATE from client 15
00000065 [140412329121472] winscard_svc.c:361:ContextThread() Received command: STATUS from client 15
00000004 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1301:SCardStatus() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:642:ContextThread() STATUS for client 15, rv=SCARD_S_SUCCESS
00000115 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000003 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00000063 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000003 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000054 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 A4 00 0C 02 3F 00 
00000003 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 7 bytes
00000004 [140412329121472] -> 000000 6F 07 00 00 00 00 07 00 00 00 00 A4 00 0C 02 3F 00 
00000280 [140412329121472] <- 000000 80 02 00 00 00 00 07 00 00 00 6D 00 
00000003 [140412329121472] SW: 6D 00 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000057 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000001 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000004 [140412329121472] APDU: 00 A4 04 00 08 A0 00 00 05 27 47 11 17 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 13 bytes
00000005 [140412329121472] -> 000000 6F 0D 00 00 00 00 08 00 00 00 00 A4 04 00 08 A0 00 00 05 27 47 11 17 
00000394 [140412329121472] <- 000000 80 20 00 00 00 00 08 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 35 2E 34 2E 33 90 00 
00000007 [140412329121472] SW: 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 35 2E 34 2E 33 90 00 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000057 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000005 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 1D 00 00 00 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000004 [140412329121472] -> 000000 6F 05 00 00 00 00 09 00 00 00 00 1D 00 00 00 
00000554 [140412329121472] <- 000000 80 31 00 00 00 00 09 00 00 00 2E 01 02 03 3F 03 02 03 3F 02 04 01 2D 51 D4 04 01 01 05 03 05 04 03 06 02 00 00 07 01 0F 08 01 00 0D 02 03 3F 0E 02 03 3F 0A 01 00 0F 01 00 90 00 
00000011 [140412329121472] SW: 2E 01 02 03 3F 03 02 03 3F 02 04 01 2D 51 D4 04 01 01 05 03 05 04 03 06 02 00 00 07 01 0F 08 01 00 0D 02 03 3F 0E 02 03 3F 0A 01 00 0F 01 00 90 00 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000098 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000003 [140412329121472] APDU: 00 A4 04 00 06 D2 76 00 01 24 01 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes
00000005 [140412329121472] -> 000000 6F 0B 00 00 00 00 0A 00 00 00 00 A4 04 00 06 D2 76 00 01 24 01 
00001496 [140412329121472] <- 000000 80 02 00 00 00 00 0A 00 00 00 90 00 
00000003 [140412329121472] SW: 90 00 
00000001 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000126 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 CA 00 4F 00 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000004 [140412329121472] -> 000000 6F 05 00 00 00 00 0B 00 00 00 00 CA 00 4F 00 
00000554 [140412329121472] <- 000000 80 12 00 00 00 00 0B 00 00 00 D2 76 00 01 24 01 03 04 00 06 19 74 72 84 00 00 90 00 
00000005 [140412329121472] SW: D2 76 00 01 24 01 03 04 00 06 19 74 72 84 00 00 90 00 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000137 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 CA 5F 52 00 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000005 [140412329121472] -> 000000 6F 05 00 00 00 00 0C 00 00 00 00 CA 5F 52 00 
00000424 [140412329121472] <- 000000 80 0A 00 00 00 00 0C 00 00 00 00 73 00 00 E0 05 90 00 90 00 
00000004 [140412329121472] SW: 00 73 00 00 E0 05 90 00 90 00 
00000001 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000138 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 CA 00 C4 00 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000005 [140412329121472] -> 000000 6F 05 00 00 00 00 0D 00 00 00 00 CA 00 C4 00 
00000500 [140412329121472] <- 000000 80 09 00 00 00 00 0D 00 00 00 01 7F 7F 7F 03 00 03 90 00 
00000003 [140412329121472] SW: 01 7F 7F 7F 03 00 03 90 00 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000125 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 CA 00 6E 00 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000004 [140412329121472] -> 000000 6F 05 00 00 00 00 0E 00 00 00 00 CA 00 6E 00 
00006709 [140412329121472] <- 000000 80 02 01 00 00 00 0E 00 00 00 6E 82 01 37 4F 10 D2 76 00 01 24 01 03 04 00 06 19 74 72 84 00 00 5F 52 08 00 73 00 00 E0 05 90 00 7F 74 03 81 01 20 73 82 01 10 C0 0A 7D 00 0B FE 08 00 00 FF 00 00 C1 06 01 08 00 00 11 00 C2 06 01 10 00 00 11 00 C3 06 01 08 00 00 11 00 DA 06 01 08 00 00 11 00 C4 07 01 7F 7F 7F 03 00 03 C5 50 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 CB 42 43 01 DA B6 C5 92 3E 63 A2 B3 6E DB 5E FD 7C 3E 49 4F 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 E9 F5 07 9A 20 03 E4 69 30 C0 D9 C2 4A 4E 33 18 FD CB CF 67 C6 50 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 61 3B 
00000031 [140412329121472] SW: 6E 82 01 37 4F 10 D2 76 00 01 24 01 03 04 00 06 19 74 72 84 00 00 5F 52 08 00 73 00 00 E0 05 90 00 7F 74 03 81 01 20 73 82 01 10 C0 0A 7D 00 0B FE 08 00 00 FF 00 00 C1 06 01 08 00 00 11 00 C2 06 01 10 00 00 11 00 C3 06 01 08 00 00 11 00 DA 06 01 08 00 00 11 00 C4 07 01 7F 7F 7F 03 00 03 C5 50 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 CB 42 43 01 DA B6 C5 92 3E 63 A2 B3 6E DB 5E FD 7C 3E 49 4F 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 E9 F5 07 9A 20 03 E4 69 30 C0 D9 C2 4A 4E 33 18 FD CB CF 67 C6 50 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 61 3B 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000133 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000019 [140412329121472] APDU: 00 C0 00 00 3B 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000005 [140412329121472] -> 000000 6F 05 00 00 00 00 0F 00 00 00 00 C0 00 00 3B 
00000458 [140412329121472] <- 000000 80 3D 00 00 00 00 0F 00 00 00 00 00 00 00 CD 10 00 00 00 00 57 A9 78 42 00 00 00 00 62 4D 70 83 DE 08 01 00 02 02 03 00 81 02 7F 66 08 02 02 0B FE 02 02 0B FE D6 02 00 20 D7 02 00 20 D8 02 00 20 D9 02 00 20 90 00 
00000007 [140412329121472] SW: 00 00 00 00 CD 10 00 00 00 00 57 A9 78 42 00 00 00 00 62 4D 70 83 DE 08 01 00 02 02 03 00 81 02 7F 66 08 02 02 0B FE 02 02 0B FE D6 02 00 20 D7 02 00 20 D8 02 00 20 D9 02 00 20 90 00 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000287 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000001 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 CA 00 5E 00 FF FE 
00000001 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 7 bytes
00000002 [140412329121472] -> 000000 6F 07 00 00 00 00 10 00 00 00 00 CA 00 5E 00 FF FE 
00006155 [140412329121472] <- 000000 80 02 00 00 00 00 10 00 00 00 90 00 
00000004 [140412329121472] SW: 90 00 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000484 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000004 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00001072 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000004 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000103 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000016 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00000101 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000017 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000595 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000007 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000001 [140412329121472] APDU: 00 CA 00 65 00 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000002 [140412329121472] -> 000000 6F 05 00 00 00 00 11 00 00 00 00 CA 00 65 00 
00006160 [140412329121472] <- 000000 80 0D 00 00 00 00 11 00 00 00 65 09 5B 00 5F 2D 00 5F 35 01 39 90 00 
00000006 [140412329121472] SW: 65 09 5B 00 5F 2D 00 5F 35 01 39 90 00 
00000003 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000189 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000022 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000001 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 CA 5F 50 00 FF FE 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 7 bytes
00000002 [140412329121472] -> 000000 6F 07 00 00 00 00 12 00 00 00 00 CA 5F 50 00 FF FE 
00000540 [140412329121472] <- 000000 80 02 00 00 00 00 12 00 00 00 90 00 
00000003 [140412329121472] SW: 90 00 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000169 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000001 [140412329121472] APDU: 00 CA 00 6E 00 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000002 [140412329121472] -> 000000 6F 05 00 00 00 00 13 00 00 00 00 CA 00 6E 00 
00006797 [140412329121472] <- 000000 80 02 01 00 00 00 13 00 00 00 6E 82 01 37 4F 10 D2 76 00 01 24 01 03 04 00 06 19 74 72 84 00 00 5F 52 08 00 73 00 00 E0 05 90 00 7F 74 03 81 01 20 73 82 01 10 C0 0A 7D 00 0B FE 08 00 00 FF 00 00 C1 06 01 08 00 00 11 00 C2 06 01 10 00 00 11 00 C3 06 01 08 00 00 11 00 DA 06 01 08 00 00 11 00 C4 07 01 7F 7F 7F 03 00 03 C5 50 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 CB 42 43 01 DA B6 C5 92 3E 63 A2 B3 6E DB 5E FD 7C 3E 49 4F 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 E9 F5 07 9A 20 03 E4 69 30 C0 D9 C2 4A 4E 33 18 FD CB CF 67 C6 50 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 61 3B 
00000017 [140412329121472] SW: 6E 82 01 37 4F 10 D2 76 00 01 24 01 03 04 00 06 19 74 72 84 00 00 5F 52 08 00 73 00 00 E0 05 90 00 7F 74 03 81 01 20 73 82 01 10 C0 0A 7D 00 0B FE 08 00 00 FF 00 00 C1 06 01 08 00 00 11 00 C2 06 01 10 00 00 11 00 C3 06 01 08 00 00 11 00 DA 06 01 08 00 00 11 00 C4 07 01 7F 7F 7F 03 00 03 C5 50 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 CB 42 43 01 DA B6 C5 92 3E 63 A2 B3 6E DB 5E FD 7C 3E 49 4F 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 E9 F5 07 9A 20 03 E4 69 30 C0 D9 C2 4A 4E 33 18 FD CB CF 67 C6 50 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 61 3B 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000101 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000005 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000001 [140412329121472] APDU: 00 C0 00 00 3B 
00000001 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000002 [140412329121472] -> 000000 6F 05 00 00 00 00 14 00 00 00 00 C0 00 00 3B 
00000521 [140412329121472] <- 000000 80 3D 00 00 00 00 14 00 00 00 00 00 00 00 CD 10 00 00 00 00 57 A9 78 42 00 00 00 00 62 4D 70 83 DE 08 01 00 02 02 03 00 81 02 7F 66 08 02 02 0B FE 02 02 0B FE D6 02 00 20 D7 02 00 20 D8 02 00 20 D9 02 00 20 90 00 
00000007 [140412329121472] SW: 00 00 00 00 CD 10 00 00 00 00 57 A9 78 42 00 00 00 00 62 4D 70 83 DE 08 01 00 02 02 03 00 81 02 7F 66 08 02 02 0B FE 02 02 0B FE D6 02 00 20 D7 02 00 20 D8 02 00 20 D9 02 00 20 90 00 
00000001 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000303 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000007 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000001 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 CA 00 C4 00 
00000001 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000002 [140412329121472] -> 000000 6F 05 00 00 00 00 15 00 00 00 00 CA 00 C4 00 
00000499 [140412329121472] <- 000000 80 09 00 00 00 00 15 00 00 00 01 7F 7F 7F 03 00 03 90 00 
00000003 [140412329121472] SW: 01 7F 7F 7F 03 00 03 90 00 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000184 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000007 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000001 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 CA 00 7A 00 
00000001 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000003 [140412329121472] -> 000000 6F 05 00 00 00 00 16 00 00 00 00 CA 00 7A 00 
00000559 [140412329121472] <- 000000 80 09 00 00 00 00 16 00 00 00 7A 05 93 03 00 00 00 90 00 
00000004 [140412329121472] SW: 7A 05 93 03 00 00 00 90 00 
00000001 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000145 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000022 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000001 [140412329121472] APDU: 00 CA 00 F9 00 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 5 bytes
00000016 [140412329121472] -> 000000 6F 05 00 00 00 00 17 00 00 00 00 CA 00 F9 00 
00000455 [140412329121472] <- 000000 80 05 00 00 00 00 17 00 00 00 81 01 00 90 00 
00000002 [140412329121472] SW: 81 01 00 90 00 
00000001 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000260 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000022 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000001 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 CA 01 01 00 FF FE 
00000001 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 7 bytes
00000002 [140412329121472] -> 000000 6F 07 00 00 00 00 18 00 00 00 00 CA 01 01 00 FF FE 
00000487 [140412329121472] <- 000000 80 02 00 00 00 00 18 00 00 00 90 00 
00000003 [140412329121472] SW: 90 00 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000226 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000006 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000001 [140412329121472] APDU: 00 CA 01 02 00 FF FE 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 7 bytes
00000002 [140412329121472] -> 000000 6F 07 00 00 00 00 19 00 00 00 00 CA 01 02 00 FF FE 
00000495 [140412329121472] <- 000000 80 02 00 00 00 00 19 00 00 00 90 00 
00000002 [140412329121472] SW: 90 00 
00000001 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000137 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000022 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000001 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 47 81 00 00 00 02 B6 00 01 0E 
00000001 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes
00000003 [140412329121472] -> 000000 6F 0B 00 00 00 00 1A 00 00 00 00 47 81 00 00 00 02 B6 00 01 0E 
00001860 [140412329121472] <- 000000 80 02 00 00 00 00 1A 00 00 00 65 81 
00000002 [140412329121472] SW: 65 81 
00000002 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000186 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000007 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000002 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000003 [140412329121472] APDU: 00 47 81 00 00 00 02 B8 00 02 0E 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes
00000005 [140412329121472] -> 000000 6F 0B 00 00 00 00 1B 00 00 00 00 47 81 00 00 00 02 B8 00 02 0E 
00003098 [140412329121472] <- 000000 80 10 02 00 00 00 1B 00 00 00 7F 49 82 02 09 81 82 02 00 B7 CC CF C6 D0 24 F6 7A FD 7F EE A0 35 E3 26 B2 C9 C1 B2 15 A2 6C E1 91 B5 79 99 EC 7D 87 8E 2C AC 74 4C 25 28 26 FC 37 CC 99 73 77 4A 11 A7 F1 FD D3 15 1C 88 E8 7D C3 87 54 64 E7 7A F4 36 F8 3D 36 8F AF 30 64 35 ED EF 9B 97 45 E6 3A 12 FB 67 6A E2 B6 E4 41 30 BF 76 F5 0A 12 63 67 9D 8F 82 CA 2E 5A 21 AF 11 A5 E2 57 91 B5 8F 2E 60 D1 3D BF 4B DE AA A6 CE 3F DA 36 41 D4 B4 52 92 48 7F E5 67 3D 52 65 95 44 B3 7D DB A0 9F 7C 78 54 84 05 64 80 49 7A 48 5B C1 F8 F7 91 59 C1 B4 8D 84 42 C7 19 E2 04 01 26 A4 47 53 2B 7D 16 1B 68 67 37 1C 92 CA 12 E6 A1 01 94 18 AF 71 79 E5 6B 08 AF 33 FA AA EF 4F E1 1C EB A9 80 A0 90 6B 21 2C 35 BB 93 93 D3 9E 43 46 C8 4C 46 1F 9F C3 0E 5A 3B 7B 75 52 AB D4 03 81 A8 A1 5E EB 12 58 D4 C8 70 21 B3 99 55 DB D5 89 D4 48 06 41 AE C3 3A 28 62 E5 CC 7C 74 6B A6 10 F4 2A F6 A3 E1 B1 74 A7 3C BD 7E D4 40 0E 84 B9 A5 2D 0E D9 BA 66 F6 70 C2 4E 82 60 27 98 6A A6 57 A3 63 4D E7 B9 9F 6D 15 52 30 C4 8F E7 AE E0 A8 D5 70 76 FD BD 4A A7 35 4F A5 DA 74 9E 47 16 5C 87 6B 26 F5 B0 F3 FA 40 6E 20 B8 9D 34 F2 B9 1B 5D 31 17 46 22 4A A5 EB 11 16 FA DF AE F4 70 E2 3B 3E 42 69 A2 8D EC 5B 5D 76 40 77 1C 72 51 09 DA 2E 73 79 D4 F9 CA E0 D5 07 BD EB 1A CB 2E 56 62 6B 68 A4 37 85 15 4E 5A 6E BC B2 47 4F 01 42 E1 9F 69 4A E8 C8 25 9C 0D B2 A2 E1 EA CC 0F B6 DA C7 C6 9B 40 C1 48 3E C5 5E B4 55 3A 62 24 81 BC EA E2 D1 26 1E 39 DC 63 8F 7B D4 51 5C 07 9B C9 D4 29 4E 4E 46 C1 BD 6D 7B 07 01 59 7F 4E F6 6C FC 94 4D 4E 5F 30 78 6D F4 37 DF 14 88 68 AB 3A 37 84 CC 6D 22 FB EA 21 E4 BA C2 3F 98 E4 BD 38 E8 80 14 57 A9 82 03 01 00 01 90 00 
00000064 [140412329121472] SW: 7F 49 82 02 09 81 82 02 00 B7 CC CF C6 D0 24 F6 7A FD 7F EE A0 35 E3 26 B2 C9 C1 B2 15 A2 6C E1 91 B5 79 99 EC 7D 87 8E 2C AC 74 4C 25 28 26 FC 37 CC 99 73 77 4A 11 A7 F1 FD D3 15 1C 88 E8 7D C3 87 54 64 E7 7A F4 36 F8 3D 36 8F AF 30 64 35 ED EF 9B 97 45 E6 3A 12 FB 67 6A E2 B6 E4 41 30 BF 76 F5 0A 12 63 67 9D 8F 82 CA 2E 5A 21 AF 11 A5 E2 57 91 B5 8F 2E 60 D1 3D BF 4B DE AA A6 CE 3F DA 36 41 D4 B4 52 92 48 7F E5 67 3D 52 65 95 44 B3 7D DB A0 9F 7C 78 54 84 05 64 80 49 7A 48 5B C1 F8 F7 91 59 C1 B4 8D 84 42 C7 19 E2 04 01 26 A4 47 53 2B 7D 16 1B 68 67 37 1C 92 CA 12 E6 A1 01 94 18 AF 71 79 E5 6B 08 AF 33 FA AA EF 4F E1 1C EB A9 80 A0 90 6B 21 2C 35 BB 93 93 D3 9E 43 46 C8 4C 46 1F 9F C3 0E 5A 3B 7B 75 52 AB D4 03 81 A8 A1 5E EB 12 58 D4 C8 70 21 B3 99 55 DB D5 89 D4 48 06 41 AE C3 3A 28 62 E5 CC 7C 74 6B A6 10 F4 2A F6 A3 E1 B1 74 A7 3C BD 7E D4 40 0E 84 B9 A5 2D 0E D9 BA 66 F6 70 C2 4E 82 60 27 98 6A A6 57 A3 63 4D E7 B9 9F 6D 15 52 30 C4 8F E7 AE E0 A8 D5 70 76 FD BD 4A A7 35 4F A5 DA 74 9E 47 16 5C 87 6B 26 F5 B0 F3 FA 40 6E 20 B8 9D 34 F2 B9 1B 5D 31 17 46 22 4A A5 EB 11 16 FA DF AE F4 70 E2 3B 3E 42 69 A2 8D EC 5B 5D 76 40 77 1C 72 51 09 DA 2E 73 79 D4 F9 CA E0 D5 07 BD EB 1A CB 2E 56 62 6B 68 A4 37 85 15 4E 5A 6E BC B2 47 4F 01 42 E1 9F 69 4A E8 C8 25 9C 0D B2 A2 E1 EA CC 0F B6 DA C7 C6 9B 40 C1 48 3E C5 5E B4 55 3A 62 24 81 BC EA E2 D1 26 1E 39 DC 63 8F 7B D4 51 5C 07 9B C9 D4 29 4E 4E 46 C1 BD 6D 7B 07 01 59 7F 4E F6 6C FC 94 4D 4E 5F 30 78 6D F4 37 DF 14 88 68 AB 3A 37 84 CC 6D 22 FB EA 21 E4 BA C2 3F 98 E4 BD 38 E8 80 14 57 A9 82 03 01 00 01 90 00 
00000016 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000534 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000007 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000001 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 47 81 00 00 00 02 A4 00 01 0E 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes
00000003 [140412329121472] -> 000000 6F 0B 00 00 00 00 1C 00 00 00 00 47 81 00 00 00 02 A4 00 01 0E 
00001866 [140412329121472] <- 000000 80 02 00 00 00 00 1C 00 00 00 65 81 
00000004 [140412329121472] SW: 65 81 
00000001 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000001 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000911 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000007 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000001 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000002 [140412329121472] APDU: 00 47 81 00 00 00 02 B6 00 01 0E 
00000002 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000001 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes
00000002 [140412329121472] -> 000000 6F 0B 00 00 00 00 1D 00 00 00 00 47 81 00 00 00 02 B6 00 01 0E 
00001756 [140412329121472] <- 000000 80 02 00 00 00 00 1D 00 00 00 65 81 
00000005 [140412329121472] SW: 65 81 
00000003 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000003 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00000132 [140412329121472] winscard_svc.c:361:ContextThread() Received command: TRANSMIT from client 15
00000008 [140412329121472] readerfactory.c:866:RFReaderInfoById() RefReader() count was: 1
00000003 [140412329121472] winscard.c:1596:SCardTransmit() Send Protocol: T=1
00000004 [140412329121472] APDU: 00 47 81 00 00 00 02 A4 00 01 0E 
00000003 [140412329121472] ifdhandler.c:1398:IFDHTransmitToICC() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000002 [140412329121472] commands.c:1679:CmdXfrBlockAPDU_extended() T=0 (extended): 11 bytes
00000019 [140412329121472] -> 000000 6F 0B 00 00 00 00 1E 00 00 00 00 47 81 00 00 00 02 A4 00 01 0E 
00001861 [140412329121472] <- 000000 80 02 00 00 00 00 1E 00 00 00 65 81 
00000004 [140412329121472] SW: 65 81 
00000001 [140412329121472] winscard.c:1649:SCardTransmit() UnrefReader() count was: 2
00000002 [140412329121472] winscard_svc.c:694:ContextThread() TRANSMIT for client 15, rv=SCARD_S_SUCCESS
00469392 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000023 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00001211 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000020 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000207 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00000206 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00500824 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000023 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00001253 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000020 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000288 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00000217 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000020 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00500881 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000022 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00001220 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000020 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000206 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00000210 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00188279 [140412337514176] ccid_usb.c:1532:InterruptRead() after (0) (2)
00000006 [140412337514176] ifdhandler.c:1955:IFDHICCPresence() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0)
00000003 [140412337514176] -> 000000 65 00 00 00 00 00 1F 00 00 00 
00000172 [140412337514176] <- 000000 81 00 00 00 00 00 1F 00 00 00 
00000004 [140412337514176] ifdhandler.c:2081:IFDHICCPresence() Card present
00000002 [140412337514176] ifdhandler.c:318:IFDHPolling() usb:1050/0407:libudev:2:/dev/bus/usb/001/024 (lun: 0) 600000 ms
00000002 [140412337514176] ccid_usb.c:1486:InterruptRead() before (0), timeout: 600000 ms
00312325 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000027 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00001185 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000208 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00000197 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000020 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00500828 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000023 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00001212 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000021 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000208 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00000205 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00500774 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000023 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00001214 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000021 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000206 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00000206 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000020 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00500697 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000007 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00001099 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000004 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000073 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000003 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00000122 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000003 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00500816 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000023 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00001260 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000019 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS
00000191 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 15
00000026 [140412329121472] winscard_svc.c:842:MSGSendReaderStates() Send reader states: 15
00000180 [140412329121472] winscard_svc.c:361:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 15
00000025 [140412329121472] winscard_svc.c:445:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE for client 15, rv=SCARD_S_SUCCESS