Flash successfull but couldn't enable modem - GDBus.Error:org.freedesktop.ModemManager1.Error.Core.Retry: Invalid transition' #170

luckylinux · 2024-01-01T17:05:52Z

luckylinux
Jan 1, 2024

I could update the firmware after slightly modifying the script.
See #71 and
autoflash-7455.sh.txt

I also trying to force FCC Unlock, given what some other users reported and the general guide at https://modemmanager.org/docs/modemmanager/fcc-unlock/.

Firmware Version is the latest available today (02.38.00.00).

root@SierraFirmwareUpdater:/usr/src/autoflash_sierra_firmware_2024# ls -l
total 125060
-rwxr-xr-x 1 root root    16673 Jan  1 15:53 autoflash-7455.sh
-rwxr-xr-x 1 root root    16673 Jan  1 17:46 autoflash-7455.sh.txt
-rw-r--r-- 1 root root      253 Jan  1 16:52 flash.sh
-rw-r--r-- 1 root root      801 Jan  1 16:49 modem.log
-rw-r--r-- 1 root root      682 Jan  1 16:49 script.txt
-rwxr-xr-x 1 root root 64330846 Jan 14  2022 SWI9X30C_02.38.00.00.cwe
-rwxr-xr-x 1 root root    23855 Jan 14  2022 SWI9X30C_02.38.00.00_GENERIC_002.082_000.nvu
-rw-r--r-- 1 root root 63618715 Jan  1 15:57 SWI9X30C_02.38.00.00_GENERIC_002.082_000.zip
-rwxr-xr-x 1 root root    22365 Jan  1 15:54 swi_setusbcomp.pl

Flashing log (unfortunately I had to flash several times because I got stuck in this , so only the last flash is shown here):

AT
OK
AT!ENTERCND="A710"
OK
AT!USBCOMP=1,1,0000100D
OK
AT!RESET
OK
AT
OK
ATE1
OK
ATI
Manufacturer: Sierra Wireless, Incorporated
Model: EM7455
Revision: SWI9X30C_02.38.00.00 rE3F3E55C03AE94 jenkins 2022/01/13 05:16:00
MEID: 01458200686358
IMEI: 014582006863584
IMEI SV: 22
FSN: LF722122230310
+GCAP: +CGSM


OK
cat: /dev/ttyUSB2: No such file or directory
cat: /dev/ttyUSB3: No such file or directory

OK

OK

OK

OK

OK

OK
ATI
Manufacturer: Sierra Wireless, Incorporated
Model: EM7455
Revision: SWI9X30C_02.38.00.00 rE3F3E55C03AE94 jenkins 2022/01/13 05:16:00
MEID: 01458200686358
IMEI: 014582006863584
IMEI SV: 22
FSN: LF722122230310
+GCAP: +CGSM


OK
cat: /dev/ttyUSB2: No such file or directory
cat: /dev/ttyUSB3: No such file or directory

lsusb -v (only relevant device shown)

