Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

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

Open
luckylinux opened this issue Jan 1, 2024 · 2 comments
Open

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

luckylinux opened this issue Jan 1, 2024 · 2 comments

Comments

@luckylinux
Copy link

luckylinux commented Jan 1, 2024 •
edited
Loading

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
Copy link
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'

@luckylinux
Copy link
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?

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@luckylinux