RE: Telit TER-GX400 on Ubuntu/Gumstix

["Andy Maginnis" <jam smru co uk>]

Dan,
I just realised that modem-manager is still not displaying the debug
messages.
Is there something I need to enable at compile ?

-----Original Message-----
From: networkmanager-list-bounces gnome org
[mailto:networkmanager-list-bounces gnome org] On Behalf Of Andy
Maginnis
Sent: 18 March 2011 12:11
To: Dan Williams
Cc: networkmanager-list gnome org
Subject: RE: Telit TER-GX400 on Ubuntu/Gumstix

Hi Dan, thanks for the reply, I appreciate the help.

Firstly I think the Ubuntu message is not issue, I mixed up HSDPA &
HSUPA, my modem
is only capable of 384kbps. I can see 1/2mbps on the down link when the
label is "3 GSM"
so I must be on a UMTS network.

Re the Gumstix, I have added the output from /var/log/messages, but it
does seem to look much
the same as the output to the terminal

I used the following commands, but the output looks largely the same as
before
sudo modem-manager --debug &
sudo NM_SERIAL_DEBUG=1 NM_PPP_DEBUG=1 /usr/sbin/NetworkManager
--no-daemon &

Is there anything further that I can switch on?


## TERMINAL

** Message: Modem /org/freedesktop/ModemManager/Modems/0: state changed
(enabling -> disabled)

** Message: Loaded plugin Gobi
** Message: Loaded plugin MotoC
** Message: Loaded plugin Longcheer
** Message: Loaded plugin Nokia
** Message: Loaded plugin Option
** Message: Loaded plugin Sierra
** Message: Loaded plugin Generic
** Message: Loaded plugin Option High-Speed
** Message: Loaded plugin Ericsson MBM
** Message: Loaded plugin Huawei
** Message: Loaded plugin ZTE

