<div dir="ltr"><div>Hi Aleksander,</div><div>I followed-up on <a href="https://gitlab.freedesktop.org/mobile-broadband/ModemManager/-/issues/874">https://gitlab.freedesktop.org/mobile-broadband/ModemManager/-/issues/874</a> and the problem seems to be resolved in newer version.</div><div>Specifically, I backported these 2 patches: <a href="https://github.com/balena-os/meta-balena/pull/3478/commits/2d53d2cb60a155ece0563fbb39629056e2c959d0">https://github.com/balena-os/meta-balena/pull/3478/commits/2d53d2cb60a155ece0563fbb39629056e2c959d0</a></div></div> <div class="gmail_quote"><div dir="ltr" class="gmail_attr">On Wed, Jul 31, 2024 at 6:28 PM Aleksander Morgado <<a href="mailto:aleksandermj@chromium.org">aleksandermj@chromium.org</a>> wrote: </div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">On Wed, Jul 31, 2024 at 5:12 PM Florin <<a href="mailto:florin@balena.io" target="_blank">florin@balena.io</a>> 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@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 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@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: <a href="https://www.mail-archive.com/modemmanager-devel@lists.freedesktop.org/msg06797.html" rel="noreferrer" target="_blank">https://www.mail-archive.com/modemmanager-devel@lists.freedesktop.org/msg06797.html</a> > 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 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@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@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? > 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 </blockquote></div>