Re: Sierra Wireless dongle enable failed

[Dan Williams <dcbw redhat com>]

On Tue, 2010-05-25 at 15:58 +0200, Maxime Boure wrote:
> Hello everyone,
> 
> 
> I have 2 3G dongles. The first huawei works fine but I want to change
> it to a sierra dongle.
> This one connects itself well on my computer but on my embedded board
> it prints this :

Any idea how long the enable process takes on the Sierra device?
NetworkManager gives that process 20 seconds, and the CFUN=1 on Sierra
devices is on a 10 second wait as well (since some Sierra devices return
OK immediately even though they are not completely powered up at that
point).  So if the entire Enable() call in modem-manager takes 20
seconds then NM might report the message you've received below.

If it does take more than 20 seconds to enable (which starts with the
"ATZ E0 V1 +CMEE=1") then can you detect any particular command that
pauses for longer than a second or two so we can figure out what's
actually taking the time?  It really shouldn't take 20 seconds to do the
enable process; normally for Sierra devices it would be on the order of
12 - 15 seconds max.

Dan

> 
> Does anyone have any idea of why it wouldn't work ? 
> 
> 
> root igep0020:~# modem-manager --debug&
> root igep0020:~# NetworkManager: <info>  modem-manager is now
> available
> ** Message: Loaded plugin Gobi
> ** Message: Loaded plugin MotoC
> ** Message: Loaded plugin Nokia
> ** Message: Loaded plugin AnyData
> ** Message: Loaded plugin Option
> ** Message: Loaded plugin Longcheer
> ** Message: Loaded plugin ZTE
> ** Message: Loaded plugin Novatel
> ** Message: Loaded plugin Huawei
> ** Message: Loaded plugin Option High-Speed
> ** Message: Loaded plugin Generic
> ** Message: Loaded plugin Ericsson MBM
> ** Message: Loaded plugin Sierra
> ** Message: (ttyUSB0) opening serial device...
> ** (modem-manager:1397): DEBUG: (ttyUSB0): probe requested by plugin
> 'Sierra'
> ** Message: (ttyUSB1) opening serial device...
> ** (modem-manager:1397): DEBUG: (ttyUSB1): probe requested by plugin
> 'Sierra'
> ** Message: (ttyUSB2) opening serial device...
> ** (modem-manager:1397): DEBUG: (ttyUSB2): probe requested by plugin
> 'Sierra'
> ** Message: (ttyUSB3) opening serial device...
> ** (modem-manager:1397): DEBUG: (ttyUSB3): probe requested by plugin
> 'Sierra'
> ** Message: (ttyUSB4) opening serial device...
> ** (modem-manager:1397): DEBUG: (ttyUSB4): probe requested by plugin
> 'Sierra'
> ** Message: (ttyUSB5) opening serial device...
> ** (modem-manager:1397): DEBUG: (ttyUSB5): probe requested by plugin
> 'Sierra'
> ** Message: (ttyUSB6) opening serial device...
> ** (modem-manager:1397): DEBUG: (ttyUSB6): probe requested by plugin
> 'Sierra'
> ** (modem-manager:1397): DEBUG: (ttyUSB0): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB1): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB2): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB4): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB5): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB6): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): <-- 'AT
> +GCAP<CR><CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'
> ** (modem-manager:1397): DEBUG: (ttyUSB4): <-- '<CR><LF>OK<CR><LF>'
> ** (modem-manager:1397): DEBUG: (ttyUSB5): <-- '<CR><LF>OK<CR><LF>'
> ** (modem-manager:1397): DEBUG: (ttyUSB2): --> 'AT+GCAP<CR>'
> ** Message: (ttyUSB3) closing serial device...
> ** (modem-manager:1397): DEBUG: (ttyUSB4): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB5): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB0): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB1): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB4): <-- '<CR><LF>OK<CR><LF>'
> ** (modem-manager:1397): DEBUG: (ttyUSB5): <-- '<CR><LF>OK<CR><LF>'
> ** Message: (Sierra): GSM
> modem /sys/devices/platform/ehci-omap.0/usb1/1-1 claimed port ttyUSB3
> ** (modem-manager:1397): DEBUG: Added
> modem /sys/devices/platform/ehci-omap.0/usb1/1-1
> ** (modem-manager:1397): DEBUG: Exported
> modem /sys/devices/platform/ehci-omap.0/usb1/1-1
> as /org/freedesktop/ModemManager/Modems/0
> ** (modem-manager:1397): DEBUG: (ttyUSB2): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB4): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB5): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB6): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB4): <-- '<CR><LF>OK<CR><LF>'
> ** (modem-manager:1397): DEBUG: (ttyUSB5): <-- '<CR><LF>OK<CR><LF>'
> NetworkManager: <info>  (ttyUSB3): new GSM device (driver: 'sierra')
> ** (modem-manager:1397): DEBUG: (ttyUSB2): --> 'ATI<CR>'
> NetworkManager: <info>  (ttyUSB3): exported
> as /org/freedesktop/NetworkManager/Devices/1
> NetworkManager: <info>  (ttyUSB3): now managed
> NetworkManager: <info>  (ttyUSB3): device state change: 1 -> 2 (reason
> 2)
> NetworkManager: <info>  (ttyUSB3): deactivating device (reason: 2).
> NetworkManager: <info>  (ttyUSB3): device state change: 2 -> 3 (reason
> 0)
> NetworkManager: <info>  Activation (ttyUSB3) starting connection
> 'bouyguestel'
> NetworkManager: <info>  (ttyUSB3): device state change: 3 -> 4 (reason
> 0)
> NetworkManager: <info>  Activation (ttyUSB3) Stage 1 of 5 (Device
> Prepare) scheduled...
> NetworkManager: <info>  Activation (ttyUSB3) Stage 1 of 5 (Device
> Prepare) started...
> NetworkManager: <info>  Activation (ttyUSB3) Stage 1 of 5 (Device
> Prepare) complete.
> ** (modem-manager:1397): DEBUG: (ttyUSB4): --> 'ATI<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB5): --> 'ATI<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB0): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB1): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB4): <-- 'Sierra Wireless,
> Inc.<LF><CR>C889<LF><CR>APP1<LF><CR><LF>OK<CR><LF>'
> ** (modem-manager:1397): DEBUG: (ttyUSB5): <-- 'Sierra Wireless,
> Inc.<LF><CR>C889<LF><CR>APP2<LF><CR><LF>OK<CR><LF>'
> ** Message: (ttyUSB3) opening serial device...
> ** Message: Modem /org/freedesktop/ModemManager/Modems/0: state
> changed (disabled -> enabling)
> ** (modem-manager:1397): DEBUG: (ttyUSB2): --> 'AT+CPIN?<CR>'
> ** Message: (ttyUSB4) closing serial device...
> ** Message: (Sierra): GSM
> modem /sys/devices/platform/ehci-omap.0/usb1/1-1 claimed port ttyUSB4
> ** Message: (ttyUSB5) closing serial device...
> ** Message: (Sierra): GSM
> modem /sys/devices/platform/ehci-omap.0/usb1/1-1 claimed port ttyUSB5
> ** (modem-manager:1397): DEBUG: (ttyUSB6): --> 'AT+GCAP<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): --> 'ATZ E0 V1 +CMEE=1<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): <-- 'ATZ E0 V1 +CMEE=1<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB2): --> 'AT+CGMM<CR>'
> ** Message: (ttyUSB0) closing serial device...
> ** Message: (ttyUSB1) closing serial device...
> ** (modem-manager:1397): DEBUG: (ttyUSB3): <-- '<CR><LF>OK<CR><LF>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): --> 'ATE0 +CMEE=1<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): <-- '<CR><LF>OK<CR><LF>'
> ** Message: (ttyUSB2) closing serial device...
> ** (modem-manager:1397): DEBUG: (ttyUSB3): --> 'ATX4 &C1<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): <-- '<CR><LF>OK<CR><LF>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): --> 'AT+CREG=0<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): <-- '<CR><LF>OK<CR><LF>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): --> 'AT+CFUN=1<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): <-- '<CR><LF>OK<CR><LF>'
> ** Message: Modem /org/freedesktop/ModemManager/Modems/0: state
> changed (enabling -> enabled)
> ** Message: (ttyUSB6) closing serial device...
> NetworkManager: <WARN>  stage1_enable_done(): GSM modem enable failed:
> (4) Did not receive a reply. Possible causes include: the remote
> application did not send a reply, the message bus security policy
> blocked the reply, the reply timeo.
> NetworkManager: <info>  (ttyUSB3): device state change: 4 -> 9 (reason
> 1)
> NetworkManager: <info>  Marking connection 'bouyguestel' invalid.
> NetworkManager: <info>  Activation (ttyUSB3) failed.
> NetworkManager: <info>  (ttyUSB3): device state change: 9 -> 3 (reason
> 0)
> NetworkManager: <info>  (ttyUSB3): deactivating device (reason: 0).
> ** (modem-manager:1397): DEBUG: (ttyUSB3): --> 'AT+CPIN?<CR>'
> ** (modem-manager:1397): DEBUG: (ttyUSB3): <-- '<CR><LF>+CPIN:
> READY<CR><LF><CR><LF>OK<CR><LF>'
> 
> 
> Thanks
> 
> 
> _______________________________________________
> networkmanager-list mailing list
> networkmanager-list gnome org
> http://mail.gnome.org/mailman/listinfo/networkmanager-list