<div dir="auto"><div>Hi Aleksander,<br><br><div class="gmail_quote"><div dir="ltr" class="gmail_attr">On Fri, 5 Jun 2020, 10:22 am Aleksander Morgado, <<a href="mailto:aleksander@aleksander.es">aleksander@aleksander.es</a>> wrote:<br></div><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">Hey!<br>
<br>
> I am facing an unexpected - as far as I can tell - behavior with ModemManager 1.12.8 and network manager is 1.22.10 on Ubuntu 20.04, the modem is LE910 V2.<br>
><br>
> During and ongoing connection, lasting since 38 minutes, the tty port is force closed after the following message<br>
><br>
> <debug> (ttyACM3): <-- '+CGEV: ME DEACT IP, "10.29.174.52", 1<CR><LF>'<br>
><br>
> Is it normal that a deactivation leads to a port close?<br>
><br>
<br>
No it isn't, this happened due to the mixup of the CLOCAL settings,<br>
full relevant logs:<br>
<br>
Jun 3 16:49:22 orndura ModemManager[418]: <debug> (ttyACM3): <--<br>
'+CGEV: ME DEACT IP, "10.29.174.52", 1<CR><LF>'<br>
Jun 3 16:49:22 orndura ModemManager[418]: <info> mobile equipment<br>
request to deactivate context (type IP, address 10.29.174.52, cid 1)<br>
Jun 3 16:49:22 orndura NetworkManager[411]: <info> [1591192162.3478]<br>
modem["ttyACM0"]: modem state changed, 'connected' --> 'registered'<br>
(reason: user-requested)<br>
Jun 3 16:49:22 orndura ModemManager[418]: <info> Bearer<br>
/org/freedesktop/ModemManager1/Bearer/1: explicitly disconnected<br>
<br>
MM detects the disconnection and by itself reports the modem<br>
disconnected, it shouldn't have done that, it should have ignored the<br>
disconnection report detected by itself and wait for pppd to detect it<br>
itself.<br>
<br>
Jun 3 16:49:22 orndura NetworkManager[411]: <info> [1591192162.3479]<br>
device (ttyACM0): state change: activated -> failed (reason<br>
'modem-no-carrier', sys-iface-state: 'managed')<br>
Jun 3 16:49:22 orndura ModemManager[418]: <debug> (ttyACM0): port now<br>
disconnected<br>
Jun 3 16:49:22 orndura NetworkManager[411]: <debug> [1591192162.3501]<br>
active-connection[0x19bb480]: set state deactivated (was activated)<br>
Jun 3 16:49:22 orndura ModemManager[418]: <info> Modem<br>
/org/freedesktop/ModemManager1/Modem/1: state changed (connected -><br>
registered)<br>
Jun 3 16:49:22 orndura NetworkManager[411]: <info> [1591192162.3508]<br>
manager: NetworkManager state is now DISCONNECTED<br>
Jun 3 16:49:22 orndura NetworkManager[411]: <debug> [1591192162.3515]<br>
active-connection[0x19bb480]: check-master-ready: not signalling<br>
(state deactivated, no master)<br>
Jun 3 16:49:22 orndura pppd[2774]: Modem hangup<br>
Jun 3 16:49:22 orndura NetworkManager[411]: Modem hangup<br>
<br>
CHUP happens in the TTY, which MM also detects because it was already<br>
also monitoring the port.<br>
<br>
Jun 3 16:49:22 orndura pppd[2774]: nm-ppp-plugin: (nm_phasechange):<br>
status 8 / phase 'network'<br>
Jun 3 16:49:22 orndura ModemManager[418]: <debug> (ttyACM3): <-- '<CR><LF>'<br>
Jun 3 16:49:22 orndura ModemManager[418]: <debug> (ttyACM0)<br>
unexpected port hangup!<br>
Jun 3 16:49:22 orndura ModemManager[418]: <debug> (ttyACM0) forced to<br>
close port<br>
<br>
MM treats the HUP as a port removal, which shouldn't have happened if<br>
the disconnection was reported by NM after pppd exit, as CLOCAL<br>
settings would have been reset.<br>
<br>
I thought this should have been fixed in<br>
5f29bd64de8127cb326488d68a2a2b64a45e1f45, but I don't see the relevant<br>
"PPP is required for connection, will ignore disconnection reports"<br>
message that should happen once the modem gets connected in PPP mode.<br>
<br>
If you have the setup with you, could you check why this message is<br>
not being logged?<br>
<br>
-- <br>
Aleksander<br>
<a href="https://aleksander.es" rel="noreferrer noreferrer" target="_blank">https://aleksander.es</a></blockquote></div></div><div dir="auto"><br></div><div dir="auto">Thanks for the analysis!</div><div dir="auto">We were thinking about something related to clocal, but not this detail, indeed.</div><div dir="auto">I'll double check with the setup and keep, and report it back</div><div dir="auto"><br></div><div dir="auto">Carlo</div><div dir="auto"><br></div><div dir="auto"> I'll double check the setup, and </div><div dir="auto"><div class="gmail_quote"><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><br>
</blockquote></div></div></div>