Bus 003 Device 038: ID 1199:9071 Sierra Wireless, Inc. 
Device Descriptor:
  bLength                18
  bDescriptorType         1
  bcdUSB               2.00
  bDeviceClass            0 
  bDeviceSubClass         0 
  bDeviceProtocol         0 
  bMaxPacketSize0        64
  idVendor           0x1199 Sierra Wireless, Inc.
  idProduct          0x9071 
  bcdDevice            0.06
  iManufacturer           1 Sierra Wireless, Incorporated
  iProduct                2 EM7455
  iSerial                 3 LF72212223031020
  bNumConfigurations      1
  Configuration Descriptor:
    bLength                 9
    bDescriptorType         2
    wTotalLength       0x00a0
    bNumInterfaces          4
    bConfigurationValue     1
    iConfiguration          0 
    bmAttributes         0xa0
      (Bus Powered)
      Remote Wakeup
    MaxPower              500mA
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        0
      bAlternateSetting       0
      bNumEndpoints           2
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass    255 Vendor Specific Subclass
      bInterfaceProtocol    255 Vendor Specific Protocol
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x81  EP 1 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x01  EP 1 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        2
      bAlternateSetting       0
      bNumEndpoints           3
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass      0 
      bInterfaceProtocol      0 
      iInterface              0 
      ** UNRECOGNIZED:  05 24 00 10 01
      ** UNRECOGNIZED:  05 24 01 00 00
      ** UNRECOGNIZED:  04 24 02 02
      ** UNRECOGNIZED:  05 24 06 00 00
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x83  EP 3 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x000a  1x 10 bytes
        bInterval               9
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x82  EP 2 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x02  EP 2 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        3
      bAlternateSetting       0
      bNumEndpoints           3
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass      0 
      bInterfaceProtocol      0 
      iInterface              0 
      ** UNRECOGNIZED:  05 24 00 10 01
      ** UNRECOGNIZED:  05 24 01 00 00
      ** UNRECOGNIZED:  04 24 02 02
      ** UNRECOGNIZED:  05 24 06 00 00
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x85  EP 5 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x000a  1x 10 bytes
        bInterval               9
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x84  EP 4 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x03  EP 3 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
    Interface Descriptor:
      bLength                 9
      bDescriptorType         4
      bInterfaceNumber        8
      bAlternateSetting       0
      bNumEndpoints           3
      bInterfaceClass       255 Vendor Specific Class
      bInterfaceSubClass    255 Vendor Specific Subclass
      bInterfaceProtocol    255 Vendor Specific Protocol
      iInterface              0 
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x87  EP 7 IN
        bmAttributes            3
          Transfer Type            Interrupt
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0008  1x 8 bytes
        bInterval               9
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x86  EP 6 IN
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
      Endpoint Descriptor:
        bLength                 7
        bDescriptorType         5
        bEndpointAddress     0x04  EP 4 OUT
        bmAttributes            2
          Transfer Type            Bulk
          Synch Type               None
          Usage Type               Data
        wMaxPacketSize     0x0200  1x 512 bytes
        bInterval               0
Device Qualifier (for other device speed):
  bLength                10
  bDescriptorType         6
  bcdUSB               2.00
  bDeviceClass            0 
  bDeviceSubClass         0 
  bDeviceProtocol         0 
  bMaxPacketSize0        64
  bNumConfigurations      1
can't get debug descriptor: Resource temporarily unavailable
Device Status:     0x0000
  (Bus Powered)

lsusb (only relevant device shown)

Bus 003 Device 038: ID 1199:9071 Sierra Wireless, Inc.

usb-devices (only relevant device shown)

T:  Bus=03 Lev=02 Prnt=31 Port=03 Cnt=03 Dev#= 38 Spd=480 MxCh= 0
D:  Ver= 2.00 Cls=00(>ifc ) Sub=00 Prot=00 MxPS=64 #Cfgs=  1
P:  Vendor=1199 ProdID=9071 Rev=00.06
S:  Manufacturer=Sierra Wireless, Incorporated
S:  Product=EM7455
S:  SerialNumber=LF72212223031020
C:  #Ifs= 4 Cfg#= 1 Atr=a0 MxPwr=500mA
I:  If#=0x0 Alt= 0 #EPs= 2 Cls=ff(vend.) Sub=ff Prot=ff Driver=qcserial
I:  If#=0x2 Alt= 0 #EPs= 3 Cls=ff(vend.) Sub=00 Prot=00 Driver=qcserial
I:  If#=0x3 Alt= 0 #EPs= 3 Cls=ff(vend.) Sub=00 Prot=00 Driver=qcserial
I:  If#=0x8 Alt= 0 #EPs= 3 Cls=ff(vend.) Sub=ff Prot=ff Driver=qmi_wwan

Status of FCC Unlock

