Quectel EC25 modem failed to be enabled with MM 1.22.0 and QMI 1.34.0

Florin florin at balena.io
Fri Jul 12 10:49:18 UTC 2024 

Hi all,
we updated MM and QMI to the above versions and now the Quectel EC25 modem
won't work anymore.
Previously, with MM 1.20.6 and QMI 1.32.4 the modem functioned correctly.

At system start, the modem fails to get initialized, and MM reports the
following:

root at 786715a:~# journalctl --no-pager -u ModemManager
Jul 08 08:06:40 localhost ModemManager[1178]: ModemManager (version 1.22.0)
starting in system bus...
Jul 08 08:06:44 786715a ModemManager[1178]: [device
/sys/devices/platform/soc at 0/32f10108.usb/38200000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.3]
creating modem with plugin 'quectel' and '6' ports
Jul 08 08:06:44 786715a ModemManager[1178]: [base-manager] modem for device
'/sys/devices/platform/soc at 0/32f10108.usb/38200000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.3'
successfully created
Jul 08 08:06:44 786715a ModemManager[1178]: [base-manager] couldn't check
support for device '/sys/devices/platform/soc at 0/30800000.bus/308c0000.can':
not supported by any plugin
Jul 08 08:06:44 786715a ModemManager[1178]: [base-manager] couldn't check
support for device '/sys/devices/platform/soc at 0/30800000.bus/308d0000.can':
not supported by any plugin
Jul 08 08:06:44 786715a ModemManager[1178]: [base-manager] couldn't check
support for device
'/sys/devices/platform/soc at 0/30800000.bus/30be0000.ethernet':
not supported by any plugin
Jul 08 08:06:44 786715a ModemManager[1178]: [base-manager] couldn't check
support for device
'/sys/devices/platform/soc at 0/30800000.bus/30bf0000.ethernet':
not supported by any plugin
Jul 08 08:06:44 786715a ModemManager[1178]: [base-manager] couldn't check
support for device
'/sys/devices/platform/soc at 0/33800000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0':
not supported by any plugin
Jul 08 08:06:45 786715a ModemManager[1178]: [modem0] unhandled QMI radio
interface '9'
Jul 08 08:06:45 786715a ModemManager[1178]: [modem0] unhandled QMI radio
interface '9'
Jul 08 08:06:46 786715a ModemManager[1178]: [modem0] state changed (unknown
-> disabled)
Jul 08 08:06:46 786715a ModemManager[1178]: [modem0] state changed
(disabled -> enabling)
Jul 08 08:06:46 786715a ModemManager[1178]: [modem0] simple connect
started...
Jul 08 08:06:46 786715a ModemManager[1178]: [modem0] simple connect state
(4/10): wait to get fully enabled
Jul 08 08:06:46 786715a ModemManager[1178]: [modem0] power state updated: on
Jul 08 08:06:50 786715a ModemManager[1178]: [modem0] (quectel) modem reset
detected, triggering reprobe
Jul 08 08:06:50 786715a ModemManager[1178]: [modem0] (quectel) modem reset
detected, triggering reprobe
Jul 08 08:06:50 786715a ModemManager[1178]: [modem0] (quectel) couldn't
check SIM swap: could not acquire SIM object
Jul 08 08:06:53 786715a ModemManager[1178]: [device
/sys/devices/platform/soc at 0/32f10108.usb/38200000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.3]
creating modem with plugin 'quectel' and '6' ports
Jul 08 08:06:57 786715a ModemManager[1178]: [modem0] failed waiting for
final state: Too much time waiting to get to a final state
Jul 08 08:06:57 786715a ModemManager[1178]: [modem0] failed enabling modem:
Operation was cancelled
Jul 08 08:10:03 786715a ModemManager[1178]: [modem1] couldn't start
initialization: Transaction timed out
Jul 08 08:10:03 786715a ModemManager[1178]: [modem1] fatal error
initializing: Fatal error: modem is unusable

The initial timing out issue seems to have been present in the past, like
this post suggest:
https://www.mail-archive.com/modemmanager-devel@lists.freedesktop.org/msg06797.html
I have tried increasing the interval like suggested above but it does not
seem to take it into account.

So upon restarting the MM systemd service, MM now makes some progress in
detecting the modem:

Jul 08 08:10:03 786715a ModemManager[1178]: [modem1] fatal error
initializing: Fatal error: modem is unusable
Jul 08 08:29:02 786715a ModemManager[1178]: caught signal, shutting down...
Jul 08 08:29:02 786715a ModemManager[1178]: ModemManager is shut down
Jul 08 08:29:02 786715a ModemManager[15542]: ModemManager (version 1.22.0)
starting in system bus...
Jul 08 08:29:06 786715a ModemManager[15542]: [base-manager] couldn't check
support for device '/sys/devices/platform/soc at 0/30800000.bus/308c0000.can':
not supported by any plugin
Jul 08 08:29:06 786715a ModemManager[15542]: [base-manager] couldn't check
support for device '/sys/devices/platform/soc at 0/30800000.bus/308d0000.can':
not supported by any plugin
Jul 08 08:29:06 786715a ModemManager[15542]: [base-manager] couldn't check
support for device
'/sys/devices/platform/soc at 0/30800000.bus/30be0000.ethernet':
not supported by any plugin
Jul 08 08:29:06 786715a ModemManager[15542]: [base-manager] couldn't check
support for device
'/sys/devices/platform/soc at 0/30800000.bus/30bf0000.ethernet':
not supported by any plugin
Jul 08 08:29:06 786715a ModemManager[15542]: [base-manager] couldn't check
support for device
'/sys/devices/platform/soc at 0/33800000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0':
not supported by any plugin
Jul 08 08:30:40 786715a ModemManager[15542]: [device
/sys/devices/platform/soc at 0/32f10108.usb/38200000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.3]
creating modem with plugin 'quectel' and '6' ports
Jul 08 08:30:40 786715a ModemManager[15542]: [plugin/quectel] could not
grab port cdc-wdm0: Cannot add port 'usbmisc/cdc-wdm0', unhandled port type
Jul 08 08:30:40 786715a ModemManager[15542]: [base-manager] modem for
device '/sys/devices/platform/soc at 0/32f10108.usb/38200000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.3'
successfully created
Jul 08 08:30:40 786715a ModemManager[15542]: [modem0] state changed
(unknown -> disabled)
Jul 08 08:30:40 786715a ModemManager[15542]: [modem0] state changed
(disabled -> enabling)
Jul 08 08:30:40 786715a ModemManager[15542]: [modem0] simple connect
started...
Jul 08 08:30:40 786715a ModemManager[15542]: [modem0] simple connect state
(4/10): wait to get fully enabled
Jul 08 08:30:41 786715a ModemManager[15542]: [modem0] power state updated:
on
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] state changed
(enabling -> enabled)
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] simple connect state
(5/10): wait after enabled
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] 3GPP registration
state changed (unknown -> registering)
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] 3GPP packet service
state changed (unknown -> attached)
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] 3GPP registration
state changed (registering -> roaming)
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] state changed
(enabled -> registered)
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] simple connect state
(6/10): register
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] simple connect state
(7/10): wait to get packet service state attached
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] simple connect state
(8/10): bearer
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] simple connect state
(9/10): connect
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] state changed
(registered -> connecting)
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] state changed
(connecting -> connected)
Jul 08 08:30:42 786715a ModemManager[15542]: [modem0] simple connect state
(10/10): all done

but as it can be seen above, it errors out at some point: Jul 08 08:30:40
786715a ModemManager[15542]: [plugin/quectel] could not grab port cdc-wdm0:
Cannot add port 'usbmisc/cdc-wdm0', unhandled port type

This makes it ignore the wwan0 port:

root at 786715a:~# mmcli -m 0
  --------------------------------
  General  |                 path: /org/freedesktop/ModemManager1/Modem/0
           |            device id: 21e80447381cd2cd9538ba5eea33a9356cefec6e
  --------------------------------
  Hardware |         manufacturer: Quectel
           |                model: EC25
           |    firmware revision: EC25AFAR05A06M4G
           |            supported: gsm-umts, lte
           |              current: gsm-umts, lte
           |         equipment id: 860858061494670
  --------------------------------
  System   |               device: /sys/devices/platform/soc at 0
/32f10108.usb/38200000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.3
           |              physdev: /sys/devices/platform/soc at 0
/32f10108.usb/38200000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.3
           |              drivers: option, qmi_wwan
           |               plugin: quectel
           |         primary port: ttyUSB2
           |                ports: ttyUSB0 (ignored), ttyUSB1 (gps),
ttyUSB2 (at),
           |                       ttyUSB3 (at), wwan0 (ignored)
  --------------------------------
  Status   |       unlock retries: sim-pin (3), sim-puk (10), sim-pin2 (3),
sim-puk2 (10)
           |                state: connected
           |          power state: on
           |          access tech: lte
           |       signal quality: 80% (recent)
  --------------------------------
  Modes    |            supported: allowed: 2g, 3g, 4g; preferred: none
           |              current: allowed: 2g, 3g, 4g; preferred: none
  --------------------------------
  IP       |            supported: ipv4, ipv6, ipv4v6
  --------------------------------
  3GPP     |                 imei: 860858061494670
           |          operator id: 310410
           |        operator name: AT&T Hologram
           |         registration: roaming
           | packet service state: attached
  --------------------------------
  3GPP EPS | ue mode of operation: ps-2
  --------------------------------
  SIM      |     primary sim path: /org/freedesktop/ModemManager1/SIM/0
  --------------------------------
  Bearer   |                paths: /org/freedesktop/ModemManager1/Bearer/0

Any advice on what to look for to determine the issue?

Thank you
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20240712/ffc189b3/attachment-0001.htm>

More information about the ModemManager-devel mailing list