<div dir="ltr">Hello!<div><br></div><div>I am using network-manager 1.2.6-<wbr>0ubuntu0.16.04.2, modemmanager 1.6.4-1ubuntu0.<wbr>16.04.1 with a Huawei ME909s-120, firmware 11.617.01.00.00.</div><div><br></div><div>We're using a SIM card from EE M2M here in the UK.</div><div><br></div><div>Intermittently when I boot the machine, I get a 3GPP registration denied message in mm logs. Having run mm in debug mode and read through the AT commands going back and forth, I believe mm is correctly reporting the modem state.</div><div><br></div><div>Here's some mm journald logs from a failed attempt (with many lines of noise removed):</div><div><br></div><div><br></div><div><div>ModemManager (version 1.4.12) starting in system bus...</div><div>Creating modem with plugin 'Huawei' and '6' ports</div><div>Modem for device at '/sys/devices/pci0000:00/0000:<wbr>00:15.0/usb1/1-8' successfully created</div><div>mobile equipment error code: 50</div><div>Modem: state changed (unknown -> disabled)</div><div>Modem /org/freedesktop/<wbr>ModemManager1/Modem/0: state changed (disabled -> enabling)</div><div>mobile equipment error code: 50</div><div>Modem /org/freedesktop/<wbr>ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> denied)</div><div>Modem /org/freedesktop/<wbr>ModemManager1/Modem/0: state changed (enabling -> enabled)</div></div><div><br></div><div><br></div><div>I've contacted the network about why the registration is sometimes denied. But I have a couple of questions:</div><div><br></div><div>1. Is registration denied a recoverable error -- and if so should I expect modemmanager to retry registration?</div><div><br></div><div>2. Does the final line in the logs above indicate a problem? It seems strange that the registration failed but then it reports the modem is 'enabled'.</div><div><br></div><div>Finally, if I try to manually reconnect after the above error I get this every time -- instantly, rather than after a delay, even though the message mentions a timeout:</div><div><br></div><div><br></div><div><div>root@host:~# mmcli -m 0 --simple-connect="apn=EEM2M,<wbr>ip-type=ipv4" --verbose</div><div>[09 Mar 2018, 16:55:53] [Debug] Forcing request to be run asynchronously</div><div>[09 Mar 2018, 16:55:53] [Debug] Assuming '0' is the modem index</div><div>[09 Mar 2018, 16:55:53] [Debug] ModemManager process found at ':1.3'</div><div>[09 Mar 2018, 16:55:53] [Debug] Modem found at '/org/freedesktop/<wbr>ModemManager1/Modem/0'</div><div><br></div><div>[09 Mar 2018, 16:55:53] [Debug] Asynchronously connecting the modem...</div><div>error: couldn't connect the modem: 'GDBus.Error:org.freedesktop.<wbr>ModemManager1.Error.<wbr>MobileEquipment.<wbr>NetworkTimeout: Network timeout'</div></div><div><br></div><div><br></div><div>And in the mm journalctl logs:</div><div><br></div><div><br></div><div><div><info>  [1520872097.948148] [mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect started...</div><div><debug> [1520872097.948222] [mm-iface-modem-simple.c:651] connect_auth_ready():    PIN: unspecified</div><div><debug> [1520872097.948244] [mm-iface-modem-simple.c:653] connect_auth_ready():    Operator ID: unspecified</div><div><debug> [1520872097.948263] [mm-iface-modem-simple.c:655] connect_auth_ready():    Allowed roaming: yes</div><div><debug> [1520872097.948282] [mm-iface-modem-simple.c:657] connect_auth_ready():    APN: EEM2M</div><div><debug> [1520872097.948302] [mm-iface-modem-simple.c:662] connect_auth_ready():    IP family: ipv4</div><div><debug> [1520872097.948320] [mm-iface-modem-simple.c:673] connect_auth_ready():    Allowed authentication: unspecified</div><div><debug> [1520872097.948339] [mm-iface-modem-simple.c:675] connect_auth_ready():    User: unspecified</div><div><debug> [1520872097.948356] [mm-iface-modem-simple.c:677] connect_auth_ready():    Password: unspecified</div><div><debug> [1520872097.948375] [mm-iface-modem-simple.c:679] connect_auth_ready():    Number: unspecified</div><div><info>  [1520872097.948392] [mm-iface-modem-simple.c:469] connection_step(): Simple connect state (4/8): Wait to get fully enabled</div><div><info>  [1520872097.948472] [mm-iface-modem-simple.c:478] connection_step(): Simple connect state (5/8): Register</div><div><debug> [1520872097.948519] [mm-iface-modem-3gpp.c:407] mm_iface_modem_3gpp_register_<wbr>in_network(): Launching automatic network registration...</div><div><debug> [1520872097.948600] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 2 (open)</div><div><debug> [1520872097.948679] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT+COPS=0<CR>'</div><div><debug> [1520872097.966674] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'</div><div><debug> [1520872097.966851] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_<wbr>registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')</div><div><debug> [1520872097.966906] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 3 (open)</div><div><debug> [1520872097.966954] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 2 (close)</div><div><debug> [1520872097.967011] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT+CREG?<CR>'</div><div><debug> [1520872097.978564] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>+CREG: 2,3<CR><LF><CR><LF>OK<CR><LF>'</div><div><info>  [1520872097.978719] [mm-iface-modem-3gpp.c:1186] update_registration_state(): Modem /org/freedesktop/<wbr>ModemManager1/Modem/0: 3GPP Registration state changed (idle -> denied)</div><div><debug> [1520872097.978961] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 3 (open)</div><div><debug> [1520872097.979022] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 2 (close)</div><div><debug> [1520872097.979408] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT+CGREG?<CR>'</div><div><debug> [1520872097.993673] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>+CGREG: 2,3<CR><LF><CR><LF>OK<CR><LF>'</div><div><debug> [1520872097.993969] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 1 (close)</div><div><debug> [1520872097.994049] [mm-iface-modem-3gpp.c:262] run_registration_checks_ready(<wbr>): Registration denied</div><div><info>  [1520872097.994097] [mm-iface-modem-3gpp.c:1186] update_registration_state(): Modem /org/freedesktop/<wbr>ModemManager1/Modem/0: 3GPP Registration state changed (denied -> idle)</div></div><div><br></div><div><br></div><div>However if I do 'mmcli -m 0 --reset', it seems to attempt a new connection from scratch (which succeeds intermittently as on a fresh boot).</div><div><br></div><div>Question number 3 is a bit more vague: is the difference between a new 'simple connect' and modem reset expected?</div><div><br></div><div>Many thanks in advance for any insight.</div><div>Best wishes,</div><div><br></div><div>Hugh Warrington</div><div>Engineer</div><div>Hoxton Analytics</div><div><br></div></div>