Bug 717017

Summary:

NetworkManager can't connect to 3G mobile

Product:

[Fedora] Fedora

Reporter:

Clodoaldo Pinto Neto <clodoaldo.pinto.neto>

Component:

NetworkManager

Assignee:

Dan Williams <dcbw>

Status:

CLOSED NOTABUG

QA Contact:

Fedora Extras Quality Assurance <extras-qa>

Severity:

high

Docs Contact:

Priority:

unspecified

Version:

CC:

artur, dcbw, jklimes, spanjikk

Target Milestone:

---

Target Release:

---

Hardware:

x86_64

OS:

Linux

Whiteboard:

Fixed In Version:

Doc Type:

Bug Fix

Doc Text:

Story Points:

---

Clone Of:

Environment:

Last Closed:

2011-07-04 14:46:52 UTC

Type:

---

Regression:

---

Mount Type:

---

Documentation:

---

CRM:

Verified Versions:

Category:

---

oVirt Team:

---

RHEL 7.3 requirements from Atomic Host:

Cloudforms Team:

---

Target Upstream Version:

Attachments:

Description	Flags
relevant /var/log/messages	none

Description Clodoaldo Pinto Neto 2011-06-27 18:24:14 UTC

Created attachment 510135 [details]
relevant /var/log/messages

Description of problem:
After yum upgrading NetworkManager I can't connect to 3G anymore

Version-Release number of selected component (if applicable):

0.8.9997-4

How reproducible:

Always.

Steps to Reproduce:
1. Click on the Mobile connection at the network shell extension
  
Actual results:

No connection

Expected results:

A connection to the 3G network

Additional info:
The fix is to downgrade NetworkManager
http://forums.fedoraforum.org/showthread.php?p=1489752&posted=1#post1489752

Comment 1 Jirka Klimes 2011-06-28 07:25:40 UTC

Please, ensure that the SELinux error is gone (by running the restorecon or disabling the SELinux). Then follow the steps in section "Debugging NetworkManager 0.8.x 3G connections" on http://live.gnome.org/NetworkManager/Debugging and paste here the logs from NM and MM.

Comment 2 Clodoaldo Pinto Neto 2011-07-04 14:08:19 UTC

After reupdating NetworkManager I can't reproduce it anymore.

Comment 3 arturj 2011-07-05 07:31:34 UTC

I'm affected by this bug too. I followed the debugging instructions and then it worked. After rebooting it stoped working again: here are my /var/log/messages upon first try after reboot and second try to connect to 3G. Also, I'm not prompted the PIN question any more... but can't find the key in keyring manager also. 


############# first try #################

Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) starting connection 'Alice Option Mobile'
Jul  5 09:25:46 eee NetworkManager[611]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Jul  5 09:25:46 eee modem-manager[672]: <info>  Modem /org/freedesktop/ModemManager/Modems/0: state changed (registered -> connecting)
Jul  5 09:25:46 eee modem-manager[672]: <info>  Modem /org/freedesktop/ModemManager/Modems/0: state changed (connecting -> connected)
Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Jul  5 09:25:46 eee NetworkManager[611]: <info> (ttyUSB0): device state change: prepare -> config (reason 'none') [40 50 0]
Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) successful.
Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) scheduled.
Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) started...
Jul  5 09:25:46 eee NetworkManager[611]: <info> (ttyUSB0): device state change: config -> ip-config (reason 'none') [50 70 0]
Jul  5 09:25:46 eee NetworkManager[611]: <info> starting PPP connection
Jul  5 09:25:46 eee NetworkManager[611]: <info> pppd started with pid 1557
Jul  5 09:25:46 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 3 of 5 (IP Configure Start) complete.
Jul  5 09:25:46 eee pppd[1557]: Warning: can't open options file /root/.ppprc: Permission denied
Jul  5 09:25:46 eee pppd[1557]: Plugin /usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
Jul  5 09:25:47 eee kernel: [  107.115179] PPP generic driver version 2.4.2
Jul  5 09:25:47 eee pppd[1557]: pppd 2.4.5 started by root, uid 0
Jul  5 09:25:47 eee pppd[1557]: Can't create lock file /var/lock/ppp/LCK..ttyUSB0: Permission denied
Jul  5 09:25:47 eee NetworkManager[611]: <info> (ttyUSB0): device state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
Jul  5 09:25:47 eee pppd[1557]: Exit.
Jul  5 09:25:47 eee NetworkManager[611]: <warn> Activation (ttyUSB0) failed.
Jul  5 09:25:47 eee NetworkManager[611]: <warn> pppd pid 1557 exited with error: Serial port lock failed
Jul  5 09:25:47 eee NetworkManager[611]: <info> (ttyUSB0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  5 09:25:47 eee NetworkManager[611]: <info> (ttyUSB0): deactivating device (reason: 0).
Jul  5 09:25:47 eee modem-manager[672]: <info>  Modem /org/freedesktop/ModemManager/Modems/0: state changed (connected -> disconnecting)
Jul  5 09:25:47 eee dbus: [system] Activating service name='org.fedoraproject.Setroubleshootd' (using servicehelper)
Jul  5 09:25:48 eee modem-manager[672]: <info>  Modem /org/freedesktop/ModemManager/Modems/0: state changed (disconnecting -> registered)
Jul  5 09:25:51 eee dbus: [system] Successfully activated service 'org.fedoraproject.Setroubleshootd'
Jul  5 09:26:00 eee setroubleshoot: SELinux is preventing /usr/sbin/pppd from read access on the lnk_file /var/lock. For complete SELinux messages. run sealert -l e7b993cb-6bc3-4adc-8777-b1516f09cb27

########### second try ##############

Jul  5 09:26:23 eee NetworkManager[611]: <info> Activation (ttyUSB0) starting connection 'Alice Option Mobile'
Jul  5 09:26:23 eee NetworkManager[611]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jul  5 09:26:23 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Jul  5 09:26:23 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Jul  5 09:26:23 eee NetworkManager[611]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Jul  5 09:26:49 eee NetworkManager[611]: <warn> GSM connection failed: (32) Serial command timed out
Jul  5 09:26:49 eee NetworkManager[611]: <info> (ttyUSB0): device state change: prepare -> failed (reason 'unknown') [40 120 1]
Jul  5 09:26:49 eee NetworkManager[611]: <warn> Activation (ttyUSB0) failed.
Jul  5 09:26:49 eee NetworkManager[611]: <info> (ttyUSB0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Jul  5 09:26:49 eee NetworkManager[611]: <info> (ttyUSB0): deactivating device (reason: 0).

Comment 4 Jirka Klimes 2011-07-07 07:14:54 UTC

See bug 717161 for the SELinux error.