From owner-freebsd-usb@FreeBSD.ORG Thu Aug 4 01:50:19 2005 Return-Path: X-Original-To: freebsd-usb@hub.freebsd.org Delivered-To: freebsd-usb@hub.freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 436B116A41F for ; Thu, 4 Aug 2005 01:50:19 +0000 (GMT) (envelope-from gnats@FreeBSD.org) Received: from freefall.freebsd.org (freefall.freebsd.org [216.136.204.21]) by mx1.FreeBSD.org (Postfix) with ESMTP id A999E43D48 for ; Thu, 4 Aug 2005 01:50:18 +0000 (GMT) (envelope-from gnats@FreeBSD.org) Received: from freefall.freebsd.org (gnats@localhost [127.0.0.1]) by freefall.freebsd.org (8.13.3/8.13.3) with ESMTP id j741oIf1097272 for ; Thu, 4 Aug 2005 01:50:18 GMT (envelope-from gnats@freefall.freebsd.org) Received: (from gnats@localhost) by freefall.freebsd.org (8.13.3/8.13.1/Submit) id j741oIB4097271; Thu, 4 Aug 2005 01:50:18 GMT (envelope-from gnats) Date: Thu, 4 Aug 2005 01:50:18 GMT Message-Id: <200508040150.j741oIB4097271@freefall.freebsd.org> To: freebsd-usb@FreeBSD.org From: Janos Mohacsi Cc: Subject: Re: usb/83863: Communication problem between opensc/openct via USB with e-gate smart-card. X-BeenThere: freebsd-usb@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list Reply-To: Janos Mohacsi List-Id: FreeBSD support for USB List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Thu, 04 Aug 2005 01:50:19 -0000 The following reply was made to PR usb/83863; it has been noted by GNATS. From: Janos Mohacsi To: bug-followup@FreeBSD.org, janos.mohacsi@bsd.hu, hselasky@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?