root@overo:~# sudo NM_SERIAL_DEBUG=1 NM_PPP_DEBUG=1
/usr/sbin/NetworkManager --n
o-daemon &	
root@overo:~# NetworkManager[874]: <info> NetworkManager (version 0.8.1)
is starting...
NetworkManager[874]: <info> Read config file
/etc/NetworkManager/NetworkManager.conf
NetworkManager[874]: <info> modem-manager is now available
NetworkManager[874]:    SCPlugin-Ifupdown: init!
NetworkManager[874]:    SCPlugin-Ifupdown: update_system_hostname
NetworkManager[874]:    SCPluginIfupdown: management mode: managed
NetworkManager[874]:    SCPlugin-Ifupdown: devices added (path:
/sys/devices/virtual/net/lo, iface: lo)
NetworkManager[874]:    SCPlugin-Ifupdown: device added (path:
/sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration
found.
NetworkManager[874]:    SCPlugin-Ifupdown: end _init.
NetworkManager[874]: <info> Loaded plugin ifupdown: (C) 2008 Canonical
Ltd.  To report bugs please use the NetworkManager mailing list.
NetworkManager[874]: <info> Loaded plugin keyfile: (c) 2007 - 2008 Red
Hat, Inc.  To report bugs please use the NetworkManager mailing list.
NetworkManager[874]:    SCPlugin-Ifupdown: (838944) ... get_connections.
NetworkManager[874]:    SCPlugin-Ifupdown: (838944) connections count: 0
NetworkManager[874]: Tried to set deprecated property gsm/band
NetworkManager[874]: <info> WiFi enabled by radio killswitch; enabled by
state file
NetworkManager[874]: <info> WWAN enabled by radio killswitch; disabled
by state file
NetworkManager[874]: <info> WiMAX enabled by radio killswitch; enabled
by state file
NetworkManager[874]: <info> Networking is enabled by state file
/sbin/ifup: interface lo already configured
NetworkManager[874]: <warn> bluez error getting default adapter: The
name org.bluez was not provided by any .service files
usb 1-1.3: new full speed USB device using musb_hdrc and address 5
usb 1-1.3: New USB device found, idVendor=1bc7, idProduct=1003
usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=4
usb 1-1.3: Product: Telit HSDPA Wireless Modem    
usb 1-1.3: Manufacturer: Telit Wireless Solutions
usb 1-1.3: SerialNumber: 000000000002
usb 1-1.3: configuration #1 chosen from 1 choice
usbcore: registered new interface driver usbserial
USB Serial support registered for generic
usbcore: registered new interface driver usbserial_generic
usbserial: USB Serial Driver core
USB Serial support registered for GSM modem (1-port)
option 1-1.3:1.0: GSM modem (1-port) converter detected
usb 1-1.3: GSM modem (1-port) converter now attached to ttyUSB0
option 1-1.3:1.1: GSM modem (1-port) converter detected
usb 1-1.3: GSM modem (1-port) converter now attached to ttyUSB1
option 1-1.3:1.2: GSM modem (1-port) converter detected
usb 1-1.3: GSM modem (1-port) converter now attached to ttyUSB2
usbcore: registered new interface driver option
option: v0.7.2:USB Driver for GSM modems
** Message: (ttyUSB0) opening serial device...
** (modem-manager:873): DEBUG: (ttyUSB0): probe requested by plugin
'Generic'
** Message: (ttyUSB1) opening serial device...
** (modem-manager:873): DEBUG: (ttyUSB1): probe requested by plugin
'Generic'
** Message: (ttyUSB2) opening serial device...
** (modem-manager:873): DEBUG: (ttyUSB2): probe requested by plugin
'Generic'
** (modem-manager:873): DEBUG: (ttyUSB0): --> 'AT+GCAP<CR>'
** (modem-manager:873): DEBUG: (ttyUSB0): <-- '<CR><LF>+GCAP:
+CGSM,+DS,+FCLASS,+MS,+ES<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:873): DEBUG: (ttyUSB1): --> 'AT+GCAP<CR>'
** (modem-manager:873): DEBUG: (ttyUSB2): --> 'AT+GCAP<CR>'
** Message: (ttyUSB0) closing serial device...
** Message: (Generic): GSM modem
/sys/devices/platform/musb_hdrc/usb1/1-1/1-1.3 claimed port ttyUSB0
** (modem-manager:873): DEBUG: Added modem
/sys/devices/platform/musb_hdrc/usb1/1-1/1-1.3
** (modem-manager:873): DEBUG: Exported modem
/sys/devices/platform/musb_hdrc/usb1/1-1/1-1.3 as
/org/freedesktop/ModemManager/Modems/0
NetworkManager[874]: <warn> (ttyUSB0): failed to look up interface index
NetworkManager[874]: <info> (ttyUSB0): new GSM device (driver: 'option1'
ifindex: -1)
NetworkManager[874]: <info> (ttyUSB0): exported as
/org/freedesktop/NetworkManager/Devices/0
NetworkManager[874]: <info> (ttyUSB0): now managed
NetworkManager[874]: <info> (ttyUSB0): device state change: 1 -> 2
(reason 2)
NetworkManager[874]: <info> (ttyUSB0): deactivating device (reason: 2).
NetworkManager[874]: <info> (ttyUSB0): device state change: 2 -> 3
(reason 0)
** (modem-manager:873): DEBUG: (ttyUSB1): --> 'AT+GCAP<CR>'
** (modem-manager:873): DEBUG: (ttyUSB2): --> 'AT+GCAP<CR>'
** (modem-manager:873): DEBUG: (ttyUSB1): --> 'AT+GCAP<CR>'
** (modem-manager:873): DEBUG: (ttyUSB2): --> 'AT+GCAP<CR>'
** Message: (ttyUSB1) closing serial device...
** Message: (ttyUSB2) closing serial device...

root@overo:~# 
root@overo:~# ./scripts/overoGsmConnCtrl.bash 
Args recieved = 0
Avaiable devices:-
method return sender=:1.10 -> dest=:1.12 reply_serial=2
   array [
      object path "/org/freedesktop/NetworkManager/Devices/0"
   ]
method return sender=:1.10 -> dest=:1.13 reply_serial=2
   array [
      object path "/org/freedesktop/NetworkManagerSettings/0"
   ]
