On Thu, 2009-09-17 at 12:35 +0100, Robert Piasek wrote: > 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}%~'
We might have actually fixed a few bugs in this area, what specific date are these checkouts of NM and MM from? Dan > (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 > _______________________________________________ > 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