root@SierraFirmwareUpdater:/usr/src/autoflash_sierra_firmware_2024# ls -l /etc/ModemManager/fcc-unlock.d/
total 0
lrwxrwxrwx 1 root root 56 Jan  1 17:37 03f0:4e1d -> /usr/share/ModemManager/fcc-unlock.available.d/03f0:4e1d
lrwxrwxrwx 1 root root 51 Jan  1 17:37 105b -> /usr/share/ModemManager/fcc-unlock.available.d/105b
lrwxrwxrwx 1 root root 56 Jan  1 17:37 105b:e0ab -> /usr/share/ModemManager/fcc-unlock.available.d/105b:e0ab
lrwxrwxrwx 1 root root 51 Jan  1 17:37 1199 -> /usr/share/ModemManager/fcc-unlock.available.d/1199
lrwxrwxrwx 1 root root 56 Jan  1 17:41 1199:9070 -> /usr/share/ModemManager/fcc-unlock.available.d/1199:9070
lrwxrwxrwx 1 root root 56 Jan  1 17:38 1199:9071 -> /usr/share/ModemManager/fcc-unlock.available.d/1199:9071
lrwxrwxrwx 1 root root 56 Jan  1 17:41 1199:9078 -> /usr/share/ModemManager/fcc-unlock.available.d/1199:9078
lrwxrwxrwx 1 root root 56 Jan  1 17:37 1199:9079 -> /usr/share/ModemManager/fcc-unlock.available.d/1199:9079
lrwxrwxrwx 1 root root 51 Jan  1 17:37 1eac -> /usr/share/ModemManager/fcc-unlock.available.d/1eac
lrwxrwxrwx 1 root root 56 Jan  1 17:37 1eac:1001 -> /usr/share/ModemManager/fcc-unlock.available.d/1eac:1001
lrwxrwxrwx 1 root root 56 Jan  1 17:37 413c:81a3 -> /usr/share/ModemManager/fcc-unlock.available.d/413c:81a3
lrwxrwxrwx 1 root root 56 Jan  1 17:37 413c:81a8 -> /usr/share/ModemManager/fcc-unlock.available.d/413c:81a8

And

root@SierraFirmwareUpdater:/usr/src/autoflash_sierra_firmware_2024# ls -l /usr/share/ModemManager/fcc-unlock.available.d/
total 12
lrwxrwxrwx 1 root root   4 Apr  8  2022 03f0:4e1d -> 1199
-rwxr-xr-x 1 root root 783 Apr  8  2022 105b
lrwxrwxrwx 1 root root   4 Apr  8  2022 105b:e0ab -> 105b
-rwxr-xr-x 1 root root 771 Apr  8  2022 1199
lrwxrwxrwx 1 root root   4 Jan  1 17:41 1199:9070 -> 1199
lrwxrwxrwx 1 root root   4 Jan  1 17:41 1199:9071 -> 1199
lrwxrwxrwx 1 root root   4 Jan  1 17:41 1199:9078 -> 1199
lrwxrwxrwx 1 root root   4 Apr  8  2022 1199:9079 -> 1199
-rwxr-xr-x 1 root root 765 Apr  8  2022 1eac
lrwxrwxrwx 1 root root   4 Apr  8  2022 1eac:1001 -> 1eac
lrwxrwxrwx 1 root root   4 Apr  8  2022 413c:81a3 -> 1199
lrwxrwxrwx 1 root root   4 Apr  8  2022 413c:81a8 -> 1199

dmesg (only relevant device shown)

