usb/83863: Communication problem between opensc/openct via USB with e-gate smart-card.

Janos Mohacsi mohacsi at niif.hu
Thu Jul 21 14:10:58 GMT 2005 

>Number:         83863
>Category:       usb
>Synopsis:       Communication problem between opensc/openct via USB with e-gate smart-card.
>Confidential:   no
>Severity:       non-critical
>Priority:       low
>Responsible:    freebsd-usb
>State:          open
>Quarter:        
>Keywords:       
>Date-Required:
>Class:          sw-bug
>Submitter-Id:   current-users
>Arrival-Date:   Thu Jul 21 14:10:06 GMT 2005
>Closed-Date:
>Last-Modified:
>Originator:     Janos Mohacsi
>Release:        FreeBSD 7.0-CURRENT i386
>Organization:
NIIF/HUNGARNET
>Environment:
System: FreeBSD scone.ki.iif.hu 7.0-CURRENT FreeBSD 7.0-CURRENT #2: Thu Jul 21 12:52:16 CEST 2005 root at scone.ki.iif.hu:/usr/obj/usr/src/sys/SCONE i386


>Description:
	I tried to used opensc 0.9.6 with openct 0.6.5 on FreeBSD 6.0 
(and later FreeBSD 7.0-CURRENT.). 
	To be able to understand this is the layering structure of different 
components:
	[opensc - provides pkcs11 interface]------\
                 |                                 \
        [openct - interface several smart-card]     \
                 |                    |              \
              (usb interface)    (serial interface)  (pcsc-lite interface)
                                                        |         
                                                      (usb)

	I have problem with the usb interface as described in the 
thread http://opensc.org/pipermail/opensc-user/2005-July/000258.html
The opensc experts and developers believe the problem is lying in the FreeBSD
kernel... Therefore I compiled a kernel with USB_DEBUG.
and set sysctl hw.usb.ugen.debug.

I think this problem is similar to http://www.freebsd.org/cgi/query-pr.cgi?pr=kern/71417

I am willing to debug it if somebedy can give me a helping hand.....

debug log output with some comments:


Startup of the kernel

mohacsi at scone> sudo dmesg -a |grep usb
uhci0: <Intel 82801DB (ICH4) USB controller USB-A> port 0xbf80-0xbf9f irq 11 at device 29.0 on pci0
usb0: <Intel 82801DB (ICH4) USB controller USB-A> on uhci0
usb0: USB revision 1.0
uhci1: <Intel 82801DB (ICH4) USB controller USB-B> port 0xbf40-0xbf5f irq 11 at device 29.1 on pci0
usb1: <Intel 82801DB (ICH4) USB controller USB-B> on uhci1
usb1: USB revision 1.0
uhci2: <Intel 82801DB (ICH4) USB controller USB-C> port 0xbf20-0xbf3f irq 11 at device 29.2 on pci0
usb2: <Intel 82801DB (ICH4) USB controller USB-C> on uhci2
usb2: USB revision 1.0
pci0: <serial bus, USB> at device 29.7 (no driver attached)
ums0: Logitech USB Mouse, rev 1.10/6.20, addr 2, iclass 3/1
Starting usbd.

Startup of openct - no smart-card attached.

ifd_driver_add_id: ifd_driver_add_id(usb:0973/0001, egate)
ifd_driver_add_id: ifd_driver_add_id(usb:0529/050c, etoken)
ifd_driver_add_id: ifd_driver_add_id(usb:0529/0514, etoken)
ifd_driver_add_id: ifd_driver_add_id(usb:073d/0005, eutron)
ifd_driver_add_id: ifd_driver_add_id(usb:04b9/1202, ikey2k)
ifd_driver_add_id: ifd_driver_add_id(usb:04b9/1300, ikey3k)
ifd_driver_add_id: ifd_driver_add_id(usb:08e6/3437, ccid)
ifd_driver_add_id: ifd_driver_add_id(usb:08e6/3438, ccid)
ifd_driver_add_id: ifd_driver_add_id(usb:08e6/4433, ccid)
ifd_driver_add_id: ifd_driver_add_id(usb:04e6/5115, ccid)
ifd_driver_add_id: ifd_driver_add_id(usb:04e6/E001, ccid)
ifd_driver_add_id: ifd_driver_add_id(usb:04e6/E003, ccid)
ifd_driver_add_id: ifd_driver_add_id(usb:076b/3021, ccid)
ifd_driver_add_id: ifd_driver_add_id(usb:076b/5121, ccid)
ifd_driver_add_id: ifd_driver_add_id(usb:0783/0003, ccid)
ifd_scan_usb: BSD: ifd_scan_usb

Attaching the smart-card.

