On Wed, Jul 31, 2024 at 5:12 PM Florin <flo...@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@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: > 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 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
