On Sunday 13 September 2009 22:47:50 Robert Piasek wrote:
> Hi,
>
> I'm trying to connect my Nokia N95 using phonet driver and NM. Device is
> picked up by kernel and properly initialized, but it doesn't connect due to
> some problems with ppp. Please have a look on log below and let me know
> what can be done to make it work.
>
I've also tried with trunk version of NM (less than month old) and modem-
manager. Modem is timing out. Some logs:
modem-manager --debug:
** Message: (ttyS1) opening serial device...
** (modem-manager:9049): DEBUG: (ttyS1): probe requested by plugin 'Generic'
** (modem-manager:9049): DEBUG: (ttyS1): --> 'AT+GCAP<CR>'
** (modem-manager:9049): DEBUG: (ttyS1): --> 'AT+GCAP<CR>'
** (modem-manager:9049): DEBUG: (ttyS1): --> 'AT+GCAP<CR>'
** Message: (ttyS1) closing serial device...
** Message: (ttyACM2) opening serial device...
** (modem-manager:9049): DEBUG: (ttyACM2): probe requested by plugin 'Nokia'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+GCAP<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- 'AT+GCAP<CR><CR><LF>+GCAP:
+CGSM,+DS,+W<CR><LF><CR><LF>OK<CR><LF>'
** Message: (ttyACM2) closing serial device...
** Message: (Nokia): GSM modem /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-2
claimed port ttyACM2
** (modem-manager:9049): DEBUG: Added modem
/sys/devices/pci0000:00/0000:00:1a.1/usb4/4-2
** (modem-manager:9049): DEBUG: Exported modem
/sys/devices/pci0000:00/0000:00:1a.1/usb4/4-2 as
/org/freedesktop/ModemManager/Modems/0
** Message: (ttyACM2) opening serial device...
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'ATZ E0 V1 X4 &C1 +CMEE=1<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- 'ATZ E0 V1 X4 &C1
+CMEE=1<CR><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'ATE0 +CMEE=1<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- 'ATE0 +CMEE='
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '1<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+CREG=0<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+CFUN=1<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+CPIN?<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>+CPIN:
READY<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+COPS=0,,<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+CREG?<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>+CREG:
0,1<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: Registration state changed: 1
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+COPS=3,2;+COPS?<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>+COPS:
0,2,"23420",2<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+COPS=3,0;+COPS?<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>+COPS: 0,0,"3
UK",2<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+CSQ<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>+CSQ:
8,99<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+CGDCONT?<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>+CGDCONT:
1,"IP",,,0,0<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+CGDCONT=?<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>+CGDCONT: (1),"IP",,,
(0),(0)<CR><LF>+CGDCONT: (1),"IPV6",,,(0),(0)<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+CGDCONT=1, "IP",
"3internet"<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'ATD*99***1#<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <--
'<CR><LF>CONNECT<CR><LF>~\-1}#\-64!}!} } }2}#}$\-64#}!}$}%\-36}"}&} }*} }
g}%~'
(networkmanager times out here)
** (modem-manager:9049): DEBUG: (ttyACM2): --> 'AT+CEER<CR>'
** (modem-manager:9049): DEBUG: (ttyACM2): <-- '<CR><LF>OK<CR><LF>'
and NM logs:
Sep 17 12:20:00 [kernel] [674137.408270] usb 4-2: new full speed USB device
using uhci_hcd and address 4
Sep 17 12:20:00 [kernel] [674137.587094] usb 4-2: New USB device found,
idVendor=0421, idProduct=0070
Sep 17 12:20:00 [kernel] [674137.587097] usb 4-2: New USB device strings:
Mfr=1, Product=2, SerialNumber=0
Sep 17 12:20:00 [kernel] [674137.587100] usb 4-2: Product: Nokia N95 8GB
Sep 17 12:20:00 [kernel] [674137.587101] usb 4-2: Manufacturer: Nokia
Sep 17 12:20:00 [kernel] [674137.587206] usb 4-2: configuration #1 chosen from
1 choice
Sep 17 12:20:00 [kernel] [674137.602190] cdc_acm 4-2:1.10: ttyACM2: USB ACM
device
Sep 17 12:20:00 [kernel] [674137.609135] usb 4-2: bad CDC descriptors
Sep 17 12:20:02 [NetworkManager] <info> (ttyACM2): new GSM device (driver:
'cdc_acm')_
Sep 17 12:20:02 [NetworkManager] <info> (ttyACM2): exported as
/org/freedesktop/NetworkManager/Devices/1_
Sep 17 12:20:02 [NetworkManager] <info> (ttyACM2): now managed_
Sep 17 12:20:02 [NetworkManager] <info> (ttyACM2): device state change: 1 ->
2 (reason 2)_
Sep 17 12:20:02 [NetworkManager] <info> (ttyACM2): deactivating device
(reason: 2)._
Sep 17 12:20:02 [NetworkManager] flush_routes: assertion `iface_idx >= 0'
failed
Sep 17 12:20:02 [NetworkManager] flush_addresses: assertion `iface_idx >= 0'
failed
Sep 17 12:20:02 [NetworkManager] <info> (ttyACM2): device state change: 2 ->
3 (reason 0)_
Sep 17 12:20:02 [NetworkManager] <info> Activation (ttyACM2) starting
connection '3'_
Sep 17 12:20:02 [NetworkManager] <info> (ttyACM2): device state change: 3 ->
4 (reason 0)_
Sep 17 12:20:02 [NetworkManager] <info> Activation (ttyACM2) Stage 1 of 5
(Device Prepare) scheduled..._
Sep 17 12:20:02 [NetworkManager] <info> Activation (ttyACM2) Stage 1 of 5
(Device Prepare) started..._
Sep 17 12:20:02 [NetworkManager] <info> Activation (ttyACM2) Stage 1 of 5
(Device Prepare) complete._
Sep 17 12:21:02 [NetworkManager] <WARN> stage1_prepare_done(): GSM modem
connection failed: Serial command timed out_
Sep 17 12:21:02 [NetworkManager] <info> (ttyACM2): device state change: 4 ->
9 (reason 1)_
Sep 17 12:21:02 [NetworkManager] <info> Marking connection '3' invalid._
Sep 17 12:21:02 [NetworkManager] <info> Activation (ttyACM2) failed._
Sep 17 12:21:02 [NetworkManager] <info> (ttyACM2): device state change: 9 ->
3 (reason 0)_
Sep 17 12:21:02 [NetworkManager] <info> (ttyACM2): deactivating device
(reason: 0)._
Sep 17 12:21:02 [NetworkManager] <info> (eth0): writing resolv.conf to
/sbin/resolvconf_
Sep 17 12:21:02 [NetworkManager] <info> Policy set 'Auto eth0' (eth0) as
default for routing and DNS._
Sep 17 12:21:02 [NetworkManager] flush_routes: assertion `iface_idx >= 0'
failed
Sep 17 12:21:02 [NetworkManager] flush_addresses: assertion `iface_idx >= 0'
failed
The same sim card works ok with Heuwai modem:
modem-manager --debug
** Message: (ttyUSB0) opening serial device...
** (modem-manager:9049): DEBUG: (ttyUSB0): probe requested by plugin 'Huawei'
** (modem-manager:9049): DEBUG: (Huawei): (ttyUSB1) deferring support check
** (modem-manager:9049): DEBUG: (Huawei): (ttyUSB2) deferring support check
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+GCAP<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- 'AT+GCAP<CR><CR><LF>+GCAP:
+CGSM,+FCLASS,+DS<CR><LF><CR><LF>OK<CR><LF>'
** Message: (ttyUSB0) closing serial device...
** Message: (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1
claimed port ttyUSB0
** (modem-manager:9049): DEBUG: Added modem
/sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1
** (modem-manager:9049): DEBUG: Exported modem
/sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1 as
/org/freedesktop/ModemManager/Modems/1
** (modem-manager:9049): DEBUG: (ttyUSB1): re-checking support...
** Message: (ttyUSB1) opening serial device...
** (modem-manager:9049): DEBUG: (ttyUSB2): re-checking support...
** Message: (ttyUSB2) opening serial device...
** Message: (ttyUSB1) closing serial device...
** Message: (ttyUSB1) opening serial device...
** (modem-manager:9049): DEBUG: (ttyUSB1): probe requested by plugin 'Generic'
** Message: (ttyUSB2) closing serial device...
** Message: (ttyUSB2) opening serial device...
** (modem-manager:9049): DEBUG: (ttyUSB2): probe requested by plugin 'Generic'
** (modem-manager:9049): DEBUG: (ttyUSB2): <--
'<CR><LF>^RSSI:31<CR><LF><CR><LF>^SRVST:1<CR><LF><CR><LF>^MODE:5,4<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB1): --> 'AT+GCAP<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB2): --> 'AT+GCAP<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB2): <--
'AT+GCAP<CR><LF>^RSSI:7<CR><LF><CR><CR><LF>+GCAP: +CGSM,+DS,
+ES<CR><LF><CR><LF>OK<CR><LF>'
** Message: (ttyUSB2) closing serial device...
** Message: (Generic): GSM modem /sys/devices/pci0000:00/0000:00:1a.1/usb4/4-1
claimed port ttyUSB2
** (modem-manager:9049): DEBUG: (ttyUSB1): --> 'AT+GCAP<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB1): --> 'AT+GCAP<CR>'
** Message: (ttyUSB1) closing serial device...
** Message: (ttyACM2) opening serial device...
** Message: (ttyUSB0) opening serial device...
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'ATZ E0 V1 X4 &C1 +CMEE=1<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- 'ATZ E0 V1 X4 &C1 +CMEE=1<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'ATE0 +CMEE=1<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CREG=0<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CFUN=1<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CPIN?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+CPIN:
READY<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+COPS=0,,<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CREG?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+CREG:
0,2<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: Registration state changed: 2
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CREG?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+CREG:
0,2<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CREG?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+CREG:
0,2<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CREG?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+CREG:
0,2<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CREG?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+CREG:
0,0<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: Registration state changed: 0
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CREG?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+CREG:
0,0<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CREG?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+CREG:
0,1<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: Registration state changed: 1
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+COPS=3,2;+COPS?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+COPS:
0,2,"23420",2<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+COPS=3,0;+COPS?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+COPS: 0,0,"3
UK",2<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CSQ<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+CSQ:
7,99<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'AT+CGDCONT?<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>+CGDCONT:
1,"IP","3internet","0.0.0.0",0,0<CR><LF>+CGDCONT:
2,"IP","three.co.uk","0.0.0.0",0,0<CR><LF><CR><LF>OK<CR><LF>'
** (modem-manager:9049): DEBUG: (ttyUSB0): --> 'ATD*99***1#<CR>'
** (modem-manager:9049): DEBUG: (ttyUSB0): <-- '<CR><LF>CONNECT
3600000<CR><LF>'
and NM logs:
Sep 17 12:26:41 [kernel] [674538.232464] usb 4-1: new full speed USB device
using uhci_hcd and address 5
Sep 17 12:26:41 [kernel] [674538.396104] usb 4-1: New USB device found,
idVendor=12d1, idProduct=1001
Sep 17 12:26:41 [kernel] [674538.396108] usb 4-1: New USB device strings:
Mfr=1, Product=2, SerialNumber=1
Sep 17 12:26:41 [kernel] [674538.396110] usb 4-1: Product: HUAWEI Mobile
Sep 17 12:26:41 [kernel] [674538.396112] usb 4-1: Manufacturer:
ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
Sep 17 12:26:41 [kernel] [674538.396114] usb 4-1: SerialNumber:
ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
Sep 17 12:26:41 [kernel] [674538.396212] usb 4-1: configuration #1 chosen from
1 choice
Sep 17 12:26:41 [kernel] [674538.580048] usb 4-1: USB disconnect, address 5
Sep 17 12:26:42 [kernel] [674539.312270] usb 4-1: new full speed USB device
using uhci_hcd and address 6
Sep 17 12:26:42 [kernel] [674539.478078] usb 4-1: New USB device found,
idVendor=12d1, idProduct=1001
Sep 17 12:26:42 [kernel] [674539.478081] usb 4-1: New USB device strings:
Mfr=1, Product=2, SerialNumber=1
Sep 17 12:26:42 [kernel] [674539.478083] usb 4-1: Product: HUAWEI Mobile
Sep 17 12:26:42 [kernel] [674539.478085] usb 4-1: Manufacturer:
ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
Sep 17 12:26:42 [kernel] [674539.478087] usb 4-1: SerialNumber:
ÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿÿ
Sep 17 12:26:42 [kernel] [674539.478183] usb 4-1: configuration #1 chosen from
1 choice
Sep 17 12:26:42 [kernel] [674539.579069] usbcore: registered new interface
driver usbserial
Sep 17 12:26:42 [kernel] [674539.579082] USB Serial support registered for
generic
Sep 17 12:26:42 [kernel] [674539.579131] usbcore: registered new interface
driver usbserial_generic
Sep 17 12:26:42 [kernel] [674539.579133] usbserial: USB Serial Driver core
Sep 17 12:26:42 [kernel] [674539.582191] USB Serial support registered for GSM
modem (1-port)
Sep 17 12:26:42 [kernel] [674539.582236] option 4-1:1.0: GSM modem (1-port)
converter detected
Sep 17 12:26:42 [kernel] [674539.582324] usb 4-1: GSM modem (1-port) converter
now attached to ttyUSB0
Sep 17 12:26:42 [kernel] [674539.582335] option 4-1:1.1: GSM modem (1-port)
converter detected
Sep 17 12:26:42 [kernel] [674539.582374] usb 4-1: GSM modem (1-port) converter
now attached to ttyUSB1
Sep 17 12:26:42 [kernel] [674539.582383] option 4-1:1.2: GSM modem (1-port)
converter detected
Sep 17 12:26:42 [kernel] [674539.582424] usb 4-1: GSM modem (1-port) converter
now attached to ttyUSB2
Sep 17 12:26:42 [kernel] [674539.582438] usbcore: registered new interface
driver option
Sep 17 12:26:42 [kernel] [674539.582440] option: v0.7.2:USB Driver for GSM
modems
Sep 17 12:26:43 [NetworkManager] <info> (ttyUSB0): new GSM device (driver:
'option1')_
Sep 17 12:26:43 [NetworkManager] <info> (ttyUSB0): exported as
/org/freedesktop/NetworkManager/Devices/2_
Sep 17 12:26:43 [NetworkManager] <info> (ttyUSB0): now managed_
Sep 17 12:26:43 [NetworkManager] <info> (ttyUSB0): device state change: 1 ->
2 (reason 2)_
Sep 17 12:26:43 [NetworkManager] <info> (ttyUSB0): deactivating device
(reason: 2)._
Sep 17 12:26:43 [NetworkManager] flush_routes: assertion `iface_idx >= 0'
failed
Sep 17 12:26:43 [NetworkManager] flush_addresses: assertion `iface_idx >= 0'
failed
Sep 17 12:26:43 [NetworkManager] <info> (ttyUSB0): device state change: 2 ->
3 (reason 0)_
Sep 17 12:29:21 [NetworkManager] <info> Activation (ttyACM2) starting
connection '3'_
Sep 17 12:29:21 [NetworkManager] <info> (ttyACM2): device state change: 3 ->
4 (reason 0)_
Sep 17 12:29:21 [NetworkManager] <info> Activation (ttyACM2) Stage 1 of 5
(Device Prepare) scheduled..._
Sep 17 12:29:21 [NetworkManager] <info> Activation (ttyUSB0) starting
connection '3'_
Sep 17 12:29:21 [NetworkManager] <info> (ttyUSB0): device state change: 3 ->
4 (reason 0)_
Sep 17 12:29:21 [NetworkManager] <info> Activation (ttyUSB0) Stage 1 of 5
(Device Prepare) scheduled..._
Sep 17 12:29:21 [NetworkManager] <info> Activation (ttyACM2) Stage 1 of 5
(Device Prepare) started..._
Sep 17 12:29:21 [NetworkManager] <info> Activation (ttyACM2) Stage 1 of 5
(Device Prepare) complete._
Sep 17 12:29:21 [NetworkManager] <info> Activation (ttyUSB0) Stage 1 of 5
(Device Prepare) started..._
Sep 17 12:29:21 [NetworkManager] <info> Activation (ttyUSB0) Stage 1 of 5
(Device Prepare) complete._
Sep 17 12:29:28 [NetworkManager] <info> Activation (ttyUSB0) Stage 2 of 5
(Device Configure) scheduled..._
Sep 17 12:29:28 [NetworkManager] <info> Activation (ttyUSB0) Stage 2 of 5
(Device Configure) starting..._
Sep 17 12:29:28 [NetworkManager] <info> (ttyUSB0): device state change: 4 ->
5 (reason 0)_
Sep 17 12:29:28 [NetworkManager] <info> Activation (ttyUSB0) Stage 2 of 5
(Device Configure) successful._
Sep 17 12:29:28 [NetworkManager] <info> Activation (ttyUSB0) Stage 3 of 5 (IP
Configure Start) scheduled._
Sep 17 12:29:28 [NetworkManager] <info> Activation (ttyUSB0) Stage 2 of 5
(Device Configure) complete._
Sep 17 12:29:28 [NetworkManager] <info> Activation (ttyUSB0) Stage 3 of 5 (IP
Configure Start) started..._
Sep 17 12:29:28 [NetworkManager] <info> (ttyUSB0): device state change: 5 ->
7 (reason 0)_
Sep 17 12:29:28 [NetworkManager] <info> Starting pppd connection_
Sep 17 12:29:28 [NetworkManager] <info> Activation (ttyUSB0) Stage 4 of 5
(IP6 Configure Get) scheduled..._
Sep 17 12:29:28 [NetworkManager] <info> Activation (ttyUSB0) Stage 3 of 5 (IP
Configure Start) complete._
Sep 17 12:29:28 [NetworkManager] <info> Activation (ttyUSB0) Stage 4 of 5
(IP6 Configure Get) started..._
Sep 17 12:29:28 [NetworkManager] <info> Activation (ttyUSB0) Stage 4 of 5
(IP6 Configure Get) complete._
Sep 17 12:29:28 [pppd] Plugin /usr/lib64/pppd/2.4.4/nm-pppd-plugin.so loaded.
Sep 17 12:29:28 [pppd] pppd 2.4.4 started by root, uid 0
Sep 17 12:29:28 [pppd] Using interface ppp0
Sep 17 12:29:28 [pppd] Connect: ppp0 <--> /dev/ttyUSB0
Sep 17 12:29:28 [NetworkManager] <info> (ttyUSB0): device state change: 7 ->
6 (reason 0)_
Sep 17 12:29:31 [pppd] CHAP authentication succeeded
- Last output repeated twice -
Sep 17 12:29:31 [NetworkManager] <info> (ttyUSB0): device state change: 6 ->
7 (reason 0)_
Sep 17 12:29:39 [pppd] Could not determine remote IP address: defaulting to
10.64.64.64
Sep 17 12:29:39 [pppd] local IP address 94.196.32.250
Sep 17 12:29:39 [pppd] remote IP address 10.64.64.64
Sep 17 12:29:39 [pppd] primary DNS address 10.11.12.13
Sep 17 12:29:39 [pppd] secondary DNS address 10.11.12.14
Sep 17 12:29:39 [NetworkManager] <info> PPP manager(IP Config Get) reply
received._
Sep 17 12:29:39 [NetworkManager] <info> Activation (ttyUSB0) Stage 4 of 5
(IP4 Configure Get) scheduled..._
Sep 17 12:29:39 [NetworkManager] <info> Activation (ttyUSB0) Stage 4 of 5
(IP4 Configure Get) started..._
Sep 17 12:29:39 [NetworkManager] <info> Activation (ttyUSB0) Stage 5 of 5 (IP
Configure Commit) scheduled..._
Sep 17 12:29:39 [NetworkManager] <info> Activation (ttyUSB0) Stage 4 of 5
(IP4 Configure Get) complete._
Sep 17 12:29:39 [NetworkManager] <info> Activation (ttyUSB0) Stage 5 of 5 (IP
Configure Commit) started..._
Sep 17 12:29:40 [NetworkManager] <info> (ppp0): writing resolv.conf to
/sbin/resolvconf_
Sep 17 12:29:40 [NetworkManager] <info> (eth0): writing resolv.conf to
/sbin/resolvconf_
Sep 17 12:29:40 [NetworkManager] <info> Policy set 'Auto eth0' (eth0) as
default for routing and DNS._
Sep 17 12:29:40 [NetworkManager] <info> (ttyUSB0): device state change: 7 ->
8 (reason 0)_
Sep 17 12:29:40 [NetworkManager] <info> Activation (ttyUSB0) successful,
device activated._
Sep 17 12:29:40 [NetworkManager] <info> Activation (ttyUSB0) Stage 5 of 5 (IP
Configure Commit) complete._
I think the problem lies here:
** (modem-manager:9049): DEBUG: (ttyACM2): <--
'<CR><LF>CONNECT<CR><LF>~\-1}#\-64!}!} } }2}#}$\-64#}!}$}%\-36}"}&} }*} }
g}%~'
Any help appreciated.
Thanks,
Rob
Attachment:
signature.asc
Description: This is a digitally signed message part.