Quectel EC25 modem failed to be enabled with MM 1.22.0 and QMI 1.34.0
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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@0/32f10108.usb/38200000.usb/xhci-hcd.2.auto/usb3/3-1/3-1.3
| physdev: /sys/devices/platform/soc@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