Not enough Arguments were supplied!
Expected Usage is:- For GSM UP
./overoGsmConnCtrl.bash <up> <system(0/1)> <Settings Service(0..X)>
<Device(0..X)>
./overoGsmConnCtrl.bash <down> <connection(0..X)>

root@overo:~# ./scripts/overoGsmConnCtrl.bash up 1 0 0
Args recieved = 4
Avaiable devices:-
method return sender=:1.10 -> dest=:1.14 reply_serial=2
   array [
      object path "/org/freedesktop/NetworkManager/Devices/0"
   ]
method return sender=:1.10 -> dest=:1.15 reply_serial=2
   array [
      object path "/org/freedesktop/NetworkManagerSettings/0"
   ]
Lets try connecting
dbus-send --system --print-reply --type=method_call
--dest="org.freedesktop.NetworkManager" /org/freedesktop/NetworkManager
"org.freedesktop.NetworkManager.ActivateConnection"
string:"org.freedesktop.NetworkManagerSystemSettings"
objpath:"/org/freedesktop/NetworkManagerSettings/0"
objpath:"/org/freedesktop/NetworkManager/Devices/0" objpath:"/"
NetworkManager[874]: <info> Activation (ttyUSB0) starting connection
'pbGsmConnection'
NetworkManager[874]: <info> (ttyUSB0): device state change: 3 -> 4
(reason 0)
NetworkManager[874]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device
Prepare) scheduled...
method return sender=:1.10 -> dest=:1.16 reply_serial=2
   object path "/org/freedesktop/NetworkManager/ActiveConnection/0"
root@overo:~# NetworkManager[874]: <info> Activation (ttyUSB0) Stage 1
of 5 (Device Prepare) started...
** Message: (ttyUSB0) opening serial device...
NetworkManager[874]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device
Prepare) complete.
** Message: Modem /org/freedesktop/ModemManager/Modems/0: state changed
(disabled -> enabling)
NetworkManager[874]: <warn> 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
timeout expired, or the network connection was broken.
NetworkManager[874]: <info> (ttyUSB0): device state change: 4 -> 9
(reason 28)
NetworkManager[874]: <info> Marking connection 'pbGsmConnection'
invalid.
NetworkManager[874]: <warn> Activation (ttyUSB0) failed.
NetworkManager[874]: <info> (ttyUSB0): device state change: 9 -> 3
(reason 0)
NetworkManager[874]: <info> (ttyUSB0): deactivating device (reason: 0).
** (modem-manager:873): DEBUG: (ttyUSB0): --> 'ATZ E0 V1 +CMEE=1<CR>'
** Message: Modem /org/freedesktop/ModemManager/Modems/0: state changed
(enabling -> disabled)

#### /var/log/messages

