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

[Janos Mohacsi]

The following reply was made to PR usb/83863; it has been noted by GNATS.

From: Janos Mohacsi <mohacsi at niif.hu>
To: bug-followup at FreeBSD.org, janos.mohacsi at bsd.hu, hselasky at c2i.net
Cc:  
Subject: Re: usb/83863: Communication problem between opensc/openct via USB
 with e-gate smart-card.
Date: Thu, 04 Aug 2005 03:40:29 +0200

 I tried new usb subsystem with similar results:
 debug output of ifdhandler:
 Aug  4 02:56:48 scone ifdhandler[677]: ifd_open: trying to open 
 egate@/dev/ugen0
 Aug  4 02:56:48 scone ifdhandler[677]: eg_open: device=/dev/ugen0
 Aug  4 02:56:48 scone ifdhandler[677]: ifd_sysdep_device_type: BSD: 
 ifd_sysdep_device_type(/dev/ugen0)
 Aug  4 02:56:48 scone ifdhandler[677]: ifd_sysdep_device_type: BSD: 
 returning IFD_DEVICE_TYPE_USB
 Aug  4 02:56:48 scone ifdhandler[677]: eg_activate: called.
 Aug  4 02:56:48 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:56:48 scone ifdhandler[677]: ifdhandler_poll_presence: card 
 status change: 0 -> 1
 Aug  4 02:56:49 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:56:58 scone last message repeated 9 times
 Aug  4 02:56:59 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:57:08 scone last message repeated 9 times
 Aug  4 02:57:09 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:57:18 scone last message repeated 9 times
 Aug  4 02:57:19 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:57:28 scone last message repeated 9 times
 Aug  4 02:57:29 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:57:38 scone last message repeated 9 times
 Aug  4 02:57:39 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:57:49 scone last message repeated 10 times
 Aug  4 02:57:50 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:57:59 scone last message repeated 9 times
 Aug  4 02:58:00 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:58:08 scone last message repeated 8 times
 Aug  4 02:58:09 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:58:14 scone last message repeated 5 times
 Aug  4 02:58:14 scone ifdhandler[677]: ifdhandler_process: 
 ifdhandler_process(cmd=CT_CMD_RESET, unit=0)
 Aug  4 02:58:14 scone ifdhandler[677]: eg_card_reset: called.
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_usb_control: usb req type=x40 
 req=x90 val=x0000 ind=x0000 len=0
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: 
 ifd_sysdep_usb_control(0x90)
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 bmRequestType 0x40 bRequest 0x90 wValue 0x0 wIndex 0x0 wLength 0x0
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: 
 ctrl.ucr_data == NULL
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: 
 ifd_sysdep_usb_control(0xa0)
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 bmRequestType 0xc0 bRequest 0xa0 wValue 0x0 wIndex 0x0 wLength 0x1
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 SEND data  80
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 RECV data  00
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 
 req=xa0 val=x0000 ind=x0000 len=1
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_usb_control: recv  00
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: 
 ifd_sysdep_usb_control(0x83)
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 bmRequestType 0xc0 bRequest 0x83 wValue 0x0 wIndex 0x0 wLength 0x23
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 SEND data  05 00 00 00 05 00 00 00 00 01 07 28 00 00 00 01 64 ca 56 0d 
 66 84 07 28 00 01 07 28 a0 ab bf bf 05 00 00
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 RECV data  3b 95 18 40 ff 62 01 02 01 04
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 
 req=x83 val=x0000 ind=x0000 len=10
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_usb_control: recv  3b 95 18 
 40 ff 62 01 02 01 04
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_protocol_select: atr= 3b 95 
 18 40 ff 62 01 02 01 04
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_protocol_select: default T=0, 
 supported protocols=0x1
 Aug  4 02:58:14 scone ifdhandler[677]: eg_set_protocol: proto=0
 Aug  4 02:58:14 scone ifdhandler[677]: ifdhandler_process: 
 ifdhandler_process(cmd=CT_CMD_LOCK, unit=0)
 Aug  4 02:58:14 scone ifdhandler[677]: ifdhandler_lock: granted excl 
 lock 0 for slot 0 by uid=4294967294
 Aug  4 02:58:14 scone ifdhandler[677]: ifdhandler_process: 
 ifdhandler_process(cmd=CT_CMD_TRANSACT, unit=0)
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_protocol_transceive: cmd:  c0 
 a4 00 00 02 3f 00
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: 
 ifd_sysdep_usb_control(0xa0)
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 bmRequestType 0xc0 bRequest 0xa0 wValue 0x0 wIndex 0x0 wLength 0x1
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 SEND data  28
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 RECV data  00
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_usb_control: usb req type=xc0 
 req=xa0 val=x0000 ind=x0000 len=1
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_usb_control: recv  00
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_usb_control: usb req type=x40 
 req=x80 val=x0000 ind=x0000 len=5
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_usb_control: send  c0 a4 00 00 02
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: 
 ifd_sysdep_usb_control(0x80)
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 bmRequestType 0x40 bRequest 0x80 wValue 0x0 wIndex 0x0 wLength 0x5
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: BSD: CTRL 
 SEND data  c0 a4 00 00 02
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_sysdep_usb_control: 
 USB_DO_REQUEST failed: -1
 Aug  4 02:58:14 scone ifdhandler[677]: ifd_protocol_transceive: 
 transceive error : Communication error
 Aug  4 02:58:14 scone ifdhandler[677]: ifdhandler_unlock_all: released 
 excl lock 0 for slot 0 by uid=4294967294
 Aug  4 02:58:15 scone ifdhandler[677]: eg_card_status: slot=0
 Aug  4 02:58:19 scone last message repeated 4 times
 
 The kernel debug message in the same time (from /var/log/messages):
 Aug  4 02:55:54 scone kernel: usb_event_thread: woke up
 Aug  4 02:55:54 scone kernel: usb_discover:
 Aug  4 02:56:08 scone kernel: usb_event_thread: woke up
 Aug  4 02:56:08 scone kernel: usb_discover:
 Aug  4 02:56:10 scone kernel: usb_event_thread: woke up
 Aug  4 02:56:10 scone kernel: usb_discover:
 Aug  4 02:56:48 scone kernel: ugenopen: flag=2051, mode=8192
 Aug  4 02:56:54 scone kernel: usb_event_thread: woke up
 Aug  4 02:56:54 scone kernel: usb_discover:
 Aug  4 02:57:08 scone kernel: usb_event_thread: woke up
 Aug  4 02:57:08 scone kernel: usb_discover:
 Aug  4 02:57:09 scone kernel: usb_event_thread: woke up
 Aug  4 02:57:09 scone kernel: usb_discover:
 Aug  4 02:57:54 scone kernel: usb_event_thread: woke up
 Aug  4 02:57:54 scone kernel: usb_discover:
 Aug  4 02:58:08 scone kernel: usb_event_thread: woke up
 Aug  4 02:58:08 scone kernel: usb_discover:
 Aug  4 02:58:09 scone kernel: usb_event_thread: woke up
 Aug  4 02:58:09 scone kernel: usb_discover:
 Aug  4 02:58:14 scone kernel: ugenioctl: cmd=80045572
 Aug  4 02:58:14 scone kernel: ugenioctl: cmd=c018556f
 Aug  4 02:58:14 scone kernel: usbd_transfer_setup: using global lock
 Aug  4 02:58:14 scone kernel: usbd_get_pipe: udev=0xc1b7e000 
 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
 Aug  4 02:58:14 scone kernel: usbd_get_pipe: udev=0xc1b7e000 
 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
 Aug  4 02:58:14 scone kernel: usbd_start_hardware: xfer=0xc1c45c00, 
 pipe=0xc1b7e004 len=8 dir=out
 Aug  4 02:58:14 scone kernel: usbd_dump_pipe: pipe=0xc1b7e004 
 edesc=0xc1b7e020 isoc_next=0 toggle_next=1 bEndpointAddress=0x00
 Aug  4 02:58:14 scone kernel: usbd_dump_queue: pipe=0xc1b7e004
 Aug  4 02:58:14 scone kernel: usbd_transfer_done: xfer=0xc1c45c00 
 pipe=0xc1b7e004 status=0 actlen=8
 Aug  4 02:58:14 scone kernel: usbd_transfer_done: xfer=0xc1c45c00 
 pipe=0xc1b7e004 status=5 actlen=8
 Aug  4 02:58:14 scone kernel: ugenioctl: cmd=80045572
 Aug  4 02:58:14 scone kernel: ugenioctl: cmd=c018556f
 Aug  4 02:58:14 scone kernel: usbd_transfer_setup: using global lock
 Aug  4 02:58:14 scone kernel: usbd_get_pipe: udev=0xc1b7e000 
 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
 Aug  4 02:58:14 scone kernel: usbd_get_pipe: udev=0xc1b7e000 
 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
 Aug  4 02:58:14 scone kernel: usbd_start_hardware: xfer=0xc1c61400, 
 pipe=0xc1b7e004 len=9 dir=out
 Aug  4 02:58:14 scone kernel: usbd_dump_pipe: pipe=0xc1b7e004 
 edesc=0xc1b7e020 isoc_next=0 toggle_next=1 bEndpointAddress=0x00
 Aug  4 02:58:14 scone kernel: usbd_dump_queue: pipe=0xc1b7e004
 Aug  4 02:58:14 scone kernel: usbd_transfer_done: xfer=0xc1c61400 
 pipe=0xc1b7e004 status=0 actlen=9
 Aug  4 02:58:14 scone kernel: usbd_transfer_done: xfer=0xc1c61400 
 pipe=0xc1b7e004 status=5 actlen=9
 Aug  4 02:58:14 scone kernel: ugenioctl: cmd=80045572
 Aug  4 02:58:14 scone kernel: ugenioctl: cmd=c018556f
 Aug  4 02:58:14 scone kernel: usbd_transfer_setup: using global lock
 Aug  4 02:58:14 scone kernel: usbd_get_pipe: udev=0xc1b7e000 
 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
 Aug  4 02:58:14 scone kernel: usbd_get_pipe: udev=0xc1b7e000 
 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
 Aug  4 02:58:14 scone kernel: usbd_start_hardware: xfer=0xc1b84000, 
 pipe=0xc1b7e004 len=43 dir=out
 Aug  4 02:58:14 scone kernel: usbd_dump_pipe: pipe=0xc1b7e004 
 edesc=0xc1b7e020 isoc_next=0 toggle_next=1 bEndpointAddress=0x00
 Aug  4 02:58:14 scone kernel: usbd_dump_queue: pipe=0xc1b7e004
 Aug  4 02:58:14 scone kernel: usbd_transfer_done: xfer=0xc1b84000 
 pipe=0xc1b7e004 status=0 actlen=18
 Aug  4 02:58:14 scone kernel: usbd_transfer_done: xfer=0xc1b84000 
 pipe=0xc1b7e004 status=5 actlen=18
 Aug  4 02:58:14 scone kernel: ugenioctl: cmd=80045572
 Aug  4 02:58:14 scone kernel: ugenioctl: cmd=c018556f
 Aug  4 02:58:14 scone kernel: usbd_transfer_setup: using global lock
 Aug  4 02:58:14 scone kernel: usbd_get_pipe: udev=0xc1b7e000 
 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
 Aug  4 02:58:14 scone kernel: usbd_get_pipe: udev=0xc1b7e000 
 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
 Aug  4 02:58:14 scone kernel: usbd_start_hardware: xfer=0xc1a39000, 
 pipe=0xc1b7e004 len=9 dir=out
 Aug  4 02:58:14 scone kernel: usbd_dump_pipe: pipe=0xc1b7e004 
 edesc=0xc1b7e020 isoc_next=0 toggle_next=1 bEndpointAddress=0x00
 Aug  4 02:58:14 scone kernel: usbd_dump_queue: pipe=0xc1b7e004
 Aug  4 02:58:14 scone kernel: usbd_transfer_done: xfer=0xc1a39000 
 pipe=0xc1b7e004 status=0 actlen=9
 Aug  4 02:58:14 scone kernel: usbd_transfer_done: xfer=0xc1a39000 
 pipe=0xc1b7e004 status=5 actlen=9
 Aug  4 02:58:14 scone kernel: ugenioctl: cmd=80045572
 Aug  4 02:58:14 scone kernel: ugenioctl: cmd=c018556f
 Aug  4 02:58:14 scone kernel: usbd_transfer_setup: using global lock
 Aug  4 02:58:14 scone kernel: usbd_get_pipe: udev=0xc1b7e000 
 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
 Aug  4 02:58:14 scone kernel: usbd_get_pipe: udev=0xc1b7e000 
 iface_index=0 address=0x0 type=0x0 dir=0xff index=0
 Aug  4 02:58:14 scone kernel: usbd_start_hardware: xfer=0xc1b7ec00, 
 pipe=0xc1b7e004 len=13 dir=out
 Aug  4 02:58:14 scone kernel: usbd_dump_pipe: pipe=0xc1b7e004 
 edesc=0xc1b7e020 isoc_next=0 toggle_next=1 bEndpointAddress=0x00
 Aug  4 02:58:14 scone kernel: usbd_dump_queue: pipe=0xc1b7e004
 Aug  4 02:58:14 scone kernel: usbd_transfer_done: xfer=0xc1b7ec00 
 pipe=0xc1b7e004 status=22 actlen=13
 Aug  4 02:58:14 scone kernel: usbd_transfer_done: xfer=0xc1b7ec00 
 pipe=0xc1b7e004 status=5 actlen=13
 Aug  4 02:58:14 scone ifdhandler[677]: usb_do_request failed: 
 Input/output error (5)
 Aug  4 02:58:54 scone kernel: usb_event_thread: woke up
 Aug  4 02:58:54 scone kernel: usb_discover:
 Aug  4 02:59:08 scone kernel: usb_event_thread: woke up
 Aug  4 02:59:08 scone kernel: usb_discover:
 Aug  4 02:59:10 scone kernel: usb_event_thread: woke up
 Aug  4 02:59:10 scone kernel: usb_discover:
 
 Next?