Bug 1383824

Summary: ModemManager fails ro establish Connection for a mobile broadband
Product: [Fedora] Fedora Reporter: GroovieMan <christian.groove>
Component: ModemManagerAssignee: Lubomir Rintel <lkundrak>
Status: CLOSED WORKSFORME QA Contact: Fedora Extras Quality Assurance <extras-qa>
Severity: urgent Docs Contact:
Priority: unspecified    
Version: 24CC: dcbw, fgiudici, lkundrak
Target Milestone: ---   
Target Release: ---   
Hardware: x86_64   
OS: Linux   
Whiteboard:
Fixed In Version: Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2017-07-05 09:48:59 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:

Description GroovieMan 2016-10-11 21:15:54 UTC 
Description of problem:
A mobil broadbad stick is recognized, but is unable to
connect with the provider.

Version-Release number of selected component (if applicable):
ModemManager 1.6.2-1
NetworkManager 1.2.4-2

How reproducible:
Try to connect and it fails

Steps to Reproduce:
1.
2.
3.

Actual results:
This is from my console journalctl

Okt 11 22:55:33 chrissyboy.fritz.box pppd[2415]: Connect: ppp0 <--> /dev/ttyUSB1
Okt 11 22:55:33 chrissyboy.fritz.box NetworkManager[1014]: <info>  [1476219333.8494] manager: (ppp0): new Generic device (/org/freedesktop/NetworkManager/Devices/4)
Okt 11 22:55:33 chrissyboy.fritz.box pppd[2415]: pppd 2.4.7 started by root, uid 0
Okt 11 22:55:33 chrissyboy.fritz.box NetworkManager[1014]: nm-pppd-plugin-Message: nm-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
Okt 11 22:55:33 chrissyboy.fritz.box pppd[2415]: Using interface ppp0
Okt 11 22:55:33 chrissyboy.fritz.box NetworkManager[1014]: Using interface ppp0
Okt 11 22:55:33 chrissyboy.fritz.box NetworkManager[1014]: Connect: ppp0 <--> /dev/ttyUSB1
Okt 11 22:55:33 chrissyboy.fritz.box NetworkManager[1014]: nm-pppd-plugin-Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
Okt 11 22:55:33 chrissyboy.fritz.box pppd[2415]: Connect: ppp0 <--> /dev/ttyUSB1
Okt 11 22:55:33 chrissyboy.fritz.box NetworkManager[1014]: <info>  [1476219333.8494] manager: (ppp0): new Generic device (/org/freedesktop/NetworkManager/Devices/4)
Okt 11 22:55:33 chrissyboy.fritz.box baloo_file[1803]: QObject::connect: invalid null parameter
Okt 11 22:55:33 chrissyboy.fritz.box kdeinit5[1686]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "AllDevices"
Okt 11 22:55:33 chrissyboy.fritz.box kdeinit5[1686]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Devices"
Okt 11 22:55:33 chrissyboy.fritz.box kdeinit5[1686]: QObject::connect: invalid null parameter
Okt 11 22:55:33 chrissyboy.fritz.box plasmashell[1817]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "AllDevices"
Okt 11 22:55:33 chrissyboy.fritz.box plasmashell[1817]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Devices"
Okt 11 22:55:54 chrissyboy.fritz.box NetworkManager[1014]: <warn>  [1476219354.4863] ppp-manager: pppd timed out or didn't initialize our dbus module
Okt 11 22:55:54 chrissyboy.fritz.box kernel: audit_printk_skb: 3 callbacks suppressed
Okt 11 22:55:54 chrissyboy.fritz.box kernel: audit: type=1107 audit(1476219354.498:259): pid=871 uid=81 auid=4294967295 ses=4294967295 subj=system_u:system_r:system_dbusd_t:s0-s0:c0.c1023 msg='avc:  denied  { send_msg } for msgtype=signa
                                              exe="/usr/bin/dbus-daemon" sauid=81 hostname=? addr=? terminal=?'
Okt 11 22:55:54 chrissyboy.fritz.box kernel: audit: type=1107 audit(1476219354.498:260): pid=871 uid=81 auid=4294967295 ses=4294967295 subj=system_u:system_r:system_dbusd_t:s0-s0:c0.c1023 msg='avc:  denied  { send_msg } for msgtype=signa
                                              exe="/usr/bin/dbus-daemon" sauid=81 hostname=? addr=? terminal=?'
