QMI protocol error (14): 'CallFailed' verbose call end reason (3,2500): [cm] offline

Geert Lens g.lens at livetech-systems.com
Wed Oct 14 15:30:12 UTC 2020 

Hi,

Sending the logs again as the formatting broke

ModemManager logs:
Oct 14 13:28:33 vc-f16xs10183 ModemManager[143]: <info>  Modem: state
changed (unknown -> disabled)
Oct 14 13:28:33 vc-f16xs10183 ModemManager[143]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
Oct 14 13:28:34 vc-f16xs10183 ModemManager[143]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)
Oct 14 13:28:34 vc-f16xs10183 ModemManager[143]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed
(unknown -> registering)
Oct 14 13:28:34 vc-f16xs10183 ModemManager[143]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed
(registering -> roaming)
Oct 14 13:28:34 vc-f16xs10183 ModemManager[143]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (enabled ->
registered)
Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]: <info>  Simple connect
started...
Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]: <info>  Simple connect
state (4/8): Wait to get fully enabled
Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]: <info>  Simple connect
state (5/8): Register
Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]: <info>  Simple connect
state (6/8): Bearer
Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]: <info>  Simple connect
state (7/8): Connect
Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (registered ->
connecting)
Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]: [/dev/cdc-wdm0] Allocating
new client ID...
Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]: [/dev/cdc-wdm0] Registered
'wds' (version 1.67) client with ID '1'
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  error: couldn't
start network: QMI protocol error (14): 'CallFailed'
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  call end reason
(1): 'generic-unspecified'
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  verbose call end
reason (3,2500): [cm] offline
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (connecting ->
registered)
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  Simple connect
started...
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  Simple connect
state (4/8): Wait to get fully enabled
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  Simple connect
state (5/8): Register
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  Simple connect
state (6/8): Bearer
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  Simple connect
state (7/8): Connect
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (registered ->
connecting)
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  error: couldn't
start network: QMI protocol error (14): 'CallFailed'
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  call end reason
(1): 'generic-unspecified'
Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  verbose call end
reason (3,2500): [cm] offline

qmicli logs:
vc-f16xs10183:~$ qmicli --device=/dev/cdc-wdm0 --device-open-proxy
--wds-start-network="ip-type=4,apn=vf-viriciti" --client-no-release-cid
error: couldn't start network: QMI protocol error (14): 'CallFailed'
call end reason (1): generic-unspecified
verbose call end reason (3,2500): [cm] offline
[/dev/cdc-wdm0] Client ID not released:
        Service: 'wds'
            CID: '1'
vc-f16xs10183:~$
vc-f16xs10183:~$ usb 1-1: USB disconnect, device number 32
option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
option 1-1:1.0: device disconnected
option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
option 1-1:1.1: device disconnected
option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
option 1-1:1.2: device disconnected
option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
option 1-1:1.3: device disconnected
qmi_wwan 1-1:1.4 wwan0: unregister 'qmi_wwan' usb-ci_hdrc.0-1, WWAN/QMI
device
usb 1-1: new high-speed USB device number 33 using ci_hdrc
option 1-1:1.0: GSM modem (1-port) converter detected
usb 1-1: GSM modem (1-port) converter now attached to ttyUSB0
option 1-1:1.1: GSM modem (1-port) converter detected
usb 1-1: GSM modem (1-port) converter now attached to ttyUSB1
option 1-1:1.2: GSM modem (1-port) converter detected
usb 1-1: GSM modem (1-port) converter now attached to ttyUSB2
option 1-1:1.3: GSM modem (1-port) converter detected
usb 1-1: GSM modem (1-port) converter now attached to ttyUSB3
qmi_wwan 1-1:1.4: cdc-wdm0: USB WDM device
qmi_wwan 1-1:1.4 wwan0: register 'qmi_wwan' at usb-ci_hdrc.0-1, WWAN/QMI
device, f2:2e:33:2c:71:d4

Thanks,
Geert Lens


On Wed, 14 Oct 2020 at 17:22, Geert Lens <g.lens at livetech-systems.com>
wrote:

> Hi,
>
> I am trying to get my device (Quectel BG96) connected via QMI.
> However when I add the GSM connection to NetworkManager using:
>
>
> *nmcli c add type gsm ifname cdc-wdm0 apn vf-viriciti gsm.number *99***1#
> ipv6.method "ignore"*
>
> then the call via ModemManager fails and gives an error and the modem
> resets itself:
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
> *Oct 14 13:28:33 vc-f16xs10183 ModemManager[143]: <info>  Modem: state
> changed (unknown -> disabled)Oct 14 13:28:33 vc-f16xs10183
> ModemManager[143]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0:
> state changed (disabled -> enabling)Oct 14 13:28:34 vc-f16xs10183
> ModemManager[143]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0:
> state changed (enabling -> enabled)Oct 14 13:28:34 vc-f16xs10183
> ModemManager[143]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0:
> 3GPP Registration state changed (unknown -> registering)Oct 14 13:28:34
> vc-f16xs10183 ModemManager[143]: <info>  Modem
> /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed
> (registering -> roaming)Oct 14 13:28:34 vc-f16xs10183 ModemManager[143]:
> <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed
> (enabled -> registered)Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]:
> <info>  Simple connect started...Oct 14 13:28:39 vc-f16xs10183
> ModemManager[143]: <info>  Simple connect state (4/8): Wait to get fully
> enabledOct 14 13:28:39 vc-f16xs10183 ModemManager[143]: <info>  Simple
> connect state (5/8): RegisterOct 14 13:28:39 vc-f16xs10183
> ModemManager[143]: <info>  Simple connect state (6/8): BearerOct 14
> 13:28:39 vc-f16xs10183 ModemManager[143]: <info>  Simple connect state
> (7/8): ConnectOct 14 13:28:39 vc-f16xs10183 ModemManager[143]: <info>
>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered ->
> connecting)Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]: [/dev/cdc-wdm0]
> Allocating new client ID...Oct 14 13:28:39 vc-f16xs10183 ModemManager[143]:
> [/dev/cdc-wdm0] Registered 'wds' (version 1.67) client with ID '1'Oct 14
> 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  error: couldn't start
> network: QMI protocol error (14): 'CallFailed'Oct 14 13:28:40 vc-f16xs10183
> ModemManager[143]: <info>  call end reason (1): 'generic-unspecified'Oct 14
> 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  verbose call end reason
> (3,2500): [cm] offlineOct 14 13:28:40 vc-f16xs10183 ModemManager[143]:
> <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed
> (connecting -> registered)Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]:
> <info>  Simple connect started...Oct 14 13:28:40 vc-f16xs10183
> ModemManager[143]: <info>  Simple connect state (4/8): Wait to get fully
> enabledOct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  Simple
> connect state (5/8): RegisterOct 14 13:28:40 vc-f16xs10183
> ModemManager[143]: <info>  Simple connect state (6/8): BearerOct 14
> 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  Simple connect state
> (7/8): ConnectOct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>
>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered ->
> connecting)Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  error:
> couldn't start network: QMI protocol error (14): 'CallFailed'Oct 14
> 13:28:40 vc-f16xs10183 ModemManager[143]: <info>  call end reason (1):
> 'generic-unspecified'Oct 14 13:28:40 vc-f16xs10183 ModemManager[143]:
> <info>  verbose call end reason (3,2500): [cm] offline*
>
> Even if I tried to manually set up the connection using qmicli it fails
> and gives the same error and also causes the modem to reset:
>
>
> *vc-f16xs10183:~$ qmicli --device=/dev/cdc-wdm0 --device-open-proxy
> --wds-start-network="ip-type=4,apn=vf-viriciti" --client-no-release-cid*
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
>
> *error: couldn't start network: QMI protocol error (14): 'CallFailed'call
> end reason (1): generic-unspecifiedverbose call end reason (3,2500): [cm]
> offline[/dev/cdc-wdm0] Client ID not released:        Service: 'wds'
>     CID: '1'vc-f16xs10183:~$ vc-f16xs10183:~$ usb 1-1: USB disconnect,
> device number 32option1 ttyUSB0: GSM modem (1-port) converter now
> disconnected from ttyUSB0option 1-1:1.0: device disconnectedoption1
> ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1option
> 1-1:1.1: device disconnectedoption1 ttyUSB2: GSM modem (1-port) converter
> now disconnected from ttyUSB2option 1-1:1.2: device disconnectedoption1
> ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3option
> 1-1:1.3: device disconnectedqmi_wwan 1-1:1.4 wwan0: unregister 'qmi_wwan'
> usb-ci_hdrc.0-1, WWAN/QMI deviceusb 1-1: new high-speed USB device number
> 33 using ci_hdrcoption 1-1:1.0: GSM modem (1-port) converter detectedusb
> 1-1: GSM modem (1-port) converter now attached to ttyUSB0option 1-1:1.1:
> GSM modem (1-port) converter detectedusb 1-1: GSM modem (1-port) converter
> now attached to ttyUSB1option 1-1:1.2: GSM modem (1-port) converter
> detectedusb 1-1: GSM modem (1-port) converter now attached to ttyUSB2option
> 1-1:1.3: GSM modem (1-port) converter detectedusb 1-1: GSM modem (1-port)
> converter now attached to ttyUSB3qmi_wwan 1-1:1.4: cdc-wdm0: USB WDM
> deviceqmi_wwan 1-1:1.4 wwan0: register 'qmi_wwan' at usb-ci_hdrc.0-1,
> WWAN/QMI device, f2:2e:33:2c:71:d4*
>
> As you can see the device number is pretty high.
>
> I have tried to set up the connection using the latest versions of
> ModemManager (v1.14.2) and libqmi (v1.26.6) as well but this resulted in
> the same error.
>
> I have tried multiple BG96 modems and they all behave like this.
>
> Can anyone tell me what the cause of this could be?
>
> Info about our current operating system:
> *Linux kernel: 4.14.78*
> *ModemManager: 1.12.10*
> *libqmi: 1.24.10*
> *NetworkManager: 1.18.0*
>
> Thanks,
> Geert Lens
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/libqmi-devel/attachments/20201014/6c1fdde3/attachment-0001.htm>

More information about the libqmi-devel mailing list