ModemManager floods log and cannot reconnect after sudden disconnect
Affects | Status | Importance | Assigned to | Milestone | |
---|---|---|---|---|---|
Raspbian |
New
|
Undecided
|
Unassigned |
Bug Description
To configure my RaspberryPi 4 to use a UBlox SARA-U201 4G USB Modem I used nmcli and mmcli. It connects after a minute after each boot now:
:~ $ mmcli -m 0
-----
General | dbus path: /org/freedeskto
| device id: xxxx
-----
Hardware | manufacturer: u-blox
| model: SARA-U201
| revision: 23.60
| supported: gsm-umts
| current: gsm-umts
| equipment id: 358887095191472
-----
System | device: /sys/devices/
| drivers: cdc_acm
| plugin: u-blox
| primary port: ttyACM0
| ports: ttyACM3 (unknown), ttyACM4 (unknown), ttyACM5 (unknown),
| ttyACM6 (unknown), ttyACM0 (at), ttyACM1 (at), ttyACM2 (at)
-----
Status | unlock retries: sim-pin (3), sim-pin2 (3), sim-puk (10), sim-puk2 (10)
| state: connected
| power state: on
| access tech: umts
| signal quality: 20% (recent)
-----
Modes | supported: allowed: 2g; preferred: none
| allowed: 2g, 3g; preferred: none
| allowed: 2g, 3g; preferred: 2g
| allowed: 2g, 3g; preferred: 3g
| allowed: 3g; preferred: none
| current: allowed: 2g, 3g; preferred: 3g
-----
Bands | supported: egsm, dcs, pcs, g850, utran-1, utran-3, utran-4, utran-6,
| utran-5, utran-8, utran-2, utran-7, utran-11
| current: egsm, pcs, g850, utran-1, utran-6, utran-5, utran-8, utran-2
-----
IP | supported: ipv4, ipv6, ipv4v6
-----
3GPP | imei: xxxx
| operator id: xxxx
| operator name: vodafone NL
| registration: roaming
-----
SIM | dbus path: /org/freedeskto
-----
Bearer | dbus path: /org/freedeskto
But randomly it disconnects and starts flooding the log, not willing to connect again. The reason it cannot reconnect again is:
NetworkManager[
Of which I think the issue is addressed here: https:/
Below is the output of a part of journalctl --boot -1
Dec 13 16:46:23 447452017112 run.sh[362]: wal
Dec 13 16:46:24 447452017112 run.sh[362]: wal
Dec 13 16:46:24 447452017112 pppd[2394]: LCP terminated by peer
Dec 13 16:46:24 447452017112 pppd[2394]: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
Dec 13 16:46:24 447452017112 NetworkManager[
Dec 13 16:46:24 447452017112 NetworkManager[
Dec 13 16:46:24 447452017112 NetworkManager[
Dec 13 16:46:24 447452017112 pppd[2394]: Connect time 6.1 minutes.
Dec 13 16:46:24 447452017112 NetworkManager[
Dec 13 16:46:24 447452017112 pppd[2394]: Sent 7957670 bytes, received 2555477 bytes.
Dec 13 16:46:24 447452017112 pppd[2394]: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
Dec 13 16:46:24 447452017112 run.sh[362]: wal
Dec 13 16:46:25 447452017112 run.sh[362]: wal
Dec 13 16:46:25 447452017112 pppd[2394]: Modem hangup
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 pppd[2394]: nm-ppp-plugin: (nm_phasechange): status 11 / phase 'disconnect'
Dec 13 16:46:25 447452017112 pppd[2394]: Connection terminated.
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> network request to deactivate context (type IP, address 172.33.70.239, cid 5)
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Bearer /org/freedeskto
Dec 13 16:46:25 447452017112 ModemManager[395]: <warn> (ttyACM0): could not re-acquire serial port lock: (5) Input/output error
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Modem /org/freedeskto
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 dbus-daemon[369]: [system] Activating via systemd: service name='org.
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 systemd[1]: Starting Network Manager Script Dispatcher Service...
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect started...
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (4/8): Wait to get fully enabled
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (5/8): Register
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (6/8): Bearer
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect started...
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (4/8): Wait to get fully enabled
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (5/8): Register
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (6/8): Bearer
Dec 13 16:46:25 447452017112 dbus-daemon[369]: [system] Successfully activated service 'org.freedeskto
Dec 13 16:46:25 447452017112 systemd[1]: Started Network Manager Script Dispatcher Service.
Dec 13 16:46:25 447452017112 nm-dispatcher[
Dec 13 16:46:25 447452017112 nm-dispatcher[
Dec 13 16:46:25 447452017112 nm-dispatcher[
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect started...
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (4/8): Wait to get fully enabled
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (5/8): Register
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (6/8): Bearer
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect started...
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (4/8): Wait to get fully enabled
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (5/8): Register
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (6/8): Bearer
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect started...
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (4/8): Wait to get fully enabled
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (5/8): Register
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (6/8): Bearer
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect started...
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (4/8): Wait to get fully enabled
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (5/8): Register
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (6/8): Bearer
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 NetworkManager[
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect started...
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (4/8): Wait to get fully enabled
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (5/8): Register
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (6/8): Bearer
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect started...
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (4/8): Wait to get fully enabled
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (5/8): Register
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (6/8): Bearer
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect started...
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (4/8): Wait to get fully enabled
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (5/8): Register
Dec 13 16:46:25 447452017112 ModemManager[395]: <info> Simple connect state (6/8): Bearer
Dec 13 16:46:25 447452017112 NetworkManager[