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

[Geert Lens]

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/c39db082/attachment.htm>