mohacsi at scone> sudo dmesg -a | grep -i ugen
ugen0: SchlumbergerSema SchlumbergerSema Cryptoflex e-gate, rev 1.10/1.00, addr 2
ugen_set_config: ugen0 to configno 1, sc=0xc2389000
ugen_set_config: ifaceno 0
ugen_set_config: ifaceno 0

Starting the openct framework.

mohacsi at scone> sudo /usr/local/etc/rc.d/openct.sh start
Starting smart card terminal framework: OpenCTDebug: ifd_driver_add_id: ifd_driver_add_id(usb:0973/0001, egate)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:0529/050c, etoken)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:0529/0514, etoken)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:073d/0005, eutron)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:04b9/1202, ikey2k)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:04b9/1300, ikey3k)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:08e6/3437, ccid)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:08e6/3438, ccid)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:08e6/4433, ccid)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:04e6/5115, ccid)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:04e6/E001, ccid)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:04e6/E003, ccid)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:076b/3021, ccid)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:076b/5121, ccid)
Debug: ifd_driver_add_id: ifd_driver_add_id(usb:0783/0003, ccid)
Debug: ifd_scan_usb: BSD: ifd_scan_usb
Debug: ifd_scan_usb: BSD: ifd_scan_usb: ifd_driver_for(SchlumbergerSema[0x0973].SchlumbergerSema Cryptoflex e-gate[0x0001)
Debug: ifd_spawn_handler: driver=egate, device=/dev/ugen0, index=-1
.

e-gate smart card found!

mohacsi at scone> dmesg -a | grep -i ugen
ugenopen: flag=2051, mode=8192, unit=0 endpt=0


ATR command succesful form openct-tool.

mohacsi at scone> sudo openct-tool atr
Detected Schlumberger E-Gate
Card present, status changed
ATR: 3b 95 18 40 ff 62 01 02 01 04

the kernel messages from ugen:

ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f
ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f
ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f

opensc-tool -a already fails...

mohacsi at scone> sudo opensc-tool -a
3B 95 18 40 FF 62 01 02 01 04 ;.. at .b....
card.c:203:sc_transceive: Unable to transmit: Generic reader error
card.c:238:sc_transmit_apdu: transceive() failed: Generic reader error
card-flex.c:1316:flex_logout: APDU transmit failed: Generic reader error

kernel messages from ugen:

ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f
ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f
ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f
ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f
ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f

p15dump also fails:

mohacsi at scone> sudo ./p15dump
Using libopensc version 0.9.6.
Card detected in reader 'Schlumberger E-Gate'
Connecting... connected.
ATR = 3B:95:18:40:FF:62:01:02:01:04
Looking for a PKCS#15 compatible Smart Card... pkcs15.c:480:sc_pkcs15_bind_internal: unable to enumerate apps: Generic reader error
pkcs15.c:647:sc_pkcs15_bind: returning with: Wrong card
failed: Wrong card

kernel messages:

ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f
ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f
ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f
ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f
ugenioctl: cmd=80045572
ugenioctl: cmd=c018556f

debug messages:


mohacsi at scone> cat /var/log/debug.log


Jul 21 12:34:39 scone ifdhandler[677]: ifd_open: trying to open egate@/dev/ugen0
Jul 21 12:34:39 scone ifdhandler[677]: eg_open: device=/dev/ugen0
Jul 21 12:34:39 scone ifdhandler[677]: ifd_sysdep_device_type: BSD: ifd_sysdep_device_type(/dev/ugen0)
Jul 21 12:34:39 scone ifdhandler[677]: ifd_sysdep_device_type: BSD: returning IFD_DEVICE_TYPE_USB
Jul 21 12:34:39 scone ifdhandler[677]: eg_activate: called.
Jul 21 12:34:39 scone ifdhandler[677]: eg_card_status: slot=0
Jul 21 12:34:39 scone ifdhandler[677]: ifdhandler_poll_presence: card status change: 0 -> 1
Jul 21 12:34:40 scone ifdhandler[677]: eg_card_status: slot=0
Jul 21 12:35:01 scone last message repeated 21 times
Jul 21 12:35:01 scone ifdhandler[677]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_LOCK, unit=0)
Jul 21 12:35:01 scone ifdhandler[677]: ifdhandler_lock: granted shared lock 0 for slot 0 by uid=4294967294
Jul 21 12:35:01 scone ifdhandler[677]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_RESET, unit=0)
Jul 21 12:35:01 scone ifdhandler[677]: eg_card_reset: called.
Jul 21 12:35:01 scone ifdhandler[677]: ifd_usb_control: usb req type=x40 req=x90 val=x0000 ind=x0000 len=0
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x90)
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0x40 bRequest 0x90 wValue 0x0 wIndex 0x0 wLength 0x0
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ctrl.ucr_data == NULL 
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0xa0)
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0xa0 wValue 0x0 wIndex 0x0 wLength 0x1
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL SEND data  a8
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL RECV data  00
Jul 21 12:35:01 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 req=xa0 val=x0000 ind=x0000 len=1
Jul 21 12:35:01 scone ifdhandler[677]: ifd_usb_control: recv  00
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x83)
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0x83 wValue 0x0 wIndex 0x0 wLength 0x23
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL SEND data  01 00 00 00 14 ac bf bf 00 01 07 28 81 23 05 01 64 ca 56 0d 66 84 07 28 00 01 07 28 b0 ab bf bf 05 00 00
Jul 21 12:35:01 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL RECV data  3b 95 18 40 ff 62 01 02 01 04
Jul 21 12:35:01 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 req=x83 val=x0000 ind=x0000 len=10
Jul 21 12:35:01 scone ifdhandler[677]: ifd_usb_control: recv  3b 95 18 40 ff 62 01 02 01 04
Jul 21 12:35:01 scone ifdhandler[677]: ifd_protocol_select: atr= 3b 95 18 40 ff 62 01 02 01 04
Jul 21 12:35:01 scone ifdhandler[677]: ifd_protocol_select: default T=0, supported protocols=0x1
Jul 21 12:35:01 scone ifdhandler[677]: eg_set_protocol: proto=0
Jul 21 12:35:01 scone ifdhandler[677]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_UNLOCK, unit=0)
Jul 21 12:35:01 scone ifdhandler[677]: ifdhandler_unlock: released shared lock 0 for slot 0 by uid=4294967294
Jul 21 12:35:02 scone ifdhandler[677]: eg_card_status: slot=0
Jul 21 12:35:33 scone last message repeated 31 times
Jul 21 12:36:09 scone last message repeated 36 times
Jul 21 12:36:09 scone ifdhandler[677]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_RESET, unit=0)
Jul 21 12:36:09 scone ifdhandler[677]: eg_card_reset: called.
Jul 21 12:36:09 scone ifdhandler[677]: ifd_usb_control: usb req type=x40 req=x90 val=x0000 ind=x0000 len=0
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x90)
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0x40 bRequest 0x90 wValue 0x0 wIndex 0x0 wLength 0x0
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ctrl.ucr_data == NULL 
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0xa0)
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0xa0 wValue 0x0 wIndex 0x0 wLength 0x1
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL SEND data  44
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL RECV data  00
Jul 21 12:36:09 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 req=xa0 val=x0000 ind=x0000 len=1
Jul 21 12:36:09 scone ifdhandler[677]: ifd_usb_control: recv  00
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x83)
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0x83 wValue 0x0 wIndex 0x0 wLength 0x23
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL SEND data  01 00 00 00 f4 ab bf bf 01 00 00 00 00 00 00 00 01 00 00 00 00 01 07 28 1f de 11 28 3c 38 09 28 60 d3 04
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL RECV data  3b 95 18 40 ff 62 01 02 01 04
Jul 21 12:36:09 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 req=x83 val=x0000 ind=x0000 len=10
Jul 21 12:36:09 scone ifdhandler[677]: ifd_usb_control: recv  3b 95 18 40 ff 62 01 02 01 04
Jul 21 12:36:09 scone ifdhandler[677]: ifd_protocol_select: atr= 3b 95 18 40 ff 62 01 02 01 04
Jul 21 12:36:09 scone ifdhandler[677]: ifd_protocol_select: default T=0, supported protocols=0x1
Jul 21 12:36:09 scone ifdhandler[677]: eg_set_protocol: proto=0
Jul 21 12:36:09 scone ifdhandler[677]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_LOCK, unit=0)
Jul 21 12:36:09 scone ifdhandler[677]: ifdhandler_lock: granted excl lock 1 for slot 0 by uid=4294967294
Jul 21 12:36:09 scone ifdhandler[677]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_TRANSACT, unit=0)
Jul 21 12:36:09 scone ifdhandler[677]: ifd_protocol_transceive: cmd:  f0 22 07 00
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0xa0)
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0xa0 wValue 0x0 wIndex 0x0 wLength 0x1
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL SEND data  28
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL RECV data  00
Jul 21 12:36:09 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 req=xa0 val=x0000 ind=x0000 len=1
Jul 21 12:36:09 scone ifdhandler[677]: ifd_usb_control: recv  00
Jul 21 12:36:09 scone ifdhandler[677]: ifd_usb_control: usb req type=x40 req=x80 val=x0000 ind=x0000 len=5
Jul 21 12:36:09 scone ifdhandler[677]: ifd_usb_control: send  f0 22 07 00 00
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x80)
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0x40 bRequest 0x80 wValue 0x0 wIndex 0x0 wLength 0x5
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL SEND data  f0 22 07 00 00
Jul 21 12:36:09 scone ifdhandler[677]: ifd_sysdep_usb_control: USB_DO_REQUEST failed: -1
Jul 21 12:36:09 scone ifdhandler[677]: ifd_protocol_transceive: transceive error: Communication error
Jul 21 12:36:09 scone ifdhandler[677]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_UNLOCK, unit=0)
Jul 21 12:36:09 scone ifdhandler[677]: ifdhandler_unlock: released excl lock 1 for slot 0 by uid=4294967294
Jul 21 12:36:10 scone ifdhandler[677]: eg_card_status: slot=0
Jul 21 12:36:41 scone last message repeated 31 times
Jul 21 12:37:34 scone last message repeated 53 times
Jul 21 12:37:34 scone ifdhandler[677]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_RESET, unit=0)
Jul 21 12:37:34 scone ifdhandler[677]: eg_card_reset: called.
Jul 21 12:37:34 scone ifdhandler[677]: ifd_usb_control: usb req type=x40 req=x90 val=x0000 ind=x0000 len=0
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x90)
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0x40 bRequest 0x90 wValue 0x0 wIndex 0x0 wLength 0x0
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ctrl.ucr_data == NULL 
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0xa0)
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0xa0 wValue 0x0 wIndex 0x0 wLength 0x1
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL SEND data  c4
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL RECV data  00
Jul 21 12:37:34 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 req=xa0 val=x0000 ind=x0000 len=1
Jul 21 12:37:34 scone ifdhandler[677]: ifd_usb_control: recv  00
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x83)
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0x83 wValue 0x0 wIndex 0x0 wLength 0x23
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL SEND data  d1 a9 09 28 e9 a9 09 28 fd a9 09 28 0d aa 09 28 23 aa 09 28 35 aa 09 28 1f de 11 28 3c 38 09 28 60 d3 04
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL RECV data  3b 95 18 40 ff 62 01 02 01 04
Jul 21 12:37:34 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 req=x83 val=x0000 ind=x0000 len=10
Jul 21 12:37:34 scone ifdhandler[677]: ifd_usb_control: recv  3b 95 18 40 ff 62 01 02 01 04
Jul 21 12:37:34 scone ifdhandler[677]: ifd_protocol_select: atr= 3b 95 18 40 ff 62 01 02 01 04
Jul 21 12:37:34 scone ifdhandler[677]: ifd_protocol_select: default T=0, supported protocols=0x1
Jul 21 12:37:34 scone ifdhandler[677]: eg_set_protocol: proto=0
Jul 21 12:37:34 scone ifdhandler[677]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_LOCK, unit=0)
Jul 21 12:37:34 scone ifdhandler[677]: ifdhandler_lock: granted excl lock 2 for slot 0 by uid=4294967294
Jul 21 12:37:34 scone ifdhandler[677]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_TRANSACT, unit=0)
Jul 21 12:37:34 scone ifdhandler[677]: ifd_protocol_transceive: cmd:  c0 a4 00 00 02 3f 00
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0xa0)
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0xa0 wValue 0x0 wIndex 0x0 wLength 0x1
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL SEND data  28
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL RECV data  00
Jul 21 12:37:34 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 req=xa0 val=x0000 ind=x0000 len=1
Jul 21 12:37:34 scone ifdhandler[677]: ifd_usb_control: recv  00
Jul 21 12:37:34 scone ifdhandler[677]: ifd_usb_control: usb req type=x40 req=x80 val=x0000 ind=x0000 len=5
Jul 21 12:37:34 scone ifdhandler[677]: ifd_usb_control: send  c0 a4 00 00 02
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x80)
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0x40 bRequest 0x80 wValue 0x0 wIndex 0x0 wLength 0x5
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL SEND data  c0 a4 00 00 02
Jul 21 12:37:34 scone ifdhandler[677]: ifd_sysdep_usb_control: USB_DO_REQUEST failed: -1
Jul 21 12:37:34 scone ifdhandler[677]: ifd_protocol_transceive: transceive error: Communication error
Jul 21 12:37:34 scone ifdhandler[677]: ifdhandler_unlock_all: released excl lock 2 for slot 0 by uid=4294967294
Jul 21 12:37:35 scone ifdhandler[677]: eg_card_status: slot=0
Jul 21 12:38:06 scone last message repeated 31 times

>How-To-Repeat:

	Try executing command "opensc-tool -a"
	
>Fix:

 unknown yet...


>Release-Note:
>Audit-Trail:
>Unformatted:

More information about the freebsd-usb mailing list