ModemManager and Roaming

[Dan Williams]

On Tue, 2016-07-19 at 10:32 +0200, Aleksander Morgado wrote:
> On Tue, Jul 19, 2016 at 12:57 AM, Ali Nematollahi <alirezan1 at gmail.co
> m> wrote:
> > 
> > ModemManager[3100]: <debug> [946686725.704970] [mm-port-serial-
> > at.c:440]
> > debug_log(): (ttyUSB3): --> 'ATD*99***1#<CR>'
> > ModemManager[3100]: <debug> [946686725.732977] [mm-port-serial-
> > at.c:440]
> > debug_log(): (ttyUSB3): <-- '<CR><LF>CONNECT<CR><LF>'
> > ModemManager[3100]: <debug> [946686725.733962] [mm-port-
> > serial.c:1294]
> > _close_internal(): (ttyUSB3) device open count is 1 (close)
> > ModemManager[3100]: <debug> [946686725.734971] [mm-port.c:95]
> > mm_port_set_connected(): (ttyUSB3): port now connected
> > ModemManager[3100]: <debug> [946686725.735756] [mm-base-
> > bearer.c:488]
> > connect_ready(): Connected bearer
> > '/org/freedesktop/ModemManager1/Bearer/0'
> > ModemManager[3100]: <info>  [946686725.737193] [mm-iface-
> > modem.c:1392]
> > __iface_modem_update_state_internal(): Modem
> > /org/freedesktop/ModemManager1/Modem/0: state changed (connecting
> > ->
> > connected)
> > ModemManager[3100]: <info>  [946686725.749835] [mm-iface-modem-
> > simple.c:602]
> > connection_step(): Simple connect state (8/8): All done
> > ModemManager[3100]: <debug> [946686725.972099] [mm-base-
> > manager.c:296]
> > device_added(): (net/ppp0): could not get port's parent device
> > ModemManager[3100]: <debug> [946686734.124031] [mm-port-serial-
> > at.c:440]
> > debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 2<CR><LF>'
> > ModemManager[3100]: <info>  [946686734.124519] [mm-iface-modem-
> > 3gpp.c:1179]
> > update_registration_state(): Modem
> > /org/freedesktop/ModemManager1/Modem/0:
> > 3GPP Registration state changed (roaming -> searching)
> > ModemManager[3100]: <debug> [946686734.125235]
> > [mm-iface-modem-location.c:295] notify_3gpp_location_update():
> > Modem
> > /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC:
> > '0',
> > MNC: '0', Location area code: '3E80', Cell ID: 'E0E0')
> > ModemManager[3100]: <debug> [946686734.125981]
> > [mm-iface-modem-location.c:295] notify_3gpp_location_update():
> > Modem
> > /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC:
> > '0',
> > MNC: '0', Location area code: '0', Cell ID: '0')
> > ModemManager[3100]: <debug> [946686745.722412] [mm-base-
> > bearer.c:760]
> > mm_base_bearer_disconnect(): Disconnecting bearer
> > '/org/freedesktop/ModemManager1/Bearer/0'
> > ModemManager[3100]: <info>  [946686745.722844] [mm-iface-
> > modem.c:1392]
> > __iface_modem_update_state_internal(): Modem
> > /org/freedesktop/ModemManager1/Modem/0: state changed (connected ->
> > disconnecting)
> The roaming bit we manage is working, as otherwise you wouldn't  even
> be able to see a connection attempt.
> 
> What's happening here is that you lost your registration to the
> network once connected (see the roaming -> searching step above).
> This
> could be the network kicking you out for some reason?

I also noticed that ^SIND? times out after 3 seconds, then MM sends
CREG? and right after that the ^SIND reply comes in.  Is that just the
modem being stupid and delaying the response, or is it some serial
thing where the reply doesn't come until MM sends more data?

Dan