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: [email protected] [mailto:[email protected]] On Behalf Of Andy Maginnis Sent: 18 March 2011 12:11 To: Dan Williams Cc: [email protected] 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 [email protected] http://mail.gnome.org/mailman/listinfo/networkmanager-list _______________________________________________ networkmanager-list mailing list [email protected] http://mail.gnome.org/mailman/listinfo/networkmanager-list