[ 5094.101427] usb 3-6.2: new high-speed USB device number 35 using xhci_hcd
[ 5094.202289] usb 3-6.2: config 1 has an invalid interface number: 8 but max is 3
[ 5094.202299] usb 3-6.2: config 1 has no interface number 1
[ 5094.202838] usb 3-6.2: New USB device found, idVendor=1199, idProduct=9071, bcdDevice= 0.06
[ 5094.202846] usb 3-6.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 5094.202850] usb 3-6.2: Product: EM7455
[ 5094.202853] usb 3-6.2: Manufacturer: Sierra Wireless, Incorporated
[ 5094.202855] usb 3-6.2: SerialNumber: LF72212223031020
[ 5094.211859] qcserial 3-6.2:1.0: Qualcomm USB modem converter detected
[ 5094.211971] usb 3-6.2: Qualcomm USB modem converter now attached to ttyUSB0
[ 5094.212633] qcserial 3-6.2:1.2: Qualcomm USB modem converter detected
[ 5094.212746] usb 3-6.2: Qualcomm USB modem converter now attached to ttyUSB1
[ 5094.214231] qcserial 3-6.2:1.3: Qualcomm USB modem converter detected
[ 5094.214353] usb 3-6.2: Qualcomm USB modem converter now attached to ttyUSB2
[ 5094.215150] qmi_wwan 3-6.2:1.8: cdc-wdm2: USB WDM device
[ 5094.215481] qmi_wwan 3-6.2:1.8 wwan0: register 'qmi_wwan' at usb-0000:00:14.0-6.2, WWAN/QMI device, 0e:26:c5:06:06:e0
[ 5097.457830] EXT4-fs (sdd2): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[ 5427.696325] usb 3-6.2: USB disconnect, device number 35
[ 5427.696483] qcserial ttyUSB0: Qualcomm USB modem converter now disconnected from ttyUSB0
[ 5427.696503] qcserial 3-6.2:1.0: device disconnected
[ 5427.696664] qcserial ttyUSB1: Qualcomm USB modem converter now disconnected from ttyUSB1
[ 5427.696689] qcserial 3-6.2:1.2: device disconnected
[ 5427.696837] qcserial ttyUSB2: Qualcomm USB modem converter now disconnected from ttyUSB2
[ 5427.696851] qcserial 3-6.2:1.3: device disconnected
[ 5427.696980] qmi_wwan 3-6.2:1.8 wwan0: unregister 'qmi_wwan' usb-0000:00:14.0-6.2, WWAN/QMI device
[ 6663.129076] usb 3-6.4: new high-speed USB device number 36 using xhci_hcd
[ 6663.229890] usb 3-6.4: config 1 interface 0 altsetting 0 bulk endpoint 0x1 has invalid maxpacket 64
[ 6663.229903] usb 3-6.4: config 1 interface 0 altsetting 0 bulk endpoint 0x81 has invalid maxpacket 64
[ 6663.230861] usb 3-6.4: New USB device found, idVendor=1199, idProduct=9070, bcdDevice= 0.00
[ 6663.230875] usb 3-6.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 6663.230879] usb 3-6.4: Product: EM7455
[ 6663.230883] usb 3-6.4: Manufacturer: Sierra Wireless, Incorporated
[ 6663.230886] usb 3-6.4: SerialNumber: LF72212223031020
[ 6663.417022] usb 3-6.4: USB disconnect, device number 36
[ 6671.321202] usb 3-6.4: new high-speed USB device number 37 using xhci_hcd
[ 6671.422007] usb 3-6.4: config 1 has an invalid interface number: 8 but max is 3
[ 6671.422013] usb 3-6.4: config 1 has no interface number 1
[ 6671.422562] usb 3-6.4: New USB device found, idVendor=1199, idProduct=9071, bcdDevice= 0.06
[ 6671.422568] usb 3-6.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 6671.422570] usb 3-6.4: Product: EM7455
[ 6671.422571] usb 3-6.4: Manufacturer: Sierra Wireless, Incorporated
[ 6671.422573] usb 3-6.4: SerialNumber: LF72212223031020
[ 6671.429189] qcserial 3-6.4:1.0: Qualcomm USB modem converter detected
[ 6671.429309] usb 3-6.4: Qualcomm USB modem converter now attached to ttyUSB0
[ 6671.430017] qcserial 3-6.4:1.2: Qualcomm USB modem converter detected
[ 6671.430100] usb 3-6.4: Qualcomm USB modem converter now attached to ttyUSB1
[ 6671.430782] qcserial 3-6.4:1.3: Qualcomm USB modem converter detected
[ 6671.430863] usb 3-6.4: Qualcomm USB modem converter now attached to ttyUSB2
[ 6671.431622] qmi_wwan 3-6.4:1.8: cdc-wdm2: USB WDM device
[ 6671.431961] qmi_wwan 3-6.4:1.8 wwan0: register 'qmi_wwan' at usb-0000:00:14.0-6.4, WWAN/QMI device, 0e:26:c5:06:06:e0
[ 6823.929552] usb 3-6.4: USB disconnect, device number 37
[ 6823.929841] qcserial ttyUSB0: Qualcomm USB modem converter now disconnected from ttyUSB0
[ 6823.929875] qcserial 3-6.4:1.0: device disconnected
[ 6823.930106] qcserial ttyUSB1: Qualcomm USB modem converter now disconnected from ttyUSB1
[ 6823.930125] qcserial 3-6.4:1.2: device disconnected
[ 6823.930356] qcserial ttyUSB2: Qualcomm USB modem converter now disconnected from ttyUSB2
[ 6823.930375] qcserial 3-6.4:1.3: device disconnected
[ 6823.930568] qmi_wwan 3-6.4:1.8 wwan0: unregister 'qmi_wwan' usb-0000:00:14.0-6.4, WWAN/QMI device
[ 6833.881746] usb 3-6.4: new high-speed USB device number 38 using xhci_hcd
[ 6833.982689] usb 3-6.4: config 1 has an invalid interface number: 8 but max is 3
[ 6833.982701] usb 3-6.4: config 1 has no interface number 1
[ 6833.983519] usb 3-6.4: New USB device found, idVendor=1199, idProduct=9071, bcdDevice= 0.06
[ 6833.983533] usb 3-6.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 6833.983539] usb 3-6.4: Product: EM7455
[ 6833.983543] usb 3-6.4: Manufacturer: Sierra Wireless, Incorporated
[ 6833.983547] usb 3-6.4: SerialNumber: LF72212223031020
[ 6833.991058] qcserial 3-6.4:1.0: Qualcomm USB modem converter detected
[ 6833.991202] usb 3-6.4: Qualcomm USB modem converter now attached to ttyUSB0
[ 6833.991821] qcserial 3-6.4:1.2: Qualcomm USB modem converter detected
[ 6833.991923] usb 3-6.4: Qualcomm USB modem converter now attached to ttyUSB1
[ 6833.992558] qcserial 3-6.4:1.3: Qualcomm USB modem converter detected
[ 6833.992673] usb 3-6.4: Qualcomm USB modem converter now attached to ttyUSB2
[ 6833.993425] qmi_wwan 3-6.4:1.8: cdc-wdm2: USB WDM device
[ 6833.993759] qmi_wwan 3-6.4:1.8 wwan0: register 'qmi_wwan' at usb-0000:00:14.0-6.4, WWAN/QMI device, 0e:26:c5:06:06:e0

