Raspbian, Option GIO 225 3G modem is not working.

Tue Jun 17 01:34:21 PDT 2014

I suspect there is some problem with the hso driver in Raspbian. After
I have activated debug in the module (modprobe hso debug=0x01) I get
the following:
[  609.950160] usbcore: deregistering interface driver hso
[  622.352862] hso: drivers/net/usb/hso.c: Option Wireless
[  622.353102] [2420:hso_net_init]: sizeof hso_net is 100
[  622.362060] usbcore: registered new interface driver hso
[  754.171387] [1293:hso_serial_open]: Opening 0
[  754.177382] [1293:hso_serial_open]: Opening 1
[  754.180811] [1293:hso_serial_open]: Opening 2
[  754.280387] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  754.283262] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  754.286163] [1946:hso_std_serial_write_bulk_callback]:
[  757.180526] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  757.182145] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  757.183400] [1946:hso_std_serial_write_bulk_callback]:
[  760.181674] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  760.182140] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  760.183542] [1946:hso_std_serial_write_bulk_callback]:
[  763.179192] [1337:hso_serial_close]: Closing serial port
[  763.180954] [1293:hso_serial_open]: Opening 0
[  763.182293] [1337:hso_serial_close]: Closing serial port
[  763.183739] [1293:hso_serial_open]: Opening 1
[  763.190944] [1337:hso_serial_close]: Closing serial port
[  763.191010] [709:handle_usb_error]:
hso_std_serial_read_bulk_callback: received USB status - endpoint not
enabled (-2)
[  763.195561] [1293:hso_serial_open]: Opening 2
[  763.198045] [1946:hso_std_serial_write_bulk_callback]:
[  763.198694] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  763.199150] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  763.200928] [1946:hso_std_serial_write_bulk_callback]:
[  763.201013] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  763.201535] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  763.202910] [1946:hso_std_serial_write_bulk_callback]:
[  763.204191] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  763.205780] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  763.207157] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  763.207268] [1946:hso_std_serial_write_bulk_callback]:
[  766.180060] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  766.185039] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  766.187466] [1946:hso_std_serial_write_bulk_callback]:
[  766.188291] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  766.188643] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  766.190906] [1946:hso_std_serial_write_bulk_callback]:
[  766.192670] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  766.192918] [1946:hso_std_serial_write_bulk_callback]:
[  766.193009] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  766.193542] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  766.194009] [709:handle_usb_error]: ctrl_callback: received USB
status - endpoint stalled (-32)
[  766.196684] [1946:hso_std_serial_write_bulk_callback]:
[  769.180437] [1337:hso_serial_close]: Closing serial port
[  769.183768] [1337:hso_serial_close]: Closing serial port
[  769.183831] [709:handle_usb_error]: intr_callback: received USB
status - endpoint not enabled (-2)
[  769.188667] [1337:hso_serial_close]: Closing serial port
[  769.188731] [709:handle_usb_error]:
hso_std_serial_read_bulk_callback: received USB status - endpoint not
enabled (-2)

I will do some more investigation and see what I come up with.
Mvh Niclas

