EC25 on Compulab IOT-GATE-iMX8PLUS 2G-4G DRAM fails to initialize until the ModemManager service is restarted.
Upon a boot or reboot, the Modem Manager Logs look like this:
Mar 26 19:32:25 localhost ModemManager[1194]: ModemManager (version 1.22.0) starting in system bus...
Mar 26 19:32:29 d3bc7f7 ModemManager[1194]: [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
Mar 26 19:32:29 d3bc7f7 ModemManager[1194]: [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
Mar 26 19:32:29 d3bc7f7 ModemManager[1194]: [base-manager] couldn't check support for device '/sys/devices/platform/soc@0/30800000.bus/308c0000.can': not supported by any plugin
Mar 26 19:32:29 d3bc7f7 ModemManager[1194]: [base-manager] couldn't check support for device '/sys/devices/platform/soc@0/30800000.bus/308d0000.can': not supported by any plugin
Mar 26 19:32:29 d3bc7f7 ModemManager[1194]: [base-manager] couldn't check support for device '/sys/devices/platform/soc@0/30800000.bus/30be0000.ethernet': not supported by any plugin
Mar 26 19:32:29 d3bc7f7 ModemManager[1194]: [base-manager] couldn't check support for device '/sys/devices/platform/soc@0/30800000.bus/30bf0000.ethernet': not supported by any plugin
Mar 26 19:32:29 d3bc7f7 ModemManager[1194]: [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
Mar 26 19:32:30 d3bc7f7 ModemManager[1194]: [modem0] unhandled QMI radio interface '9'
Mar 26 19:32:30 d3bc7f7 ModemManager[1194]: [modem0] unhandled QMI radio interface '9'
Mar 26 19:32:31 d3bc7f7 ModemManager[1194]: [modem0] state changed (unknown -> disabled)
Mar 26 19:32:31 d3bc7f7 ModemManager[1194]: [modem0] state changed (disabled -> enabling)
Mar 26 19:32:32 d3bc7f7 ModemManager[1194]: [modem0] simple connect started...
Mar 26 19:32:32 d3bc7f7 ModemManager[1194]: [modem0] simple connect state (4/10): wait to get fully enabled
Mar 26 19:32:32 d3bc7f7 ModemManager[1194]: [modem0] power state updated: on
Mar 26 19:32:35 d3bc7f7 ModemManager[1194]: [modem0] (quectel) modem reset detected, triggering reprobe
Mar 26 19:32:35 d3bc7f7 ModemManager[1194]: [modem0] (quectel) modem reset detected, triggering reprobe
Mar 26 19:32:35 d3bc7f7 ModemManager[1194]: [modem0] (quectel) couldn't check SIM swap: could not acquire SIM object
Mar 26 19:32:38 d3bc7f7 ModemManager[1194]: [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
Mar 26 19:32:42 d3bc7f7 ModemManager[1194]: [modem0] failed waiting for final state: Too much time waiting to get to a final state
Mar 26 19:32:43 d3bc7f7 ModemManager[1194]: [modem0] failed enabling modem: Operation was cancelled
Mar 26 19:35:48 d3bc7f7 ModemManager[1194]: [modem1] fatal error initializing: Fatal error: modem is unusable
After restarting the service it works:
Mar 26 19:36:28 d3bc7f7 ModemManager[4051]: ModemManager (version 1.22.0) starting in system bus...
Mar 26 19:36:32 d3bc7f7 ModemManager[4051]: [base-manager] couldn't check support for device '/sys/devices/platform/soc@0/30800000.bus/308c0000.can': not supported by any plugin
Mar 26 19:36:32 d3bc7f7 ModemManager[4051]: [base-manager] couldn't check support for device '/sys/devices/platform/soc@0/30800000.bus/308d0000.can': not supported by any plugin
Mar 26 19:36:32 d3bc7f7 ModemManager[4051]: [base-manager] couldn't check support for device '/sys/devices/platform/soc@0/30800000.bus/30be0000.ethernet': not supported by any plugin
Mar 26 19:36:32 d3bc7f7 ModemManager[4051]: [base-manager] couldn't check support for device '/sys/devices/platform/soc@0/30800000.bus/30bf0000.ethernet': not supported by any plugin
Mar 26 19:36:32 d3bc7f7 ModemManager[4051]: [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
Mar 26 19:38:06 d3bc7f7 ModemManager[4051]: [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
Mar 26 19:38:06 d3bc7f7 ModemManager[4051]: [plugin/quectel] could not grab port cdc-wdm0: Cannot add port 'usbmisc/cdc-wdm0', unhandled port type
Mar 26 19:38:06 d3bc7f7 ModemManager[4051]: [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
Mar 26 19:38:06 d3bc7f7 ModemManager[4051]: [modem0] state changed (unknown -> disabled)
Mar 26 19:38:06 d3bc7f7 ModemManager[4051]: [modem0] state changed (disabled -> enabling)
Mar 26 19:38:06 d3bc7f7 ModemManager[4051]: [modem0] simple connect started...
Mar 26 19:38:06 d3bc7f7 ModemManager[4051]: [modem0] simple connect state (4/10): wait to get fully enabled
Mar 26 19:38:07 d3bc7f7 ModemManager[4051]: [modem0] power state updated: on
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] state changed (enabling -> enabled)
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] simple connect state (5/10): wait after enabled
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] 3GPP registration state changed (unknown -> registering)
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] 3GPP packet service state changed (unknown -> attached)
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] 3GPP registration state changed (registering -> roaming)
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] state changed (enabled -> registered)
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] simple connect state (6/10): register
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] simple connect state (7/10): wait to get packet service state attached
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] simple connect state (8/10): bearer
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] simple connect state (9/10): connect
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] state changed (registered -> connecting)
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] state changed (connecting -> connected)
Mar 26 19:38:08 d3bc7f7 ModemManager[4051]: [modem0] simple connect state (10/10): all done
Notice the large gap in time between the last [base-manager] and the next entry.
I’m not sure where to go from here, Is ModemManager simply starting too early or is it something else?