Quectel EC25 modem failed to be enabled with MM 1.22.0 and QMI 1.34.0
Aleksander Morgado
aleksandermj at chromium.org
Wed Jul 31 15:28:42 UTC 2024
On Wed, Jul 31, 2024 at 5:12 PM Florin <florin at balena.io> wrote:
>
> 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
These lines above may be causing problems, but the "RDY" URC handling
was already present in MM 1.20.4 (see fcd393a6c2a6fa).
> 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
>
Could you update the MM init file so that it starts with --debug and
so we could see the full debug logs?
> 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
This is bad, we're ignoring the cdc-wdm port altogether, so the wwan0
netdev will also be ignored.
> 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:
Yes, this is bad.
>
> 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?
>
As said above, please try to enable debug logs in the MM init file by
adding --debug to the daemon start line, and then share the debug
logs. We need to understand how all this is failing.
--
Aleksander
More information about the ModemManager-devel
mailing list