Mar 18 02:19:19 overo sudo:     root : TTY=ttyS2 ; PWD=/home/root ;
USER=root ; COMMAND=/usr/sbin/modem-manager --debug
Mar 18 02:19:33 overo sudo:     root : TTY=ttyS2 ; PWD=/home/root ;
USER=root ; ENV=NM_PPP_DEBUG=1 NM_SERIAL_DEBUG=1 ;
COMMAND=/usr/sbin/NetworkManager --no-daemon
Mar 18 02:19:33 overo NetworkManager[874]: <info> NetworkManager
(version 0.8.1) is starting...
Mar 18 02:19:33 overo NetworkManager[874]: <info> Read config file
/etc/NetworkManager/NetworkManager.conf
Mar 18 02:19:33 overo NetworkManager[874]: <info> modem-manager is now
available
Mar 18 02:19:33 overo NetworkManager[874]:    SCPlugin-Ifupdown: init!
Mar 18 02:19:33 overo NetworkManager[874]:    SCPlugin-Ifupdown:
update_system_hostname
Mar 18 02:19:33 overo NetworkManager[874]:    SCPluginIfupdown:
management mode: managed
Mar 18 02:19:33 overo NetworkManager[874]:    SCPlugin-Ifupdown: devices
added (path: /sys/devices/virtual/net/lo, iface: lo)
Mar 18 02:19:33 overo NetworkManager[874]:    SCPlugin-Ifupdown: device
added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown
configuration found.
Mar 18 02:19:33 overo NetworkManager[874]:    SCPlugin-Ifupdown: end
_init.
Mar 18 02:19:33 overo NetworkManager[874]: <info> Loaded plugin
ifupdown: (C) 2008 Canonical Ltd.  To report bugs please use the
NetworkManager mailing list.
Mar 18 02:19:33 overo NetworkManager[874]: <info> Loaded plugin keyfile:
(c) 2007 - 2008 Red Hat, Inc.  To report bugs please use the
NetworkManager mailing list.
Mar 18 02:19:33 overo NetworkManager[874]:    SCPlugin-Ifupdown:
(838944) ... get_connections.
Mar 18 02:19:33 overo NetworkManager[874]:    SCPlugin-Ifupdown:
(838944) connections count: 0
Mar 18 02:19:33 overo NetworkManager[874]: Tried to set deprecated
property gsm/band
Mar 18 02:19:33 overo NetworkManager[874]: <info> WiFi enabled by radio
killswitch; enabled by state file
Mar 18 02:19:33 overo NetworkManager[874]: <info> WWAN enabled by radio
killswitch; disabled by state file
Mar 18 02:19:33 overo NetworkManager[874]: <info> WiMAX enabled by radio
killswitch; enabled by state file
Mar 18 02:19:33 overo NetworkManager[874]: <info> Networking is enabled
by state file
Mar 18 02:19:33 overo NetworkManager[874]: <warn> bluez error getting
default adapter: The name org.bluez was not provided by any .service
files
Mar 18 02:19:49 overo usb 1-1.3: new full speed USB device using
musb_hdrc and address 5
Mar 18 02:19:49 overo usb 1-1.3: New USB device found, idVendor=1bc7,
idProduct=1003
Mar 18 02:19:49 overo usb 1-1.3: New USB device strings: Mfr=1,
Product=2, SerialNumber=4
Mar 18 02:19:49 overo usb 1-1.3: Product: Telit HSDPA Wireless Modem    
Mar 18 02:19:49 overo usb 1-1.3: Manufacturer: Telit Wireless Solutions
Mar 18 02:19:49 overo usb 1-1.3: SerialNumber: 000000000002
Mar 18 02:19:49 overo usb 1-1.3: configuration #1 chosen from 1 choice
Mar 18 02:19:50 overo usbcore: registered new interface driver usbserial
Mar 18 02:19:50 overo USB Serial support registered for generic
Mar 18 02:19:50 overo usbcore: registered new interface driver
usbserial_generic
Mar 18 02:19:50 overo usbserial: USB Serial Driver core
Mar 18 02:19:50 overo USB Serial support registered for GSM modem
(1-port)
Mar 18 02:19:50 overo option 1-1.3:1.0: GSM modem (1-port) converter
detected
Mar 18 02:19:50 overo usb 1-1.3: GSM modem (1-port) converter now
attached to ttyUSB0
Mar 18 02:19:50 overo option 1-1.3:1.1: GSM modem (1-port) converter
detected
Mar 18 02:19:50 overo usb 1-1.3: GSM modem (1-port) converter now
attached to ttyUSB1
Mar 18 02:19:50 overo option 1-1.3:1.2: GSM modem (1-port) converter
detected
Mar 18 02:19:50 overo usb 1-1.3: GSM modem (1-port) converter now
attached to ttyUSB2
Mar 18 02:19:50 overo usbcore: registered new interface driver option
Mar 18 02:19:50 overo option: v0.7.2:USB Driver for GSM modems
Mar 18 02:19:53 overo NetworkManager[874]: <warn> (ttyUSB0): failed to
look up interface index
Mar 18 02:19:53 overo NetworkManager[874]: <info> (ttyUSB0): new GSM
device (driver: 'option1' ifindex: -1)
Mar 18 02:19:53 overo NetworkManager[874]: <info> (ttyUSB0): exported as
/org/freedesktop/NetworkManager/Devices/0
Mar 18 02:19:53 overo NetworkManager[874]: <info> (ttyUSB0): now managed
Mar 18 02:19:53 overo NetworkManager[874]: <info> (ttyUSB0): device
state change: 1 -> 2 (reason 2)
Mar 18 02:19:53 overo NetworkManager[874]: <info> (ttyUSB0):
deactivating device (reason: 2).
Mar 18 02:19:53 overo NetworkManager[874]: <info> (ttyUSB0): device
state change: 2 -> 3 (reason 0)
Mar 18 02:20:25 overo NetworkManager[874]: <info> Activation (ttyUSB0)
starting connection 'pbGsmConnection'
Mar 18 02:20:25 overo NetworkManager[874]: <info> (ttyUSB0): device
state change: 3 -> 4 (reason 0)
Mar 18 02:20:25 overo NetworkManager[874]: <info> Activation (ttyUSB0)
Stage 1 of 5 (Device Prepare) scheduled...
Mar 18 02:20:25 overo NetworkManager[874]: <info> Activation (ttyUSB0)
Stage 1 of 5 (Device Prepare) started...
Mar 18 02:20:25 overo NetworkManager[874]: <info> Activation (ttyUSB0)
Stage 1 of 5 (Device Prepare) complete.
Mar 18 02:20:45 overo NetworkManager[874]: <warn> 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 timeout expired, or the network connection
was broken.
Mar 18 02:20:45 overo NetworkManager[874]: <info> (ttyUSB0): device
state change: 4 -> 9 (reason 28)
Mar 18 02:20:45 overo NetworkManager[874]: <info> Marking connection
'pbGsmConnection' invalid.
Mar 18 02:20:45 overo NetworkManager[874]: <warn> Activation (ttyUSB0)
failed.
Mar 18 02:20:45 overo NetworkManager[874]: <info> (ttyUSB0): device
state change: 9 -> 3 (reason 0)
Mar 18 02:20:45 overo NetworkManager[874]: <info> (ttyUSB0):
deactivating device (reason: 0).
Mar 18 02:21:03 overo syslog-ng[819]: Log statistics;
processed='center(queued)=3196', processed='center(received)=720',
processed='destination(messages)=328',
processed='destination(mailinfo)=0',
processed='destination(mailwarn)=0', processed='destination(console)=0',
processed='destination(ppp)=2', processed='destination(authlog)=5',
processed='destination(user)=37', processed='destination(lpr)=0',
processed='destination(daemon)=53', processed='destination(uucp)=0',
processed='destination(cron)=0', processed='destination(kern)=621',
processed='destination(console_all)=720',
processed='destination(mail)=0', processed='destination(xconsole)=0',
processed='destination(debug)=715', processed='destination(syslog)=715',
processed='destination(newserr)=0', processed='destination(newscrit)=0',
processed='destination(newsnotice)=0',
processed='destination(mailerr)=0', processed='source(src)=720',
processed='source(net)=0'
root@overo:~#