Trying to enable modem

mmcli -v -m 0 -e
[01 Jan 2024, 17:42:43] [Debug] ModemManager process found at ':1.424'
[01 Jan 2024, 17:42:43] [Debug] Assuming '0' is the modem index
[01 Jan 2024, 17:42:43] [Debug] Modem found at '/org/freedesktop/ModemManager1/Modem/0'

[01 Jan 2024, 17:42:43] [Debug] Synchronously enabling modem...
error: couldn't enable the modem: 'GDBus.Error:org.freedesktop.ModemManager1.Error.Core.Retry: Invalid transition'

journalctl -xeu ModemManager

root@SierraFirmwareUpdater:/usr/src/autoflash_sierra_firmware_2024# systemctl status ModemManager
● ModemManager.service - Modem Manager
     Loaded: loaded (/lib/systemd/system/ModemManager.service; enabled; vendor preset: enabled)
     Active: active (running) since Mon 2024-01-01 17:42:06 CET; 31min ago
   Main PID: 18173 (ModemManager)
      Tasks: 5 (limit: 38366)
     Memory: 4.0M
     CGroup: /system.slice/ModemManager.service
             ├─18173 /usr/sbin/ModemManager
             └─18238 /usr/libexec/qmi-proxy

Jan 01 18:11:17 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (enabling -> disabled)
Jan 01 18:11:18 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (disabled -> enabling)
Jan 01 18:11:19 SierraFirmwareUpdater ModemManager[18173]: <warn>  [modem2] couldn't enable interface: 'Invalid transition'
Jan 01 18:11:19 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (enabling -> disabled)
Jan 01 18:11:20 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (disabled -> enabling)
Jan 01 18:11:20 SierraFirmwareUpdater ModemManager[18173]: <warn>  [modem2] couldn't enable interface: 'Invalid transition'
Jan 01 18:11:20 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (enabling -> disabled)
Jan 01 18:11:21 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (disabled -> enabling)
Jan 01 18:11:21 SierraFirmwareUpdater ModemManager[18173]: <warn>  [modem2] couldn't enable interface: 'Invalid transition'
Jan 01 18:11:21 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (enabling -> disabled)
root@SierraFirmwareUpdater:/usr/src/autoflash_sierra_firmware_2024# journalctl -xeu ModemManager
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    wds (1.67)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    dms (1.14)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    nas (1.25)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    qos (1.6)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    wms (1.10)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    auth (1.3)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    at (1.2)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    voice (2.1)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    cat2 (2.24)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    uim (1.45)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    pbm (1.4)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    test (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    loc (2.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    sar (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    ts (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    tmd (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    wda (1.16)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    csvt (1.1)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    coex (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    pdc (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    rfrpe (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    dsd (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    ssctl (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    unknown [0x2e] (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    unknown [0x30] (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    unknown [0x31] (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    unknown [0x36] (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    rms (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    unknown [0xf0] (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    unknown [0xf3] (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    unknown [0xf5] (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    unknown [0xf6] (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2]    unknown [0xfc] (1.0)
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Allocating new client ID...
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Registered 'wda' (version 1.16) client with ID '1'
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Allocating new client ID...
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Releasing 'wda' client with flags 'release-cid'...
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Unregistered 'wda' client with ID '1'
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Registered 'dms' (version 1.14) client with ID '2'
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Allocating new client ID...
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Registered 'nas' (version 1.25) client with ID '3'
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Allocating new client ID...
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Registered 'wds' (version 1.67) client with ID '35'
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Allocating new client ID...
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Registered 'wms' (version 1.10) client with ID '2'
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Allocating new client ID...
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Registered 'uim' (version 1.45) client with ID '2'
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Allocating new client ID...
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Registered 'loc' (version 2.0) client with ID '1'
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Allocating new client ID...
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Registered 'pdc' (version 1.0) client with ID '1'
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Allocating new client ID...
Jan 01 18:11:09 SierraFirmwareUpdater ModemManager[18173]: [/dev/cdc-wdm2] Registered 'voice' (version 2.1) client with ID '2'
Jan 01 18:11:11 SierraFirmwareUpdater ModemManager[18173]: <warn>  [modem2/sim2] couldn't load list of emergency numbers: Failed to parse CRSM query result '+CRSM: 105,129,""'
Jan 01 18:11:11 SierraFirmwareUpdater ModemManager[18173]: <warn>  [modem2] couldn't load list of own numbers: Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned'
Jan 01 18:11:12 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (unknown -> disabled)
Jan 01 18:11:12 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (disabled -> enabling)
Jan 01 18:11:12 SierraFirmwareUpdater ModemManager[18173]: <warn>  [modem2] couldn't enable interface: 'Invalid transition'
Jan 01 18:11:12 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (enabling -> disabled)
Jan 01 18:11:17 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (disabled -> enabling)
Jan 01 18:11:17 SierraFirmwareUpdater ModemManager[18173]: <warn>  [modem2] couldn't enable interface: 'Invalid transition'
Jan 01 18:11:17 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (enabling -> disabled)
Jan 01 18:11:18 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (disabled -> enabling)
Jan 01 18:11:19 SierraFirmwareUpdater ModemManager[18173]: <warn>  [modem2] couldn't enable interface: 'Invalid transition'
Jan 01 18:11:19 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (enabling -> disabled)
Jan 01 18:11:20 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (disabled -> enabling)
Jan 01 18:11:20 SierraFirmwareUpdater ModemManager[18173]: <warn>  [modem2] couldn't enable interface: 'Invalid transition'
Jan 01 18:11:20 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (enabling -> disabled)
Jan 01 18:11:21 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (disabled -> enabling)
Jan 01 18:11:21 SierraFirmwareUpdater ModemManager[18173]: <warn>  [modem2] couldn't enable interface: 'Invalid transition'
Jan 01 18:11:21 SierraFirmwareUpdater ModemManager[18173]: <info>  [modem2] state changed (enabling -> disabled)

More details on journalctl -xeu ModemManager after systemctl restart ModemManager.
modemmanager.log

I tried this (at least the enable modem part) on:

OpenWrt 23.05
Ubuntu 20.04.6 LTS
Ubuntu 23.10

There just seems to be something missing and I cannot figure out what.

Any other idea ?

Could it also be a USB power issue ? 500mA maximum current (if reported correctly) at 5V is only 2.5W. Could this also be the cause of the issue ?

Not sure if this is normal, probably it's NOT:

root@SierraFirmwareUpdater:/usr/src/autoflash_sierra_firmware_2024# sudo mbimcli -p -d /dev/cdc-wdm2 --quectel-query-radio-state
[01 Jan 2024, 18:27:16] -Warning ** [/dev/cdc-wdm2] Couldn't find MBIM signature in descriptors file
error: couldn't open the MbimDevice: Transaction timed out

And this is when I tried some AT commands on OpenWrt.
Seems like the device is stuck in low power mode:


Welcome to minicom 2.8

OPTIONS: 
Port /dev/ttyUSB2, 18:04:18

Press CTRL-A Z for help on special keys


OK
at!gstatus?
!GSTATUS: 
Current Time:  128              Temperature: 20
Reset Counter: 1                Mode:        LOW POWER MODE 


OK

luckylinux · 2024-01-01T20:10:56Z

luckylinux
Jan 1, 2024
Author

Small update and difference after trying an older version of the firmware from 2022:

deviceid=`lsusb | grep -i -E '1199:9071|1199:9079|413C:81B6' | awk '{print $6}'`
echo $deviceid
qmi-firmware-update --update -d "$deviceid" SWI9X30C_02.33.03.00.cwe SWI9X30C_02.33.03.00_GENERIC_002.072_001.nvu

Enable is successful this time, but connection times out:

root@SierraFirmwareUpdater:/usr/src/autoflash_sierra_firmware_2022# mmcli -m 1 -e
successfully enabled the modem
root@SierraFirmwareUpdater:/usr/src/autoflash_sierra_firmware_2022# mmcli -m 1 --simple-connect="apn=internet" --3gpp-register-in-operator=XXXYY
error: couldn't register the modem: 'Timeout was reached'

0 replies

luckylinux · 2024-01-02T14:45:45Z

luckylinux
Jan 2, 2024
Author

For those interested I got it to work. Not very well (bad weather, small antenna, indoor) but approx. 10 Mbps download / 1 Mbps upload.

Solution from my inquiry at SIERRA Wireless Forums https://forum.sierrawireless.com/t/em7455-not-connecting-registering-to-network/31348/16 1

The Solution was to "flash" the APN. Not sure it was stuck on an APN of a different operator (maybe OpenWRT "flashed" the APN into the modem during one of the previous attemps with the other SIM card in QMI/MBIM modem), but it would definitively ignore the correctly specified operator in the "APN" Field.

AT+CGDCONT=1,"IP","internet"

The reboot the modem network stack

AT+CFUN=0
AT+Cfun=1
AT+CGDCONT?

And finally check the status ... Now it's registered

AT!GSTATUS?

0 replies

Navigation Menu

Flash successfull but couldn't enable modem - GDBus.Error:org.freedesktop.ModemManager1.Error.Core.Retry: Invalid transition' #170

Uh oh!

{{title}}

Uh oh!

Uh oh!

{{editor}}'s edit

{{editor}}'s edit

Uh oh!

Replies: 2 comments

Uh oh!

{{title}}

Uh oh!

Uh oh!

{{title}}

Uh oh!

Select a reply

Uh oh!

Flash successfull but couldn't enable modem - GDBus.Error:org.freedesktop.ModemManager1.Error.Core.Retry: Invalid transition' #170

Uh oh!

Uh oh!

luckylinux Jan 1, 2024

Replies: 2 comments

Uh oh!

luckylinux Jan 1, 2024 Author

Uh oh!

luckylinux Jan 2, 2024 Author

luckylinux
Jan 1, 2024

luckylinux
Jan 1, 2024
Author

luckylinux
Jan 2, 2024
Author