Re: [Opensc-devel] Smartcard recognized by pcsc_scan but not opensc-tools

[Florent D. <fde...@gm...>]

00000000 debuglog.c:269:DebugLogSetLevel() debug level=debug
00000036 debuglog.c:298:DebugLogSetCategory() Debug options: APDU
00000154 configfile.l:245:DBGetReaderListDir() Parsing conf directory: /etc/reader.conf.d
00000070 configfile.l:257:DBGetReaderListDir() Skipping non regular file: .
00000006 configfile.l:257:DBGetReaderListDir() Skipping non regular file: ..
00000005 configfile.l:298:DBGetReaderList() Parsing conf file: /etc/reader.conf.d/libccidtwin
00000115 pcscdaemon.c:516:main() pcsc-lite 1.8.5 daemon ready.
00001988 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/001/001
00000132 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/001/001
00000152 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x0E0F, PID: 0x0003, path: /dev/bus/usb/001/002
00000126 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x0E0F, PID: 0x0003, path: /dev/bus/usb/001/002
00000124 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x1D6B, PID: 0x0001, path: /dev/bus/usb/001/001
00000148 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x0E0F, PID: 0x0002, path: /dev/bus/usb/001/003
00000146 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x08E6, PID: 0x3438, path: /dev/bus/usb/001/008
00000034 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x08E6, PID: 0x3438, path: /dev/bus/usb/001/008
00000005 hotplug_libudev.c:312:HPAddDevice() Adding USB device: Gemalto USB Shell Token V2
00000051 readerfactory.c:978:RFInitializeReader() Attempting startup of Gemalto USB Shell Token V2 (309EF81F) 00 00 using /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents
00000199 readerfactory.c:868:RFBindFunctions() Loading IFD Handler 3.0
00000032 ifdhandler.c:1832:init_driver() Driver version: 1.4.7
00000676 ifdhandler.c:1849:init_driver() LogLevel: 0x0003
00000008 ifdhandler.c:1860:init_driver() DriverOptions: 0x0000
00000109 ifdhandler.c:1873:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F
00000008 ifdhandler.c:83:CreateChannelByNameOrChannel() Lun: 0, device: usb:08e6/3438:libudev:0:/dev/bus/usb/001/008
00000012 ccid_usb.c:180:OpenUSBByName() Reader index: 0, Device: usb:08e6/3438:libudev:0:/dev/bus/usb/001/008
00000020 ccid_usb.c:212:OpenUSBByName() interface_number: 0
00000004 ccid_usb.c:228:OpenUSBByName() Using: /usr/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist
00000468 ccid_usb.c:246:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (lud...@fr...)
00000005 ccid_usb.c:247:OpenUSBByName() ifdProductString: Generic CCID driver
00000006 ccid_usb.c:248:OpenUSBByName() Copyright: This driver is protected by terms of the GNU Lesser General Public License version 2.1, or (at your option) any later
00002653 ccid_usb.c:375:OpenUSBByName() Checking device: 1/8
00000021 ccid_usb.c:430:OpenUSBByName() Trying to open USB bus/device: 1/8
00175050 ccid_usb.c:521:OpenUSBByName() Found Vendor/Product: 08E6/3438 (Gemalto USB Shell Token V2)
00000067 ccid_usb.c:523:OpenUSBByName() Using USB bus/device: 1/8
00000022 ccid_usb.c:1041:ControlUSB() request: 0x03
00030534 receive: 67 32 00 00 CE 64 00 00 9D C9 00 00 3A 93 01 00 74 26 03 00 E7 4C 06 00 CE 99 0C 00 D7 5C 02 00 11 F0 03 00 34 43 00 00 69 86 00 00 D1 0C 01 00 A2 19 02 00 45 33 04 00 8A 66 08 00 0B A0 02 00 73 30 00 00 E6 60 00 00 CC C1 00 00 99 83 01 00 32 07 03 00 63 0E 06 00 B3 22 01 00 7F E4 01 00 06 50 01 00 36 97 00 00 04 FC 00 00 53 28 00 00 A5 50 00 00 4A A1 00 00 95 42 01 00 29 85 02 00 F8 78 00 00 3E 49 00 00 7C 92 00 00 F8 24 01 00 F0 49 02 00 E0 93 04 00 C0 27 09 00 74 B7 01 00 6C DC 02 00 D4 30 00 00 A8 61 00 00 50 C3 00 00 A0 86 01 00 40 0D 03 00 80 1A 06 00 48 E8 01 00 BA DB 00 00 36 6E 01 00 24 F4 00 00 DD 6D 00 00 1B B7 00 00 
00000085 ccid_usb.c:1021:get_data_rates() declared: 12903 bps
00000020 ccid_usb.c:1021:get_data_rates() declared: 25806 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 51613 bps
00000017 ccid_usb.c:1021:get_data_rates() declared: 103226 bps
00000044 ccid_usb.c:1021:get_data_rates() declared: 206452 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 412903 bps
00000017 ccid_usb.c:1021:get_data_rates() declared: 825806 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 154839 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 258065 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 17204 bps
00000017 ccid_usb.c:1021:get_data_rates() declared: 34409 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 68817 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 137634 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 275269 bps
00000017 ccid_usb.c:1021:get_data_rates() declared: 550538 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 172043 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 12403 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 24806 bps
00000017 ccid_usb.c:1021:get_data_rates() declared: 49612 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 99225 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 198450 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 396899 bps
00000017 ccid_usb.c:1021:get_data_rates() declared: 74419 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 124031 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 86022 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 38710 bps
00000017 ccid_usb.c:1021:get_data_rates() declared: 64516 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 10323 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 20645 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 41290 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 82581 bps
00000017 ccid_usb.c:1021:get_data_rates() declared: 165161 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 30968 bps
00000016 ccid_usb.c:1021:get_data_rates() declared: 18750 bps
00000025 ccid_usb.c:1021:get_data_rates() declared: 37500 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 75000 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 150000 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 300000 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 600000 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 112500 bps
00000017 ccid_usb.c:1021:get_data_rates() declared: 187500 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 12500 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 25000 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 50000 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 100000 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 200000 bps
00000018 ccid_usb.c:1021:get_data_rates() declared: 400000 bps
00001769 ccid_usb.c:1021:get_data_rates() declared: 125000 bps
00000041 ccid_usb.c:1021:get_data_rates() declared: 56250 bps
00000026 ccid_usb.c:1021:get_data_rates() declared: 93750 bps
00000024 ccid_usb.c:1021:get_data_rates() declared: 62500 bps
00000024 ccid_usb.c:1021:get_data_rates() declared: 28125 bps
00000024 ccid_usb.c:1021:get_data_rates() declared: 46875 bps
00122113 ccid_usb.c:1090:InterruptRead() before (0)
00125238 ccid_usb.c:1132:InterruptRead() after (0) (2)
00000089 -> 000000 65 00 00 00 00 00 00 00 00 00 
00030692 <- 000000 81 00 00 00 00 00 00 01 00 00 
00000095 -> 000000 6B 01 00 00 00 00 01 00 00 00 6A 
00031111 <- 000000 83 00 00 00 00 00 01 41 0A 00 
00000077 commands.c:890:CmdEscape error on byte 10
00000022 ccid.c:205:set_gemalto_firmware_features() GET_FIRMWARE_FEATURES failed: 612, len=0
00000036 ifdhandler.c:353:IFDHGetCapabilities() tag: 0xFB3, usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000021 readerfactory.c:332:RFAddReader() Using the reader polling thread
00000018 ifdhandler.c:1695:IFDHICCPresence() usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000020 -> 000000 65 00 00 00 00 00 02 00 00 00 
00031069 <- 000000 81 00 00 00 00 00 02 01 00 00 
00000075 ifdhandler.c:1813:IFDHICCPresence() Card present
00000119 ifdhandler.c:353:IFDHGetCapabilities() tag: 0xFAE, usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000026 ifdhandler.c:441:IFDHGetCapabilities() Reader supports 1 slot(s)
00000268 hotplug_libudev.c:260:get_driver() Looking for a driver for VID: 0x0E0F, PID: 0x0002, path: /dev/bus/usb/001/003
00000119 readerfactory.c:1304:RFWaitForReaderInit() Waiting init for reader: Gemalto USB Shell Token V2 (309EF81F) 00 00
00000534 ifdhandler.c:1695:IFDHICCPresence() usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000136 -> 000000 65 00 00 00 00 00 03 00 00 00 
00013986 readerfactory.c:1304:RFWaitForReaderInit() Waiting init for reader: Gemalto USB Shell Token V2 (309EF81F) 00 00
00015582 readerfactory.c:1304:RFWaitForReaderInit() Waiting init for reader: Gemalto USB Shell Token V2 (309EF81F) 00 00
00001765 <- 000000 81 00 00 00 00 00 03 01 00 00 
00000211 ifdhandler.c:1813:IFDHICCPresence() Card present
00000016 ifdhandler.c:1695:IFDHICCPresence() usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000015 -> 000000 65 00 00 00 00 00 04 00 00 00 
00014468 readerfactory.c:1304:RFWaitForReaderInit() Waiting init for reader: Gemalto USB Shell Token V2 (309EF81F) 00 00
00015216 readerfactory.c:1304:RFWaitForReaderInit() Waiting init for reader: Gemalto USB Shell Token V2 (309EF81F) 00 00
00000233 <- 000000 81 00 00 00 00 00 04 01 00 00 
00000033 ifdhandler.c:1813:IFDHICCPresence() Card present
00000046 ifdhandler.c:1114:IFDHPowerICC() action: PowerUp, usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000017 -> 000000 62 00 00 00 00 00 05 01 00 00 
00014802 readerfactory.c:1304:RFWaitForReaderInit() Waiting init for reader: Gemalto USB Shell Token V2 (309EF81F) 00 00
00015742 readerfactory.c:1304:RFWaitForReaderInit() Waiting init for reader: Gemalto USB Shell Token V2 (309EF81F) 00 00
00015460 readerfactory.c:1304:RFWaitForReaderInit() Waiting init for reader: Gemalto USB Shell Token V2 (309EF81F) 00 00
00015517 readerfactory.c:1304:RFWaitForReaderInit() Waiting init for reader: Gemalto USB Shell Token V2 (309EF81F) 00 00
00015697 readerfactory.c:1304:RFWaitForReaderInit() Waiting init for reader: Gemalto USB Shell Token V2 (309EF81F) 00 00
00002343 <- 000000 80 14 00 00 00 00 05 00 00 00 3B 7F 96 00 00 00 31 B8 64 40 70 14 10 73 94 01 80 82 90 00 
00000089 eventhandler.c:256:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
00000017 Card ATR: 3B 7F 96 00 00 00 31 B8 64 40 70 14 10 73 94 01 80 82 90 00 
00000014 ifdhandler.c:1695:IFDHICCPresence() usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000014 -> 000000 65 00 00 00 00 00 06 00 00 00 
00028893 <- 000000 81 00 00 00 00 00 06 00 00 00 
00000060 ifdhandler.c:1813:IFDHICCPresence() Card present
00000014 ifdhandler.c:282:IFDHPolling() usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0) 5000 ms
00000012 ccid_usb.c:1090:InterruptRead() before (0)
05459699 ccid_usb.c:1132:InterruptRead() after (0) (2)
00000178 ifdhandler.c:1695:IFDHICCPresence() usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000030 -> 000000 65 00 00 00 00 00 07 00 00 00 
00359168 <- 000000 81 00 00 00 00 00 07 00 00 00 
00000134 ifdhandler.c:1813:IFDHICCPresence() Card present
00000033 ifdhandler.c:1114:IFDHPowerICC() action: PowerDown, usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000027 -> 000000 63 00 00 00 00 00 08 00 00 00 
00030709 <- 000000 81 00 00 00 00 00 08 01 00 00 
00000077 eventhandler.c:446:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
00000023 ifdhandler.c:1695:IFDHICCPresence() usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000021 -> 000000 65 00 00 00 00 00 09 00 00 00 
00031143 <- 000000 81 00 00 00 00 00 09 01 00 00 
00000090 ifdhandler.c:1813:IFDHICCPresence() Card present
00000025 ifdhandler.c:282:IFDHPolling() usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0) 600000 ms
00000019 ccid_usb.c:1090:InterruptRead() before (0)
11990742 winscard_msg_srv.c:230:ProcessEventsServer() Common channel packet arrival
00000024 winscard_msg_srv.c:242:ProcessEventsServer() ProcessCommonChannelRequest detects: 9
00000005 pcscdaemon.c:93:SVCServiceRunLoop() A new context thread creation is requested: 9
00000183 winscard_svc.c:297:ContextThread() Thread is started: dwClientID=9, threadContext @0x8f226d8
00002934 winscard_svc.c:315:ContextThread() Received command: CMD_VERSION from client 9
00000428 winscard_svc.c:327:ContextThread() Client is protocol version 4:2
00000058 winscard_svc.c:347:ContextThread() CMD_VERSION rv=0x0 for client 9
00000225 winscard_svc.c:315:ContextThread() Received command: ESTABLISH_CONTEXT from client 9
00000059 winscard.c:193:SCardEstablishContext() Establishing Context: 0x75999A1B
00000023 winscard_svc.c:408:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 9
00000065 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
00000045 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
00000080 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
00000084 winscard_svc.c:315:ContextThread() Received command: CONNECT from client 9
00000047 winscard.c:235:SCardConnect() Attempting Connect to Gemalto USB Shell Token V2 (309EF81F) 00 00 using protocol: 3
00000027 readerfactory.c:739:RFReaderInfo() RefReader() count was: 1
00000035 ifdhandler.c:1695:IFDHICCPresence() usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000044 -> 000000 65 00 00 00 00 00 0A 00 00 00 
00419367 <- 000000 81 00 00 00 00 00 0A 01 00 00 
00000080 ifdhandler.c:1813:IFDHICCPresence() Card present
00000023 ifdhandler.c:1114:IFDHPowerICC() action: PowerUp, usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000023 -> 000000 62 00 00 00 00 00 0B 01 00 00 
00078889 <- 000000 80 14 00 00 00 00 0B 00 00 00 3B 7F 96 00 00 00 31 B8 64 40 70 14 10 73 94 01 80 82 90 00 
00000098 winscard.c:310:SCardConnect() power up complete.
00000027 Card ATR: 3B 7F 96 00 00 00 31 B8 64 40 70 14 10 73 94 01 80 82 90 00 
00000018 winscard.c:330:SCardConnect() powerState: POWER_STATE_INUSE
00000018 prothandler.c:87:PHSetProtocol() Attempting PTS to T=0
00000021 ifdhandler.c:663:IFDHSetProtocolParameters() protocol T=0, usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000028 ifdhandler.c:1953:extra_egt() Extra EGT patch applied
00000022 ifdhandler.c:1983:find_baud_rate() Card baud rate: 300000
00000019 ifdhandler.c:1992:find_baud_rate() Reader can do: 12903
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 25806
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 51613
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 103226
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 206452
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 412903
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 825806
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 154839
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 258065
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 17204
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 34409
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 68817
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 137634
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 275269
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 550538
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 172043
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 12403
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 24806
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 49612
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 99225
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 198450
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 396899
00000018 ifdhandler.c:1992:find_baud_rate() Reader can do: 74419
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 124031
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 86022
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 38710
00000017 ifdhandler.c:1992:find_baud_rate() Reader can do: 64516
00000016 ifdhandler.c:1992:find_baud_rate() Reader can do: 10323
00001113 ifdhandler.c:1992:find_baud_rate() Reader can do: 20645
00000028 ifdhandler.c:1992:find_baud_rate() Reader can do: 41290
00000035 ifdhandler.c:1992:find_baud_rate() Reader can do: 82581
00000023 ifdhandler.c:1992:find_baud_rate() Reader can do: 165161
00000022 ifdhandler.c:1992:find_baud_rate() Reader can do: 30968
00000022 ifdhandler.c:1992:find_baud_rate() Reader can do: 18750
00000022 ifdhandler.c:1992:find_baud_rate() Reader can do: 37500
00000022 ifdhandler.c:1992:find_baud_rate() Reader can do: 75000
00000022 ifdhandler.c:1992:find_baud_rate() Reader can do: 150000
00000022 ifdhandler.c:1992:find_baud_rate() Reader can do: 300000
00000023 ifdhandler.c:784:IFDHSetProtocolParameters() Set speed to 300000 bauds
00000024 towitoko/atr.c:341:ATR_GetDefaultProtocol() no default protocol found in ATR. Using T=0
00000023 PPS: Sending request: FF 10 96 79 
00000027 -> 000000 6F 04 00 00 00 00 0C 00 00 00 FF 10 96 79 
00022876 <- 000000 80 04 00 00 00 00 0C 00 00 00 FF 10 96 79 
00000098 PPS: Receiving confirm: FF 10 96 79 
00000030 ifdhandler.c:1020:IFDHSetProtocolParameters() Communication timeout: 266240 ms
00000022 commands.c:2115:SetParameters() length: 5 bytes
00000021 -> 000000 61 05 00 00 00 00 0D 00 00 00 96 00 02 0A 00 
00020124 <- 000000 
00000063 commands.c:2135:SetParameters() Not enough data received: 0 bytes
00000013 prothandler.c:103:PHSetProtocol() PTS failed (612), using T=0
00000012 winscard.c:490:SCardConnect() UnrefReader() count was: 2
00000012 winscard_svc.c:449:ContextThread() CONNECT rv=0x80100066 for client 9
00000241 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
00000084 winscard_svc.c:315:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 9
00000022 winscard_svc.c:315:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 9
00000015 winscard_svc.c:389:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 9
00000025 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
00000139 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
00000038 winscard_svc.c:315:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 9
00000019 winscard_svc.c:315:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 9
00000015 winscard_svc.c:389:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 9
00000024 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
00000039 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
00000049 winscard_svc.c:315:ContextThread() Received command: CMD_WAIT_READER_STATE_CHANGE from client 9
00000019 winscard_svc.c:315:ContextThread() Received command: CMD_STOP_WAITING_READER_STATE_CHANGE from client 9
00000015 winscard_svc.c:389:ContextThread() CMD_STOP_WAITING_READER_STATE_CHANGE rv=0x0 for client 9
00000024 winscard_svc.c:315:ContextThread() Received command: CMD_GET_READERS_STATE from client 9
00000030 winscard_svc.c:315:ContextThread() Received command: CONNECT from client 9
00000016 winscard.c:235:SCardConnect() Attempting Connect to Gemalto USB Shell Token V2 (309EF81F) 00 00 using protocol: 3
00000013 readerfactory.c:739:RFReaderInfo() RefReader() count was: 1
00000012 winscard.c:330:SCardConnect() powerState: POWER_STATE_INUSE
00000012 prothandler.c:87:PHSetProtocol() Attempting PTS to T=0
00000013 ifdhandler.c:663:IFDHSetProtocolParameters() protocol T=0, usb:08e6/3438:libudev:0:/dev/bus/usb/001/008 (lun: 0)
00000014 ifdhandler.c:1953:extra_egt() Extra EGT patch applied
00000012 ifdhandler.c:1983:find_baud_rate() Card baud rate: 300000
00000011 ifdhandler.c:1992:find_baud_rate() Reader can do: 12903
00000012 ifdhandler.c:1992:find_baud_rate() Reader can do: 25806
00000011 ifdhandler.c:1992:find_baud_rate() Reader can do: 51613
00000011 ifdhandler.c:1992:find_baud_rate() Reader can do: 103226
00000012 ifdhandler.c:1992:find_baud_rate() Reader can do: 206452
00000011 ifdhandler.c:1992:find_baud_rate() Reader can do: 412903
00000012 ifdhandler.c:1992:find_baud_rate() Reader can do: 825806
00000021 ifdhandler.c:1992:find_baud_rate() Reader can do: 154839
00000012 ifdhandler.c:1992:find_baud_rate() Reader can do: 258065
00000012 ifdhandler.c:1992:find_baud_rate() Reader can do: 17204
00000011 ifdhandler.c:1992:find_baud_rate() Reader can do: 34409
00000012 ifdhandler.c:1992:find_baud_rate() Reader can do: 68817
00000011 ifdhandler.c:1992:find_baud_rate() Reader can do: 137634
00000012 ifdhandler.c:1992:find_baud_rate() Reader can do: 275269
00000011 ifdhandler.c:1992:find_baud_rate() Reader can do: 550538
00000012 ifdhandler.c:1992:find_baud_rate() Reader can do: 172043
00000011 ifdhandler.c:1992:find_baud_rate() Reader can do: 12403
00000011 ifdhandler.c:1992:find_baud_rate() Reader can do: 24806
00000012 ifdhandler.c:1992:find_baud_rate() Reader can do: 49612
00000018 ifdhandler.c:1992:find_baud_rate() Reader can do: 99225
00000015 ifdhandler.c:1992:find_baud_rate() Reader can do: 198450
00000015 ifdhandler.c:1992:find_baud_rate() Reader can do: 396899
00000015 ifdhandler.c:1992:find_baud_rate() Reader can do: 74419
00000015 ifdhandler.c:1992:find_baud_rate() Reader can do: 124031
00000014 ifdhandler.c:1992:find_baud_rate() Reader can do: 86022
00000015 ifdhandler.c:1992:find_baud_rate() Reader can do: 38710
00000015 ifdhandler.c:1992:find_baud_rate() Reader can do: 64516
00000014 ifdhandler.c:1992:find_baud_rate() Reader can do: 10323
00000015 ifdhandler.c:1992:find_baud_rate() Reader can do: 20645
00000015 ifdhandler.c:1992:find_baud_rate() Reader can do: 41290
00000014 ifdhandler.c:1992:find_baud_rate() Reader can do: 82581
00000014 ifdhandler.c:1992:find_baud_rate() Reader can do: 165161
00000015 ifdhandler.c:1992:find_baud_rate() Reader can do: 30968
00000014 ifdhandler.c:1992:find_baud_rate() Reader can do: 18750
00000014 ifdhandler.c:1992:find_baud_rate() Reader can do: 37500
00000015 ifdhandler.c:1992:find_baud_rate() Reader can do: 75000
00000014 ifdhandler.c:1992:find_baud_rate() Reader can do: 150000
00000014 ifdhandler.c:1992:find_baud_rate() Reader can do: 300000
00000015 ifdhandler.c:784:IFDHSetProtocolParameters() Set speed to 300000 bauds
00000015 towitoko/atr.c:341:ATR_GetDefaultProtocol() no default protocol found in ATR. Using T=0
00000016 PPS: Sending request: FF 10 96 79 
00000018 -> 000000 6F 04 00 00 00 00 0E 00 00 00 FF 10 96 79 
00029959 <- 000000 
00000076 commands.c:1387:CCID_Receive() Not enough data received: 0 bytes
00000019 ifdhandler.c:887:IFDHSetProtocolParameters() PPS_Exchange Failed
00000018 prothandler.c:103:PHSetProtocol() PTS failed (605), using T=0
00000017 winscard.c:490:SCardConnect() UnrefReader() count was: 2
00000016 winscard_svc.c:449:ContextThread() CONNECT rv=0x80100066 for client 9
00000913 winscard_svc.c:315:ContextThread() Received command: RELEASE_CONTEXT from client 9
00000112 winscard.c:204:SCardReleaseContext() Releasing Context: 0x75999A1B
00000021 winscard_svc.c:423:ContextThread() RELEASE_CONTEXT rv=0x0 for client 9
00000968 winscard_svc.c:307:ContextThread() Client die: 9
00000077 winscard_svc.c:918:MSGCleanupClient() Thread is stopping: dwClientID=9, threadContext @0x8f226d8
00000020 winscard_svc.c:924:MSGCleanupClient() Freeing SCONTEXT @0x8f226d8