Okt 11 22:55:54 chrissyboy.fritz.box kernel: audit: type=1325 audit(1476219354.511:261): table=mangle family=2 entries=40
Okt 11 22:55:54 chrissyboy.fritz.box kernel: audit: type=1325 audit(1476219354.511:262): table=nat family=2 entries=52
Okt 11 22:55:54 chrissyboy.fritz.box kernel: audit: type=1325 audit(1476219354.511:263): table=filter family=2 entries=86
Okt 11 22:55:54 chrissyboy.fritz.box kernel: audit: type=1325 audit(1476219354.518:264): table=mangle family=10 entries=40
Okt 11 22:55:54 chrissyboy.fritz.box kernel: audit: type=1325 audit(1476219354.518:265): table=nat family=10 entries=52
Okt 11 22:55:54 chrissyboy.fritz.box kernel: audit: type=1325 audit(1476219354.518:266): table=filter family=10 entries=87
Okt 11 22:55:54 chrissyboy.fritz.box audit[871]: USER_AVC pid=871 uid=81 auid=4294967295 ses=4294967295 subj=system_u:system_r:system_dbusd_t:s0-s0:c0.c1023 msg='avc:  denied  { send_msg } for msgtype=signal interface=org.freedesktop.DBu
                                                  exe="/usr/bin/dbus-daemon" sauid=81 hostname=? addr=? terminal=?'
Okt 11 22:55:54 chrissyboy.fritz.box audit[871]: USER_AVC pid=871 uid=81 auid=4294967295 ses=4294967295 subj=system_u:system_r:system_dbusd_t:s0-s0:c0.c1023 msg='avc:  denied  { send_msg } for msgtype=signal interface=org.freedesktop.Mod
                                                  exe="/usr/bin/dbus-daemon" sauid=81 hostname=? addr=? terminal=?'
Okt 11 22:55:54 chrissyboy.fritz.box audit: NETFILTER_CFG table=mangle family=2 entries=40
Okt 11 22:55:54 chrissyboy.fritz.box audit: NETFILTER_CFG table=nat family=2 entries=52
Okt 11 22:55:54 chrissyboy.fritz.box audit: NETFILTER_CFG table=filter family=2 entries=86
Okt 11 22:55:54 chrissyboy.fritz.box audit: NETFILTER_CFG table=mangle family=10 entries=40
Okt 11 22:55:54 chrissyboy.fritz.box audit: NETFILTER_CFG table=nat family=10 entries=52
Okt 11 22:55:54 chrissyboy.fritz.box audit: NETFILTER_CFG table=filter family=10 entries=87
Okt 11 22:55:54 chrissyboy.fritz.box org.gnome.Shell.desktop[1202]: (gnome-shell:1202): Gjs-WARNING **: JS ERROR: TypeError: this._activeConnection._connection is null
Okt 11 22:55:54 chrissyboy.fritz.box org.gnome.Shell.desktop[1202]: NMConnectionDevice<._activeConnectionChanged@resource:///org/gnome/shell/ui/status/network.js:386
Okt 11 22:55:54 chrissyboy.fritz.box org.gnome.Shell.desktop[1202]: wrapper@resource:///org/gnome/gjs/modules/lang.js:178
Okt 11 22:55:54 chrissyboy.fritz.box pppd[2415]: Terminating on signal 15
Okt 11 22:55:54 chrissyboy.fritz.box NetworkManager[1014]: Terminating on signal 15
Okt 11 22:55:54 chrissyboy.fritz.box NetworkManager[1014]: nm-pppd-plugin-Message: nm-ppp-plugin: (nm_phasechange): status 10 / phase 'terminate'
Okt 11 22:55:54 chrissyboy.fritz.box NetworkManager[1014]: <info>  [1476219354.4868] device (ttyUSB1): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
Okt 11 22:55:54 chrissyboy.fritz.box ModemManager[869]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disconnecting)
Okt 11 22:55:54 chrissyboy.fritz.box NetworkManager[1014]: <info>  [1476219354.4873] manager: NetworkManager state is now DISCONNECTED
Okt 11 22:55:54 chrissyboy.fritz.box dbus-daemon[871]: [system] Rejected send message, 3 matched rules; type="error", sender=":1.14" (uid=0 pid=1014 comm="/usr/sbin/NetworkManager --no-daemon ") interface="(unset)" member="(unset)" error
Okt 11 22:55:54 chrissyboy.fritz.box NetworkManager[1014]: <warn>  [1476219354.4885] device (ttyUSB1): Activation: failed for connection '1&1'
Okt 11 22:55:54 chrissyboy.fritz.box NetworkManager[1014]: <info>  [1476219354.4894] device (ttyUSB1): state change: failed -> disconnected (reason 'none') [120 30 0]
Okt 11 22:55:54 chrissyboy.fritz.box NetworkManager[1014]: <info>  [1476219354.5002] (ttyUSB1): modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
Okt 11 22:55:55 chrissyboy.fritz.box ModemManager[869]: <warn>  (ttyUSB1): port attributes not fully set
Okt 11 22:55:55 chrissyboy.fritz.box systemd[1]: Starting Cleanup of Temporary Directories...
-- Subject: Unit systemd-tmpfiles-clean.service has begun start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit systemd-tmpfiles-clean.service has begun starting up.
Okt 11 22:55:55 chrissyboy.fritz.box systemd[1]: Started Cleanup of Temporary Directories.
-- Subject: Unit systemd-tmpfiles-clean.service has finished start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- Unit systemd-tmpfiles-clean.service has finished starting up.



