QMI modems with MM-master branch error

Fri May 5 11:36:46 UTC 2017

Hi again,

Quectel EC25 works perfectly now, but TelitLE910 is still failing the first attempt of connection.  The second attempt succeeded. Maybe is needed a timeout or something?

I send you the logs:

root at ccimx6ulsbc:~# mmcli -m 0 --simple-connect="apn=ac.vodafone.es,pin=8770"
ModemManager[918]: <info>  Simple connect started...
ModemManager[918]: <info>  Simple connect state (1/8): Unlock check
ModemManager[918]: <info>  Simple connect state (2/8): Wait to get fully initialized
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (locked -> initializing)
ModemManager[918]: <warn>  couldn't load Operator identifier: 'Couldn't get home network: QMI protocol error (16): 'NotProvisioned''
ModemManager[918]: <warn>  couldn't load Operator name: 'Couldn't get home network: QMI protocol error (16): 'NotProvisioned''
ModemManager[918]: <warn>  couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (initializing -> disabled)
ModemManager[918]: <info>  Simple connect state (3/8): Enable
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)
ModemManager[918]: <info>  Simple connect state (4/8): Wait to get fully enabled
ModemManager[918]: <info>  Simple connect state (5/8): Register
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> searching)
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> searching)
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (searching -> idle)
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> registering)
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (searching -> registered)
ModemManager[918]: <info>  Simple connect state (6/8): Bearer
ModemManager[918]: <info>  Simple connect state (7/8): Connect
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
ModemManager[918]: [/dev/cdc-wdm0] Allocating new client ID...
ModemManager[918]: [/dev/cdc-wdm0] Registered 'wds' (version 1.36) client with ID '11'
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (home -> idle)
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> disconnecting)
ModemManager[918]: <warn>  Error disconnecting bearer '/org/freedesktop/ModemManager1/Bearer/0': 'Couldn't disconnect QMI bearer: this bearer is not connected'. Will assume disconnected anyway.
error: couldn't connect the modem: 'GDBus.Error:org.freedesktop.ModemManager1.Error.Core.Cancelled: Connection setup operation has been cancelled'
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> enabled)
root at ccimx6ulsbc:~# ModemManager[918]: [/dev/cdc-wdm0] No transaction matched in received message

root at ccimx6ulsbc:~# mmcli -m 0 --simple-connect="apn=ac.vodafone.es,pin=8770"ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> registering)
ModemManager[918]: <warn>  Couldn't load Operator Name: 'Current operator description is still unknown'
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> registered)

ModemManager[918]: <info>  Simple connect started...
ModemManager[918]: <info>  Simple connect state (4/8): Wait to get fully enabled
ModemManager[918]: <info>  Simple connect state (5/8): Register
ModemManager[918]: <info>  Simple connect state (6/8): Bearer
ModemManager[918]: <info>  Simple connect state (7/8): Connect
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
ModemManager[918]: <info>  QMI IPv4 Settings:
ModemManager[918]: <info>      Address: 77.210.242.158/30
ModemManager[918]: <info>      Gateway: 77.210.242.157
ModemManager[918]: <info>      DNS #1: 212.166.210.6
ModemManager[918]: <info>      DNS #2: 212.73.32.67
ModemManager[918]: <info>         MTU: 1430
ModemManager[918]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
ModemManager[918]: <info>  Simple connect state (8/8): All done
successfully connected the modem

Tell me if you need debug logs.

Thanks,

Salvador

-----Original Message-----
From: Aleksander Morgado [mailto:aleksander at aleksander.es] 
Sent: viernes, 05 de mayo de 2017 11:40
To: Penalva, Salvador
Cc: ModemManager (development); Ben Chan
Subject: Re: QMI modems with MM-master branch error

Hey,

On Fri, May 5, 2017 at 11:01 AM, Penalva, Salvador <Salvador.Penalva at digi.com> wrote:
> Hi have been trying to connect two different QMI modems: Telit LE910 and Quectel EC25 with ModemManager master branch and I get the following error messages:
>
> ModemManager[937]: <info>  Simple connect state (7/8): Connect
> ModemManager[937]: <info>  Modem 
> /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> 
> connecting)
> ModemManager[937]: [/dev/cdc-wdm0] Allocating new client ID...
> ModemManager[937]: [/dev/cdc-wdm0] Registered 'wds' (version 1.67) client with ID '19'
> ModemManager[937]: qmi_client_get_next_transaction_id: assertion 
> 'QMI_IS_CLIENT (self)' failed
> ModemManager[937]: qmi_client_get_cid: assertion 'QMI_IS_CLIENT 
> (self)' failed
> ModemManager[937]: qmi_client_peek_device: assertion 'QMI_IS_CLIENT 
> (self)' failed
> ModemManager[937]: qmi_device_command_full: assertion 'QMI_IS_DEVICE 
> (self)' failed
> error: couldn't connect the modem: 'Timeout was reached'

The issue was introduced by commit 26ee94ec28b6f68 (port-qmi: port mm_port_qmi_allocate_client to use GTask); I already fixed it now in git master, please pull and retry.

Cheers!

--
Aleksander
https://aleksander.es