On Mon, Jun 16, 2014 at 6:37 PM, Dan Williams <dcbw at redhat.com> wrote:
> On Mon, 2014-06-16 at 16:29 +0200, Aleksander Morgado wrote:
>> Hey,
>>
>> >
>> > I'm trying to use a Option GIO 225 3G modem on Raspberry Pi running
>> > latest Raspbian, but it's not working. In Ubuntu 10.04 and Mint 14 on
>> > x86 the modem is working as expected.
>> > On the Raspberry, the kernel module is loaded and three entries in
>> > /dev/ is created.
>> > pi at raspberrypi:~$ ls /dev/ttyHS*
>> > /dev/ttyHS0  /dev/ttyHS1  /dev/ttyHS2
>> >
>> > But the network manager can't find any modem. Please see the debug
>> > info from the modemmanager when I plug in the modem. What is going
>> > wrong?
>> >
>> >
>> > pi at raspberrypi:~$ modem-manager[2657]: <info>  [1402925910.352299]
>> > [mm-serial-port.c:741] mm_serial_port_open(): (ttyHS2) opening serial
>> > port...
>> > modem-manager[2657]: <debug> [1402925910.365466]
>> > [mm-serial-port.c:810] mm_serial_port_open(): (ttyHS2) device open
>> > count is 1 (open)
>> > modem-manager[2657]: <debug> [1402925910.366781]
>> > [mm-plugin-base.c:859] try_open(): (ttyHS2): probe requested by plugin
>> > 'Option High-Speed'
>> > modem-manager[2657]: <info>  [1402925910.703974]
>> > [mm-serial-port.c:741] mm_serial_port_open(): (ttyHS0) opening serial
>> > port...
>> > modem-manager[2657]: <debug> [1402925910.715510]
>> > [mm-serial-port.c:810] mm_serial_port_open(): (ttyHS0) device open
>> > count is 1 (open)
>> > modem-manager[2657]: <debug> [1402925910.717249]
>> > [mm-plugin-base.c:859] try_open(): (ttyHS0): probe requested by plugin
>> > 'Option High-Speed'
>> > modem-manager[2657]: <info>  [1402925910.718722]
>> > [mm-serial-port.c:741] mm_serial_port_open(): (ttyHS1) opening serial
>> > port...
>> > modem-manager[2657]: <debug> [1402925910.720653]
>> > [mm-serial-port.c:810] mm_serial_port_open(): (ttyHS1) device open
>> > count is 1 (open)
>> > modem-manager[2657]: <debug> [1402925910.722087]
>> > [mm-plugin-base.c:859] try_open(): (ttyHS1): probe requested by plugin
>> > 'Option High-Speed'
>> > modem-manager[2657]: <debug> [1402925910.723543]
>> > [mm-at-serial-port.c:333] debug_log(): (ttyHS2): --> 'AT+GCAP<CR>'
>> > modem-manager[2657]: <debug> [1402925910.745707] [mm-modem-base.c:155]
>> > mm_modem_base_add_port(): (hso0) type ignored claimed by
>> > /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.4
>> > modem-manager[2657]: <info>  [1402925910.754522] [mm-manager.c:564]
>> > do_grab_port(): (Option High-Speed): GSM modem
>> > /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.4 claimed port
>> > hso0
>> > modem-manager[2657]: <debug> [1402925910.756246] [mm-manager.c:317]
>> > add_modem(): Added modem
>> > /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.3/1-1.3.4
>> > modem-manager[2657]: <debug> [1402925910.757878] [mm-manager.c:243]
>> > check_export_modem(): (tty/ttyHS0): outstanding support task prevents
>> > export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.34
>> > modem-manager[2657]: <debug> [1402925910.759798] [mm-manager.c:243]
>> > check_export_modem(): (tty/ttyHS0): outstanding support task prevents
>> > export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.34
>> > modem-manager[2657]: <debug> [1402925910.819967]
>> > [mm-at-serial-port.c:333] debug_log(): (ttyHS0): --> 'AT+GCAP<CR>'
>> > modem-manager[2657]: <debug> [1402925910.824519]
>> > [mm-at-serial-port.c:333] debug_log(): (ttyHS1): --> 'AT+GCAP<CR>'
>> > modem-manager[2657]: <debug> [1402925914.099878]
>> > [mm-at-serial-port.c:333] debug_log(): (ttyHS2): --> 'AT+GCAP<CR>'
>> > modem-manager[2657]: <debug> [1402925914.102202]
>> > [mm-at-serial-port.c:333] debug_log(): (ttyHS0): --> 'AT+GCAP<CR>'
>> > modem-manager[2657]: <debug> [1402925914.106841]
>> > [mm-at-serial-port.c:333] debug_log(): (ttyHS1): --> 'AT+GCAP<CR>'
>> > modem-manager[2657]: <debug> [1402925917.099027]
>> > [mm-at-serial-port.c:333] debug_log(): (ttyHS2): --> 'AT+GCAP<CR>'
>> > modem-manager[2657]: <debug> [1402925917.100706]
>> > [mm-at-serial-port.c:333] debug_log(): (ttyHS0): --> 'AT+GCAP<CR>'
>> > modem-manager[2657]: <debug> [1402925917.102228]
>> > [mm-at-serial-port.c:333] debug_log(): (ttyHS1): --> 'AT+GCAP<CR>'
>> > modem-manager[2657]: <debug> [1402925920.098629]
>> > [mm-serial-port.c:844] mm_serial_port_close(): (ttyHS2) device open
>> > count is 0 (close)
>> > modem-manager[2657]: <info>  [1402925920.100334]
>> > [mm-serial-port.c:859] mm_serial_port_close(): (ttyHS2) closing serial
>> > port...
>> > modem-manager[2657]: <info>  [1402925920.107369]
>> > [mm-serial-port.c:880] mm_serial_port_close(): (ttyHS2) serial port
>> > closed
>> > modem-manager[2657]: <info>  [1402925920.109194]
>> > [mm-serial-port.c:741] mm_serial_port_open(): (ttyHS2) opening serial
>> > port...
>> > modem-manager[2657]: <debug> [1402925920.111105]
>> > [mm-serial-port.c:810] mm_serial_port_open(): (ttyHS2) device open
>> > count is 1 (open)
>> > modem-manager[2657]: <debug> [1402925920.112710]
>> > [mm-serial-port.c:844] mm_serial_port_close(): (ttyHS0) device open
>> > count is 0 (close)
>> > modem-manager[2657]: <info>  [1402925920.112987]
>> > [mm-serial-port.c:859] mm_serial_port_close(): (ttyHS0) closing serial
>> > port...
>> > modem-manager[2657]: <info>  [1402925920.114536]
>> > [mm-serial-port.c:880] mm_serial_port_close(): (ttyHS0) serial port
>> > closed
>> > modem-manager[2657]: <info>  [1402925920.115607]
>> > [mm-serial-port.c:741] mm_serial_port_open(): (ttyHS0) opening serial
>> > port...
>> > modem-manager[2657]: <debug> [1402925920.116640]
>> > [mm-serial-port.c:810] mm_serial_port_open(): (ttyHS0) device open
>> > count is 1 (open)
>> > modem-manager[2657]: <debug> [1402925920.117112]
>> > [mm-serial-port.c:844] mm_serial_port_close(): (ttyHS1) device open
>> > count is 0 (close)
>> > modem-manager[2657]: <info>  [1402925920.117347]
>> > [mm-serial-port.c:859] mm_serial_port_close(): (ttyHS1) closing serial
>> > port...
>> > modem-manager[2657]: <info>  [1402925920.119363]
>> > [mm-serial-port.c:880] mm_serial_port_close(): (ttyHS1) serial port
>> > closed
>> > modem-manager[2657]: <info>  [1402925920.121401]
>> > [mm-serial-port.c:741] mm_serial_port_open(): (ttyHS1) opening serial
>> > port...
>> > modem-manager[2657]: <debug> [1402925920.123141]
>> > [mm-serial-port.c:810] mm_serial_port_open(): (ttyHS1) device open
>> > count is 1 (open)
>> > modem-manager[2657]: <debug> [1402925920.125018]
>> > [mm-qcdm-serial-port.c:196] debug_log(): (ttyHS2): --> 00 78 f0 7e
>> > modem-manager[2657]: <debug> [1402925920.126638]
>> > [mm-qcdm-serial-port.c:196] debug_log(): (ttyHS0): --> 00 78 f0 7e
>> > modem-manager[2657]: <debug> [1402925920.128156]
>> > [mm-qcdm-serial-port.c:196] debug_log(): (ttyHS1): --> 00 78 f0 7e
>> > modem-manager[2657]: <debug> [1402925923.098980]
>> > [mm-qcdm-serial-port.c:196] debug_log(): (ttyHS2): --> 00 78 f0 7e
>> > modem-manager[2657]: <debug> [1402925923.105152]
>> > [mm-qcdm-serial-port.c:196] debug_log(): (ttyHS0): --> 00 78 f0 7e
>> > modem-manager[2657]: <debug> [1402925923.105676]
>> > [mm-qcdm-serial-port.c:196] debug_log(): (ttyHS1): --> 00 78 f0 7e
>> > modem-manager[2657]: <debug> [1402925923.114330]
>> > [mm-qcdm-serial-port.c:196] debug_log(): (ttyHS2): <-- 00 46 65 62 20
>> > 31 38 20 32 30 30 38 31 38 3a 33 32 3a 34 30 4a 61 6e 20 31 38 20 32
>> > 30 30 38e
>> > modem-manager[2657]: <debug> [1402925923.115245]
>> > [mm-serial-port.c:844] mm_serial_port_close(): (ttyHS2) device open
>> > count is 0 (close)
>> > modem-manager[2657]: <info>  [1402925923.115493]
>> > [mm-serial-port.c:859] mm_serial_port_close(): (ttyHS2) closing serial
>> > port...
>> > modem-manager[2657]: <info>  [1402925923.124854]
>> > [mm-serial-port.c:880] mm_serial_port_close(): (ttyHS2) serial port
>> > closed
>> > modem-manager[2657]: <debug> [1402925923.125384] [mm-manager.c:624]
>> > supports_callback(): (tty/ttyHS2): ignoring port unsupported by
>> > physical modem's plugin
>> > modem-manager[2657]: <debug> [1402925923.125738] [mm-manager.c:243]
>> > check_export_modem(): (tty/ttyHS0): outstanding support task prevents
>> > export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.34
>> > modem-manager[2657]: <debug> [1402925926.099024]
>> > [mm-serial-port.c:844] mm_serial_port_close(): (ttyHS0) device open
>> > count is 0 (close)
>> > modem-manager[2657]: <info>  [1402925926.099371]
>> > [mm-serial-port.c:859] mm_serial_port_close(): (ttyHS0) closing serial
>> > port...
>> > modem-manager[2657]: <info>  [1402925926.101302]
>> > [mm-serial-port.c:880] mm_serial_port_close(): (ttyHS0) serial port
>> > closed
>> > modem-manager[2657]: <debug> [1402925926.101833] [mm-manager.c:624]
>> > supports_callback(): (tty/ttyHS0): ignoring port unsupported by
>> > physical modem's plugin
>> > modem-manager[2657]: <debug> [1402925926.102274] [mm-manager.c:243]
>> > check_export_modem(): (tty/ttyHS1): outstanding support task prevents
>> > export of /sys/devices/platform/bcm2708_usb/usb1/1-1/1-1.34
>> > modem-manager[2657]: <debug> [1402925926.102689]
>> > [mm-serial-port.c:844] mm_serial_port_close(): (ttyHS1) device open
>> > count is 0 (close)
>> > modem-manager[2657]: <info>  [1402925926.102911]
>> > [mm-serial-port.c:859] mm_serial_port_close(): (ttyHS1) closing serial
>> > port...
>> > modem-manager[2657]: <info>  [1402925926.104326]
>> > [mm-serial-port.c:880] mm_serial_port_close(): (ttyHS1) serial port
>> > closed
>> > modem-manager[2657]: <debug> [1402925926.105262] [mm-manager.c:624]
>> > supports_callback(): (tty/ttyHS1): ignoring port unsupported by
>> > physical modem's plugin
>> >
>>
>>
>> Hum... the modem doesn't seem to reply to any command. How are you
>> connecting the modem to the RPi? Are you using an external *powered*
>> USB hub? Or just plugging it in one of the USB ports of the RPi? Note
>> that the RPi has a max output power in the USB ports, and likely the
>> modem won't work unless you use a external powered USB hub.
>
> To provide a baseline, my GIO225 is detected and connects OK with git
> master MM and 3.14.6 kernel.
>
> Dan
>