- 
-- The start-up result is done.
Okt 11 22:55:55 chrissyboy.fritz.box audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? ter
Okt 11 22:55:55 chrissyboy.fritz.box audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? term
Okt 11 22:55:55 chrissyboy.fritz.box kernel: audit: type=1130 audit(1476219355.552:267): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/sy
Okt 11 22:55:55 chrissyboy.fritz.box kernel: audit: type=1131 audit(1476219355.552:268): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/sy
Okt 11 22:55:56 chrissyboy.fritz.box NetworkManager[1014]: <error> [1476219356.4918] platform-linux: do-change-link[4]: failure changing link: failure 19 (No such device)
Okt 11 22:55:56 chrissyboy.fritz.box NetworkManager[1014]: <warn>  [1476219356.4918] device (ppp0): failed to disable userspace IPv6LL address handling
Okt 11 22:55:56 chrissyboy.fritz.box org.gnome.Shell.desktop[1202]: Gjs-Message: JS LOG: Removing a network device that was not added
Okt 11 22:55:56 chrissyboy.fritz.box plasmashell[1817]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "AllDevices"
Okt 11 22:55:56 chrissyboy.fritz.box audit[871]: USER_AVC pid=871 uid=81 auid=4294967295 ses=4294967295 subj=system_u:system_r:system_dbusd_t:s0-s0:c0.c1023 msg='avc:  denied  { send_msg } for msgtype=signal interface=org.freedesktop.DBu
                                                  exe="/usr/bin/dbus-daemon" sauid=81 hostname=? addr=? terminal=?'
Okt 11 22:55:56 chrissyboy.fritz.box audit[871]: USER_AVC pid=871 uid=81 auid=4294967295 ses=4294967295 subj=system_u:system_r:system_dbusd_t:s0-s0:c0.c1023 msg='avc:  denied  { send_msg } for msgtype=signal interface=org.freedesktop.Mod
                                                  exe="/usr/bin/dbus-daemon" sauid=81 hostname=? addr=? terminal=?'
Okt 11 22:55:56 chrissyboy.fritz.box kdeinit5[1686]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "AllDevices"
Okt 11 22:55:56 chrissyboy.fritz.box ModemManager[869]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> registered)
Okt 11 22:55:56 chrissyboy.fritz.box kdeinit5[1686]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Devices"
Okt 11 22:55:56 chrissyboy.fritz.box plasmashell[1817]: networkmanager-qt: void NetworkManager::NetworkManagerPrivate::propertiesChanged(const QVariantMap&) Unhandled property "Devices"
Okt 11 22:55:56 chrissyboy.fritz.box NetworkManager[1014]: <info>  [1476219356.5100] (ttyUSB1): modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
Okt 11 22:56:08 chrissyboy.fritz.box kwin_x11[1759]: QXcbConnection: XCB error: 3 (BadWindow), sequence: 21660, resource id: 10485768, major code: 18 (ChangeProperty), minor code: 0
Okt 11 22:56:31 chrissyboy.fritz.box audit[871]: USER_AVC pid=871 uid=81 auid=4294967295 ses=4294967295 subj=system_u:system_r:system_dbusd_t:s0-s0:c0.c1023 msg='avc:  denied  { send_msg } for msgtype=signal interface=org.freedesktop.DBu
                                                  exe="/usr/bin/dbus-daemon" sauid=81 hostname=? addr=? terminal=?'
Okt 11 22:56:31 chrissyboy.fritz.box kernel: audit_printk_skb: 6 callbacks suppressed
Okt 11 22:56:31 chrissyboy.fritz.box kernel: audit: type=1107 audit(1476219391.493:271): pid=871 uid=81 auid=4294967295 ses=4294967295 subj=system_u:system_r:system_dbusd_t:s0-s0:c0.c1023 msg='avc:  denied  { send_msg } for msgtype=signa
                                              exe="/usr/bin/dbus-daemon" sauid=81 hostname=? addr=? terminal=?'




Expected results:
a connect 

Additional info:
see bug 1093271, 867755
Comment 1 GroovieMan 2017-07-05 09:48:59 UTC 
fixed later