[Pcsclite-muscle] SCardConnect() returns unresponsive card

Thu May 18 04:29:55 PDT 2017

Sorry for wrong one, generated updated log is attached.

On 18-05-2017 14:22, Ludovic Rousseau wrote:
> 2017-05-18 12:10 GMT+02:00 G?k?enur CANLI <gokcenur.canli at udea.com.tr 
> <mailto:gokcenur.canli at udea.com.tr>>:
>
>     Hello,
>
>     I executed my program and reproduced problem again. New log file
>     is attached.
>
>
> This log is not complete.
> Your first log was correctly generated. Please use the same method.
>
> Thanks
>
> -- 
>  Dr. Ludovic Rousseau
>
>
> _______________________________________________
> Pcsclite-muscle mailing list
> Pcsclite-muscle at lists.alioth.debian.org
> http://lists.alioth.debian.org/cgi-bin/mailman/listinfo/pcsclite-muscle

-- 
*G?k?enur CANLI*
/Ar-Ge M?hendisi
/UDEA Elektronik A.?. ODT? Teknokent
G?m?? Bloklar K1-3 Ofis Alan?
?ankaya/ANKARA
Tel : + 90 (312) 210 19 82
Fax: +90 (312) 210 19 97
//
Signature <http://www.udea.com.tr>//
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.alioth.debian.org/pipermail/pcsclite-muscle/attachments/20170518/02182cf4/attachment-0001.html>
-------------- next part --------------
00000000 debuglog.c:289:DebugLogSetLevel() debug level=debug
00000016 debuglog.c:310:DebugLogSetCategory() Debug options: APDU
[36m00000004[0m [34mpcscdaemon.c:351:main() Force colored logs[0m
[36m00000092[0m configfile.l:358:DBGetReaderList() Parsing conf file: /usr/local/etc/reader.conf.d
[36m00000013[0m [34mpcscdaemon.c:655:main() pcsc-lite 1.8.20 daemon ready.[0m
[36m00001889[0m [34mhotplug_libusb.c:536:HPAddHotPluggable() Adding USB device: 1:12:0[0m
[36m00000607[0m [34mreaderfactory.c:1079:RFInitializeReader() Attempting startup of USX101x SmartCard Reader (330C38CB) 00 00 using /usr/local/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Linux/libccid.so[0m
[36m00000079[0m [34mreaderfactory.c:954:RFBindFunctions() Loading IFD Handler 3.0[0m
[36m00000019[0m [34mifdhandler.c:1961:init_driver() Driver version: 1.4.26[0m
[36m00000461[0m [34mifdhandler.c:1978:init_driver() LogLevel: 0x0003[0m
[36m00000006[0m [34mifdhandler.c:1989:init_driver() DriverOptions: 0x0000[0m
[36m00000147[0m [34mifdhandler.c:2002:init_driver() LogLevel from LIBCCID_ifdLogLevel: 0x000F[0m
[36m00000004[0m [34mifdhandler.c:111:CreateChannelByNameOrChannel() Lun: 0, device: usb:0424/1104:libusb-1.0:1:12:0[0m
[36m00000005[0m ccid_usb.c:237:OpenUSBByName() Reader index: 0, Device: usb:0424/1104:libusb-1.0:1:12:0
[36m00000007[0m [34mccid_usb.c:287:OpenUSBByName() Using: /usr/local/lib/pcsc/drivers/ifd-ccid.bundle/Contents/Info.plist[0m
[36m00000458[0m [34mccid_usb.c:305:OpenUSBByName() ifdManufacturerString: Ludovic Rousseau (ludovic.rousseau at free.fr)[0m
[36m00000004[0m [34mccid_usb.c:306:OpenUSBByName() ifdProductString: Generic CCID driver[0m
[36m00000003[0m [34mccid_usb.c:307:OpenUSBByName() Copyright: This driver is protected by terms of the GNU Lesser General Public License version 2.1, or (at your option) any later version.[0m
[36m00000760[0m ccid_usb.c:391:OpenUSBByName() Try device: 2/1
[36m00000004[0m ccid_usb.c:401:OpenUSBByName() vid/pid : 1D6B/0003
[36m00000002[0m ccid_usb.c:391:OpenUSBByName() Try device: 1/7
[36m00000002[0m ccid_usb.c:401:OpenUSBByName() vid/pid : 0BDA/0129
[36m00000002[0m ccid_usb.c:391:OpenUSBByName() Try device: 1/5
[36m00000002[0m ccid_usb.c:401:OpenUSBByName() vid/pid : 04F2/B483
[36m00000001[0m ccid_usb.c:391:OpenUSBByName() Try device: 1/12
[36m00000012[0m ccid_usb.c:401:OpenUSBByName() vid/pid : 0424/1104
[36m00000002[0m ccid_usb.c:448:OpenUSBByName() Checking device: 1/12
[36m00000002[0m ccid_usb.c:519:OpenUSBByName() Trying to open USB bus/device: 1/12
[36m00000039[0m [34mccid_usb.c:621:OpenUSBByName() Found Vendor/Product: 0424/1104 (USX101x SmartCard Reader)[0m
[36m00000003[0m [34mccid_usb.c:623:OpenUSBByName() Using USB bus/device: 1/12[0m
[36m00000002[0m [34mccid_usb.c:680:OpenUSBByName() bNumDataRatesSupported is 0[0m
[36m00001257[0m ccid_usb.c:1244:InterruptRead() before (0)
[36m00005929[0m ccid_usb.c:1290:InterruptRead() after (0) (0)
[36m00000011[0m NotifySlotChange: 50 02 
[36m00000005[0m -> 000000 65 00 00 00 00 00 00 00 00 00 
[36m00000247[0m <- 000000 81 00 00 00 00 00 00 01 00 01 
[36m00000007[0m -> 000000 65 00 00 00 00 00 01 00 00 00 
[36m00000247[0m <- 000000 81 00 00 00 00 00 01 01 00 01 
[36m00000010[0m [34mifdhandler.c:382:IFDHGetCapabilities() tag: 0xFB3, usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000003[0m [34mreaderfactory.c:395:RFAddReader() Using the reader polling thread[0m
[36m00000003[0m ifdhandler.c:1821:IFDHICCPresence() usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)
[36m00000004[0m -> 000000 65 00 00 00 00 00 02 00 00 00 
[36m00000207[0m <- 000000 81 00 00 00 00 00 02 01 00 01 
[36m00000005[0m ifdhandler.c:1942:IFDHICCPresence() Card present
[36m00000509[0m [34mifdhandler.c:382:IFDHGetCapabilities() tag: 0xFAE, usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000007[0m [34mifdhandler.c:473:IFDHGetCapabilities() Reader supports 1 slot(s)[0m
[36m00000212[0m [34mhotplug_libusb.c:440:HPEstablishUSBNotifications() Driver ifd-ccid.bundle does not support IFD_GENERATE_HOTPLUG. Using active polling instead.[0m
[36m00000004[0m [34mhotplug_libusb.c:449:HPEstablishUSBNotifications() Polling forced every 1 second(s)[0m
[36m00000057[0m ifdhandler.c:1821:IFDHICCPresence() usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)
[36m00000021[0m -> 000000 65 00 00 00 00 00 03 00 00 00 
[36m00000000[0m readerfactory.c:1420:RFWaitForReaderInit() Waiting init for reader: USX101x SmartCard Reader (330C38CB) 00 00
[36m00000405[0m <- 000000 81 00 00 00 00 00 03 01 00 01 
[36m00000012[0m ifdhandler.c:1942:IFDHICCPresence() Card present
[36m00000005[0m ifdhandler.c:1821:IFDHICCPresence() usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)
[36m00000006[0m -> 000000 65 00 00 00 00 00 04 00 00 00 
[36m00000222[0m <- 000000 81 00 00 00 00 00 04 01 00 01 
[36m00000008[0m ifdhandler.c:1942:IFDHICCPresence() Card present
[36m00000004[0m [34mifdhandler.c:1154:IFDHPowerICC() action: PowerUp, usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000005[0m -> 000000 62 00 00 00 00 00 05 01 00 00 
[36m00009462[0m readerfactory.c:1420:RFWaitForReaderInit() Waiting init for reader: USX101x SmartCard Reader (330C38CB) 00 00
[36m00010150[0m readerfactory.c:1420:RFWaitForReaderInit() Waiting init for reader: USX101x SmartCard Reader (330C38CB) 00 00
[36m00010085[0m readerfactory.c:1420:RFWaitForReaderInit() Waiting init for reader: USX101x SmartCard Reader (330C38CB) 00 00
[36m00010072[0m readerfactory.c:1420:RFWaitForReaderInit() Waiting init for reader: USX101x SmartCard Reader (330C38CB) 00 00
[36m00010109[0m readerfactory.c:1420:RFWaitForReaderInit() Waiting init for reader: USX101x SmartCard Reader (330C38CB) 00 00
[36m00010116[0m readerfactory.c:1420:RFWaitForReaderInit() Waiting init for reader: USX101x SmartCard Reader (330C38CB) 00 00
[36m00010126[0m readerfactory.c:1420:RFWaitForReaderInit() Waiting init for reader: USX101x SmartCard Reader (330C38CB) 00 00
[36m00010146[0m readerfactory.c:1420:RFWaitForReaderInit() Waiting init for reader: USX101x SmartCard Reader (330C38CB) 00 00
[36m00010099[0m readerfactory.c:1420:RFWaitForReaderInit() Waiting init for reader: USX101x SmartCard Reader (330C38CB) 00 00
[36m00001901[0m <- 000000 80 17 00 00 00 00 05 00 00 00 3B 9F 96 81 31 FE 45 80 65 54 43 12 21 08 31 C0 73 F6 21 80 81 05 9A 
[36m00000029[0m eventhandler.c:286:EHStatusHandlerThread() powerState: POWER_STATE_POWERED
[36m00000004[0m [34mCard ATR: 3B 9F 96 81 31 FE 45 80 65 54 43 12 21 08 31 C0 73 F6 21 80 81 05 9A [0m
[36m00000007[0m ifdhandler.c:1821:IFDHICCPresence() usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)
[36m00000003[0m -> 000000 65 00 00 00 00 00 06 00 00 00 
[36m00000290[0m <- 000000 81 00 00 00 00 00 06 00 00 00 
[36m00000004[0m ifdhandler.c:1942:IFDHICCPresence() Card present
[36m00000002[0m [34mifdhandler.c:311:IFDHPolling() usb:0424/1104:libusb-1.0:1:12:0 (lun: 0) 5000 ms[0m
[36m00000001[0m ccid_usb.c:1244:InterruptRead() before (0)
[36m00001163[0m ccid_usb.c:1290:InterruptRead() after (0) (0)
[36m00000005[0m NotifySlotChange: 50 03 
[36m00000002[0m ifdhandler.c:1821:IFDHICCPresence() usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)
[36m00000013[0m -> 000000 65 00 00 00 00 00 07 00 00 00 
[36m00000268[0m <- 000000 81 00 00 00 00 00 07 00 00 00 
[36m00000014[0m ifdhandler.c:1942:IFDHICCPresence() Card present
[36m00000002[0m [34mifdhandler.c:1154:IFDHPowerICC() action: PowerDown, usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000002[0m -> 000000 63 00 00 00 00 00 08 00 00 00 
[36m00011525[0m <- 000000 81 00 00 00 00 00 08 01 00 01 
[36m00000014[0m eventhandler.c:479:EHStatusHandlerThread() powerState: POWER_STATE_UNPOWERED
[36m00000003[0m ifdhandler.c:1821:IFDHICCPresence() usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)
[36m00000004[0m -> 000000 65 00 00 00 00 00 09 00 00 00 
[36m00000289[0m <- 000000 81 00 00 00 00 00 09 01 00 01 
[36m00000007[0m ifdhandler.c:1942:IFDHICCPresence() Card present
[36m00000003[0m [34mifdhandler.c:311:IFDHPolling() usb:0424/1104:libusb-1.0:1:12:0 (lun: 0) 600000 ms[0m
[36m00000003[0m ccid_usb.c:1244:InterruptRead() before (0)
[36m08070632[0m winscard_msg_srv.c:251:ProcessEventsServer() Common channel packet arrival
[36m00000021[0m winscard_msg_srv.c:263:ProcessEventsServer() ProcessCommonChannelRequest detects: 6
[36m00000004[0m pcscdaemon.c:132:SVCServiceRunLoop() A new context thread creation is requested: 6
[36m00000053[0m winscard_svc.c:337:ContextThread() Authorized PC/SC client
[36m00000006[0m winscard_svc.c:341:ContextThread() Thread is started: dwClientID=6, threadContext @0x126f9b0
[36m00000010[0m winscard_svc.c:359:ContextThread() Received command: CMD_VERSION from client 6
[36m00000004[0m winscard_svc.c:371:ContextThread() Client is protocol version 4:3
[36m00000003[0m winscard_svc.c:391:ContextThread() CMD_VERSION rv=0x0 for client 6
[36m00000041[0m winscard_svc.c:359:ContextThread() Received command: ESTABLISH_CONTEXT from client 6
[36m00000011[0m winscard.c:215:SCardEstablishContext() Establishing Context: 0x55646AFD
[36m00000002[0m winscard_svc.c:455:ContextThread() ESTABLISH_CONTEXT rv=0x0 for client 6
[36m00000042[0m winscard_svc.c:359:ContextThread() Received command: CMD_GET_READERS_STATE from client 6
[36m00000056[0m winscard_svc.c:359:ContextThread() Received command: CONNECT from client 6
[36m00000012[0m winscard_svc.c:493:ContextThread() Authorized client for 'USX101x SmartCard Reader (330C38CB) 00 00'
[36m00000003[0m winscard.c:256:SCardConnect() Attempting Connect to USX101x SmartCard Reader (330C38CB) 00 00 using protocol: 2
[36m00000003[0m readerfactory.c:825:RFReaderInfo() RefReader() count was: 1
[36m00000014[0m ifdhandler.c:1821:IFDHICCPresence() usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)
[36m00000005[0m -> 000000 65 00 00 00 00 00 0A 00 00 00 
[36m00000314[0m <- 000000 81 00 00 00 00 00 0A 01 00 01 
[36m00000007[0m ifdhandler.c:1942:IFDHICCPresence() Card present
[36m00000004[0m [34mifdhandler.c:1154:IFDHPowerICC() action: PowerUp, usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000004[0m -> 000000 62 00 00 00 00 00 0B 01 00 00 
[36m00092345[0m <- 000000 80 17 00 00 00 00 0B 00 00 00 3B 9F 96 81 31 FE 45 80 65 54 43 12 21 08 31 C0 73 F6 21 80 81 05 9A 
[36m00000015[0m winscard.c:329:SCardConnect() power up complete.
[36m00000006[0m Card ATR: 3B 9F 96 81 31 FE 45 80 65 54 43 12 21 08 31 C0 73 F6 21 80 81 05 9A 
[36m00000003[0m winscard.c:349:SCardConnect() powerState: POWER_STATE_INUSE
[36m00000006[0m ATR: 3B 9F 96 81 31 FE 45 80 65 54 43 12 21 08 31 C0 73 F6 21 80 81 05 9A 
[36m00000003[0m atrhandler.c:124:ATRDecodeAtr() TA1: 96, TB1: FFFFFFFF, TC1: FFFFFFFF, TD1: 81
[36m00000002[0m atrhandler.c:154:ATRDecodeAtr() T=1 Protocol Found
[36m00000003[0m atrhandler.c:124:ATRDecodeAtr() TA2: FFFFFFFF, TB2: FFFFFFFF, TC2: FFFFFFFF, TD2: 31
[36m00000002[0m atrhandler.c:154:ATRDecodeAtr() T=1 Protocol Found
[36m00000003[0m atrhandler.c:124:ATRDecodeAtr() TA3: FE, TB3: 45, TC3: FFFFFFFF, TD3: FFFFFFFF
[36m00000002[0m atrhandler.c:222:ATRDecodeAtr() CurrentProtocol: 2, AvailableProtocols: 2
[36m00000003[0m [34mprothandler.c:108:PHSetProtocol() Attempting PTS to T=1[0m
[36m00000004[0m [34mifdhandler.c:692:IFDHSetProtocolParameters() protocol T=1, usb:0424/1104:libusb-1.0:1:12:0 (lun: 0)[0m
[36m00000003[0m ifdhandler.c:818:IFDHSetProtocolParameters() Set speed to 300000 bauds
[36m00000002[0m ifdhandler.c:979:IFDHSetProtocolParameters() BWI/CWI (TB3) present: 0x45
[36m00000006[0m ifdhandler.c:1013:IFDHSetProtocolParameters() IFSC (TA3) present: 254
[36m00000003[0m ifdhandler.c:1017:IFDHSetProtocolParameters() Timeout: 2237 ms
[36m00000002[0m commands.c:2251:SetParameters() length: 7 bytes
[36m00000006[0m -> 000000 61 07 00 00 00 00 0C 01 00 00 96 10 00 45 00 FE 00 
[36m00017346[0m <- 000000 82 07 00 00 00 00 0C 00 00 01 96 10 00 45 00 FE 00 
[36m00000011[0m ifdhandler.c:1074:IFDHSetProtocolParameters() IFSC (TA3) present: 254
[36m00000002[0m ifdhandler.c:1081:IFDHSetProtocolParameters() Negotiate IFSD at 254
[36m00000003[0m sending: 00 C1 01 FE 3E 
[36m00000003[0m -> 000000 6F 05 00 00 00 00 0D 00 00 00 00 C1 01 FE 3E 
[36m00000966[0m <- 000000 80 04 00 00 00 00 0D 00 00 00 92 82 00 10 
[36m00000005[0m received: 92 82 00 10 
[36m00000002[0m sending: 00 C1 01 FE 3E 
[36m00000003[0m -> 000000 6F 05 00 00 00 00 0E 00 00 00 00 C1 01 FE 3E 
[36m00000769[0m <- 000000 80 04 00 00 00 00 0E 00 00 00 92 82 00 10 
[36m00000005[0m received: 92 82 00 10 
[36m00000002[0m sending: 00 C1 01 FE 3E 
[36m00000003[0m -> 000000 6F 05 00 00 00 00 0F 00 00 00 00 C1 01 FE 3E 
[36m00000767[0m <- 000000 80 04 00 00 00 00 0F 00 00 00 92 82 00 10 
[36m00000005[0m received: 92 82 00 10 
[36m00000002[0m [34mprothandler.c:124:PHSetProtocol() PTS failed (612), using T=1[0m
[36m00000003[0m winscard.c:517:SCardConnect() UnrefReader() count was: 2
[36m00000004[0m winscard_svc.c:507:ContextThread() CONNECT rv=0x80100066 for client 6
[36m01447353[0m winscard_svc.c:359:ContextThread() Received command: RELEASE_CONTEXT from client 6
[36m00000009[0m winscard.c:226:SCardReleaseContext() Releasing Context: 0x55646AFD
[36m00000003[0m winscard_svc.c:470:ContextThread() RELEASE_CONTEXT rv=0x0 for client 6
[36m00000121[0m winscard_svc.c:351:ContextThread() Client die: 6
[36m00000012[0m winscard_svc.c:1013:MSGCleanupClient() Thread is stopping: dwClientID=6, threadContext @0x126f9b0
[36m00000002[0m winscard_svc.c:1019:MSGCleanupClient() Freeing SCONTEXT @0x126f9b0