Quectel EC25 modem failed to be enabled with MM 1.22.0 and QMI 1.34.0
Florin
florin at balena.io
Wed Jul 31 19:42:26 UTC 2024
Hi Aleksander,
I followed-up on
https://gitlab.freedesktop.org/mobile-broadband/ModemManager/-/issues/874
and the problem seems to be resolved in newer version.
Specifically, I backported these 2 patches:
https://github.com/balena-os/meta-balena/pull/3478/commits/2d53d2cb60a155ece0563fbb39629056e2c959d0
On Wed, Jul 31, 2024 at 6:28 PM Aleksander Morgado <
aleksandermj at chromium.org> wrote:
> 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
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20240731/a17a8b35/attachment-0001.htm>
More information about the ModemManager-devel
mailing list