##### /var/log/daemon.log

Mar 18 02:01:15 overo NetworkManager[852]: <info> NetworkManager
(version 0.8.1) is starting...
Mar 18 02:01:15 overo NetworkManager[852]: <info> Read config file
/etc/NetworkManager/NetworkManager.conf
Mar 18 02:01:15 overo NetworkManager[852]: <info> trying to start the
modem manager...
Mar 18 02:01:15 overo NetworkManager[852]:    SCPlugin-Ifupdown: init!
Mar 18 02:01:15 overo NetworkManager[852]:    SCPlugin-Ifupdown:
update_system_hostname
Mar 18 02:01:15 overo NetworkManager[852]:    SCPluginIfupdown:
management mode: managed
Mar 18 02:01:15 overo NetworkManager[852]:    SCPlugin-Ifupdown: devices
added (path: /sys/devices/virtual/net/lo, iface: lo)
Mar 18 02:01:15 overo NetworkManager[852]:    SCPlugin-Ifupdown: device
added (path: /sys/devices/virtual/net/lo, iface: lo): no ifupdown
configuration found.
Mar 18 02:01:15 overo NetworkManager[852]:    SCPlugin-Ifupdown: end
_init.
Mar 18 02:01:15 overo NetworkManager[852]: <info> Loaded plugin
ifupdown: (C) 2008 Canonical Ltd.  To report bugs please use the
NetworkManager mailing list.
Mar 18 02:01:15 overo NetworkManager[852]: <info> Loaded plugin keyfile:
(c) 2007 - 2008 Red Hat, Inc.  To report bugs please use the
NetworkManager mailing list.
Mar 18 02:01:15 overo NetworkManager[852]:    SCPlugin-Ifupdown:
(837864) ... get_connections.
Mar 18 02:01:15 overo NetworkManager[852]:    SCPlugin-Ifupdown:
(837864) connections count: 0
Mar 18 02:01:15 overo modem-manager: Loaded plugin Novatel
Mar 18 02:01:15 overo modem-manager: Loaded plugin Gobi
Mar 18 02:01:15 overo modem-manager: Loaded plugin MotoC
Mar 18 02:01:15 overo modem-manager: Loaded plugin Longcheer
Mar 18 02:01:15 overo modem-manager: Loaded plugin Nokia
Mar 18 02:01:15 overo modem-manager: Loaded plugin Option
Mar 18 02:01:15 overo NetworkManager[852]: Tried to set deprecated
property gsm/band
Mar 18 02:01:15 overo modem-manager: Loaded plugin Sierra
Mar 18 02:01:15 overo modem-manager: Loaded plugin Generic
Mar 18 02:01:15 overo modem-manager: Loaded plugin Option High-Speed
Mar 18 02:01:15 overo modem-manager: Loaded plugin Ericsson MBM
Mar 18 02:01:15 overo modem-manager: Loaded plugin Huawei
Mar 18 02:01:15 overo modem-manager: Loaded plugin ZTE
Mar 18 02:01:15 overo NetworkManager[852]: <info> WiFi enabled by radio
killswitch; enabled by state file
Mar 18 02:01:15 overo NetworkManager[852]: <info> WWAN enabled by radio
killswitch; disabled by state file
Mar 18 02:01:15 overo NetworkManager[852]: <info> WiMAX enabled by radio
killswitch; enabled by state file
Mar 18 02:01:15 overo NetworkManager[852]: <info> Networking is enabled
by state file
Mar 18 02:01:15 overo NetworkManager[852]: <info> modem-manager is now
available
Mar 18 02:01:15 overo NetworkManager[852]: <warn> bluez error getting
default adapter: The name org.bluez was not provided by any .service
files
Mar 18 02:01:15 overo NetworkManager[852]: <info> Trying to start the
supplicant...
Mar 18 02:02:07 overo ntpd_initres[818]: host name not found:
pool.ntp.org
Mar 18 02:04:09 overo ntpd_initres[818]: host name not found:
pool.ntp.org
Mar 18 02:04:16 overo ntpd[815]: synchronized to LOCAL(0), stratum 14
Mar 18 02:04:16 overo ntpd[815]: kernel time sync status change 0001
Mar 18 02:08:12 overo ntpd_initres[818]: host name not found:
pool.ntp.org
Mar 18 02:16:14 overo ntpd_initres[818]: host name not found:
pool.ntp.org
Mar 18 02:18:09 overo NetworkManager[852]: <info> caught signal 15,
shutting down normally.
Mar 18 02:18:09 overo modem-manager: Caught signal 15, shutting down...
Mar 18 02:18:09 overo NetworkManager[852]: <info> exiting (success)
Mar 18 02:32:17 overo ntpd_initres[818]: host name not found:
pool.ntp.org
Mar 18 02:48:20 overo ntpd_initres[818]: host name not found:
pool.ntp.org

Can I get full NetworkManager output from the Gumstix?  The bits
included don't seem to show everything; NM will usually dump quite a bit
of information about what's going on to syslog's 'daemon' facility.
It'll show up in usually /var/log/messages or /var/log/daemon.log
depending on how syslog is configured on the device.

For the modem-manager end, if you can get into the device, stop NM and
modem-manager, then run 'modem-manager --debug' and then restart NM,
you'll get a lot of modem-manager spew that's useful for debugging
what's going on.

Dan


_______________________________________________
networkmanager-list mailing list
networkmanager-list gnome org
http://mail.gnome.org/mailman/listinfo/networkmanager-list