EM7455 failing to upgrade with new release.

Paul Gildea gildeap at tcd.ie
Mon Oct 7 09:44:00 UTC 2019


Hi, I upgraded to the new release and tested out EM7565/EM7511 firmware
upgrades which worked perfectly. However I'm having an issue with EM7455
failing to upgrade now. Any ideas?
The error received is error: unsupported download protocol. Verbose output
is below.

On an identical system (same hardware, OS, drivers etc) with another EM7455
the upgrade worked. Output also below.

*Working:*

[04 Oct 2019, 15:00:31] [Debug] [qfu-sahara-device] opening TTY:
/dev/ttyUSB6
[04 Oct 2019, 15:00:31] [Debug] [qfu-sahara-device] setting terminal in raw
mode...
[04 Oct 2019, 15:00:31] [Debug] [qfu-sahara-device] waiting time for device
to boot properly...
[04 Oct 2019, 15:00:33] [Debug] [qfu-sahara-device] initializing sahara
protocol...
[04 Oct 2019, 15:00:36] [Debug] [qfu-updater] sahara device creation
failed: no sahara response received
[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] opening TTY: /dev/ttyUSB6
[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] setting terminal in raw
mode...
[04 Oct 2019, 15:00:36] [Debug] [qfu,dload-message] sent sdp:
[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] >> 70:00:00 [3, unframed]

[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] >> 7E:70:00:00:14:46:7E [7]
[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] <<
7E:0D:16:00:00:00:00:88:4D:7E [10]
[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] << 0D:16:00:00:00:00 [6,
unframed]
[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message] received error
[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message]   error:    22
[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message]   errortxt: 0
[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] error (ignored): DLOAD SDP
not supported
[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message] sent hello-req:
[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message]   magic:           QCOM
high speed protocol
[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message]   maximum version: 4
[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message]   minimum version: 4
[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message]   features:        0x30
[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] >>
01:51:43:4F:4D:20:68:69:67:68:20:73:70:65:65:64:20:70:72:6F:74:6F:63:6F:6C:20:68:73:74:00:00:00:00:04:04:30
[36, unframed]
[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] >>
7E:01:51:43:4F:4D:20:68:69:67:68:20:73:70:65:65:64:20:70:72:6F:74:6F:63:6F:6C:20:68:73:74:00:00:00:00:04:04:30:03:0B:7E
[40]
[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] <<
7E:0D:05:00:00:00:00:04:E4:7E [10]
[04 Oct 2019, 15:00:36] [Debug] [qfu-qdl-device] << 0D:05:00:00:00:00 [6,
unframed]
[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message] sent hello-req:

[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message]   magic:           QCOM
high speed protocol
[04 Oct 2019, 15:00:36] [Debug] [qfu,qdl-message]   maximum version: 5
etc


*Broken:*

qmi-firmware-update --update --override-download --verbose -w
/dev/cdc-wdm2 -P /jffs2/flash/SWI9X30C_02.24.05.06.cwe
/jffs2/flash/SWI9X30C_02.24.05.06_VERIZON_002.034_000.nvu
[04 Oct 2019, 15:01:04] [Debug] [qfu-udev] sysfs path for 'cdc-wdm2'
found: /sys/devices/pci0000:00/0000:00:14.0/usb2/2-4
[04 Oct 2019, 15:01:04] [Debug] [qfu-utils] couldn't ping
ModemManager: GDBus.Error:org.freedesktop.DBus.Error.NameHasNoOwner:
Name "org.freedesktop.ModemManager1" does not exist
[04 Oct 2019, 15:01:04] [Debug] [qfu-image] loading file info...
[04 Oct 2019, 15:01:04] [Debug] [qfu-image] opening file for reading...
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe] reading image headers...
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe] image offset range: [0,16951]
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe]   image offset range:
[400,16951]
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe]     image offset
range: [800,16951]
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe]       image offset
range: [1200,16951]
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe]     image at offset
1200 is valid
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe]   image at offset 800 is valid
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe] image at offset 400 is valid
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe] validating data size...
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe] preloading
firmware/config/carrier...
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe]   firmware version: 02.24.05.06
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe]   config version:   002.034_000
[04 Oct 2019, 15:01:04] [Debug] [qfu-image-cwe]   carrier:          VERIZON
[04 Oct 2019, 15:01:04] [Debug] [qfu,device-selection] single cdc-wdm
device requested in sysfs path
'/sys/devices/pci0000:00/0000:00:14.0/usb2/2-4'
[04 Oct 2019, 15:01:04] [Debug] [qfu,device-selection]   device found:
/dev/cdc-wdm2
[04 Oct 2019, 15:01:04] [Debug] [qfu,device-selection]   device found:
/dev/cdc-wdm3
[04 Oct 2019, 15:01:04] [Debug] [qfu,device-selection]   using
preferred device: /dev/cdc-wdm2
loading device information before the update...
[04 Oct 2019, 15:01:04] [Debug] [qfu-updater] creating QMI DMS client...
[04 Oct 2019, 15:01:04] [Debug] [qfu,utils] creating QMI device...
[04 Oct 2019, 15:01:04] [Debug] [qfu,utils] QMI device created
[04 Oct 2019, 15:01:04] [Debug] [qfu,utils] opening QMI device
(without proxy, qmi mode)...
[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] Opening device with
flags 'sync, auto'...
[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] loaded driver of
cdc-wdm port: qmi_wwan
[04 Oct 2019, 15:01:04] -Warning ** [/dev/cdc-wdm2] requested auto
mode but no MBIM QMUX support available
[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] created endpoint
[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] Running sync (10 retries)...
[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 01:0B:00:00:00:00:00:01:27:00:00:00

[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] sent generic request
(translated)...
<<<<<< QMUX:
<<<<<<   length  = 11
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Sync" (0x0027)

[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] received message...
<<<<<< RAW:
<<<<<<   length = 19
<<<<<<   data   = 01:12:00:80:00:00:01:01:27:00:07:00:02:04:00:00:00:00:00

[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] received generic
response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 18
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Sync" (0x0027)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] Sync operation finished
[04 Oct 2019, 15:01:04] [Debug] [qfu,utils] QMI device open
[04 Oct 2019, 15:01:04] [Debug] [qfu,utils] allocating new DMS QMI client...
[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] Assuming service 'dms'
is supported...
[04 Oct 2019, 15:01:04] [Debug] [/dev/cdc-wdm2] Allocating new client ID...
[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:02:22:00:04:00:01:01:00:02

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] sent generic request
(translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   =
01:17:00:80:00:00:01:02:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:02

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] received generic
response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:02
<<<<<<   translated = [ service = 'dms' cid = '2' ]

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] Registered 'dms'
(version unknown) client with ID '2'
[04 Oct 2019, 15:01:08] [Debug] [qfu,utils] DMS QMI client allocated
[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:01:00:23:00:00:00

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] sent generic request
(translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Revision" (0x0023)

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:02:00:49:00:00:00

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] sent generic request
(translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 0
<<<<<<   message     = "List Stored Images" (0x0049)

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:03:00:47:00:00:00

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] sent generic request
(translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Firmware Preference" (0x0047)

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:02:00:04:00:56:55:00:00

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] sent vendor-specific
(0x1199) request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Swi Get Current Firmware" (0x5556)

[04 Oct 2019, 15:01:08] [Debug] [/dev/cdc-wdm2] received message...
<<<<<< RAW:
<<<<<<   length = 180
<<<<<<   data   =
01:B3:00:80:02:02:02:01:00:23:00:A7:00:02:04:00:00:00:00:00:01:3E:00:53:57:49:39:58:33:30:43:5F:30:32:2E:32:34:2E:30:35:2E:30:36:20:72:37:30:34:30:20:43:41:52:4D:44:2D:45:56:2D:46:52:4D:57:52:32:20:32:30:31:37:2F:30:35:2F:31:39:20:30:36:3A:32:33:3A:30:39:10:3E:00:53:57:49:39:58:33:30:43:5F:30:32:2E:32:34:2E:30:35:2E:30:36:20:72:37:30:34:30:20:43:41:52:4D:44:2D:45:56:2D:46:52:4D:57:52:32:20:32:30:31:37:2F:30:35:2F:31:39:20:30:36:3A:32:33:3A:30:39:11:1B:00:39:39:30:37:34:36:39:20:30:30:31:2E:30:30:30:20:47:65:6E:65:72:69:63:2D:4D:32:4D

[04 Oct 2019, 15:01:12] [Debug] [/dev/cdc-wdm2] received generic
response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 179
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 167
<<<<<<   message     = "Get Revision" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Revision" (0x01)
<<<<<<   length     = 62
<<<<<<   value      =
53:57:49:39:58:33:30:43:5F:30:32:2E:32:34:2E:30:35:2E:30:36:20:72:37:30:34:30:20:43:41:52:4D:44:2D:45:56:2D:46:52:4D:57:52:32:20:32:30:31:37:2F:30:35:2F:31:39:20:30:36:3A:32:33:3A:30:39
<<<<<<   translated = SWI9X30C_02.24.05.06 r7040 CARMD-EV-FRMWR2
2017/05/19 06:23:09
<<<<<< TLV:
<<<<<<   type   = 0x10
<<<<<<   length = 62
<<<<<<   value  =
53:57:49:39:58:33:30:43:5F:30:32:2E:32:34:2E:30:35:2E:30:36:20:72:37:30:34:30:20:43:41:52:4D:44:2D:45:56:2D:46:52:4D:57:52:32:20:32:30:31:37:2F:30:35:2F:31:39:20:30:36:3A:32:33:3A:30:39
<<<<<< TLV:
<<<<<<   type   = 0x11
<<<<<<   length = 27
<<<<<<   value  =
39:39:30:37:34:36:39:20:30:30:31:2E:30:30:30:20:47:65:6E:65:72:69:63:2D:4D:32:4D

[04 Oct 2019, 15:01:12] [Debug] [/dev/cdc-wdm2] received message...
<<<<<< RAW:
<<<<<<   length = 283
<<<<<<   data   =
01:1A:01:80:02:02:02:02:00:49:00:0E:01:02:04:00:00:00:00:00:01:04:01:02:00:04:01:02:01:00:3F:5F:3F:00:00:00:00:00:00:00:00:00:00:00:00:00:0D:30:32:2E:32:34:2E:30:35:2E:30:36:5F:3F:02:00:3F:5F:3F:00:00:00:00:00:00:00:00:00:00:00:00:00:0D:30:32:2E:32:34:2E:30:33:2E:30:30:5F:3F:01:32:04:05:FF:FF:30:30:32:2E:30:32:37:5F:30:30:30:00:00:00:00:00:0F:30:32:2E:32:34:2E:30:35:2E:30:36:5F:41:54:54:FF:FF:30:30:32:2E:30:32:36:5F:30:30:30:00:00:00:00:00:13:30:32:2E:32:34:2E:30:35:2E:30:36:5F:47:45:4E:45:52:49:43:FF:FF:30:30:32:2E:30:32:32:5F:30:30:30:00:00:00:00:00:12:30:32:2E:32:34:2E:30:33:2E:30:30:5F:53:50:52:49:4E:54:FF:FF:30:30:31:2E:30:30:30:5F:30:30:30:00:00:00:00:00:15:30:30:2E:30:30:2E:30:30:2E:30:30:5F:4F:45:4D:50:52:49:39:31:38:FF:FF:30:30:32:2E:30:33:34:5F:30:30:30:00:00:00:00:00:13:30:32:2E:32:34:2E:30:35:2E:30:36:5F:56:45:52:49:5A:4F:4E

[04 Oct 2019, 15:01:12] [Debug] [/dev/cdc-wdm2] received generic
response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 282
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 270
<<<<<<   message     = "List Stored Images" (0x0049)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "List" (0x01)
<<<<<<   length     = 260
<<<<<<   value      =
02:00:04:01:02:01:00:3F:5F:3F:00:00:00:00:00:00:00:00:00:00:00:00:00:0D:30:32:2E:32:34:2E:30:35:2E:30:36:5F:3F:02:00:3F:5F:3F:00:00:00:00:00:00:00:00:00:00:00:00:00:0D:30:32:2E:32:34:2E:30:33:2E:30:30:5F:3F:01:32:04:05:FF:FF:30:30:32:2E:30:32:37:5F:30:30:30:00:00:00:00:00:0F:30:32:2E:32:34:2E:30:35:2E:30:36:5F:41:54:54:FF:FF:30:30:32:2E:30:32:36:5F:30:30:30:00:00:00:00:00:13:30:32:2E:32:34:2E:30:35:2E:30:36:5F:47:45:4E:45:52:49:43:FF:FF:30:30:32:2E:30:32:32:5F:30:30:30:00:00:00:00:00:12:30:32:2E:32:34:2E:30:33:2E:30:30:5F:53:50:52:49:4E:54:FF:FF:30:30:31:2E:30:30:30:5F:30:30:30:00:00:00:00:00:15:30:30:2E:30:30:2E:30:30:2E:30:30:5F:4F:45:4D:50:52:49:39:31:38:FF:FF:30:30:32:2E:30:33:34:5F:30:30:30:00:00:00:00:00:13:30:32:2E:32:34:2E:30:35:2E:30:36:5F:56:45:52:49:5A:4F:4E
<<<<<<   translated = { [0] = '[ type = 'modem' maximum_images = '4'
index_of_running_image = '1' sublist = '{ [0] = '[ storage_index = '1'
failure_count = '0' unique_id = '{ [0] = '63 ' [1] = '95 ' [2] = '63 '
[3] = '0 ' [4] = '0 ' [5] = '0 ' [6] = '0 ' [7] = '0 ' [8] = '0 ' [9]
= '0 ' [10] = '0 ' [11] = '0 ' [12] = '0 ' [13] = '0 ' [14] = '0 '
[15] = '0 '}' build_id = '02.24.05.06_?' ] ' [1] = '[ storage_index =
'2' failure_count = '0' unique_id = '{ [0] = '63 ' [1] = '95 ' [2] =
'63 ' [3] = '0 ' [4] = '0 ' [5] = '0 ' [6] = '0 ' [7] = '0 ' [8] = '0
' [9] = '0 ' [10] = '0 ' [11] = '0 ' [12] = '0 ' [13] = '0 ' [14] = '0
' [15] = '0 '}' build_id = '02.24.03.00_?' ] '}' ] ' [1] = '[ type =
'pri' maximum_images = '50' index_of_running_image = '4' sublist = '{
[0] = '[ storage_index = '255' failure_count = '255' unique_id = '{
[0] = '48 ' [1] = '48 ' [2] = '50 ' [3] = '46 ' [4] = '48 ' [5] = '50
' [6] = '55 ' [7] = '95 ' [8] = '48 ' [9] = '48 ' [10] = '48 ' [11] =
'0 ' [12] = '0 ' [13] = '0 ' [14] = '0 ' [15] = '0 '}' build_id =
'02.24.05.06_ATT' ] ' [1] = '[ storage_index = '255' failure_count =
'255' unique_id = '{ [0] = '48 ' [1] = '48 ' [2] = '50 ' [3] = '46 '
[4] = '48 ' [5] = '50 ' [6] = '54 ' [7] = '95 ' [8] = '48 ' [9] = '48
' [10] = '48 ' [11] = '0 ' [12] = '0 ' [13] = '0 ' [14] = '0 ' [15] =
'0 '}' build_id = '02.24.05.06_GENERIC' ] ' [2] = '[ storage_index =
'255' failure_count = '255' unique_id = '{ [0] = '48 ' [1] = '48 ' [2]
= '50 ' [3] = '46 ' [4] = '48 ' [5] = '50 ' [6] = '50 ' [7] = '95 '
[8] = '48 ' [9] = '48 ' [10] = '48 ' [11] = '0 ' [12] = '0 ' [13] = '0
' [14] = '0 ' [15] = '0 '}' build_id = '02.24.03.00_SPRINT' ] ' [3] =
'[ storage_index = '255' failure_count = '255' unique_id = '{ [0] =
'48 ' [1] = '48 ' [2] = '49 ' [3] = '46 ' [4] = '48 ' [5] = '48 ' [6]
= '48 ' [7] = '95 ' [8] = '48 ' [9] = '48 ' [10] = '48 ' [11] = '0 '
[12] = '0 ' [13] = '0 ' [14] = '0 ' [15] = '0 '}' build_id =
'00.00.00.00_OEMPRI918' ] ' [4] = '[ storage_index = '255'
failure_count = '255' unique_id = '{ [0] = '48 ' [1] = '48 ' [2] = '50
' [3] = '46 ' [4] = '48 ' [5] = '51 ' [6] = '52 ' [7] = '95 ' [8] =
'48 ' [9] = '48 ' [10] = '48 ' [11] = '0 ' [12] = '0 ' [13] = '0 '
[14] = '0 ' [15] = '0 '}' build_id = '02.24.05.06_VERIZON' ] '}' ] '}

[04 Oct 2019, 15:01:16] [Debug] [qfu,utils] current revision loaded:
SWI9X30C_02.24.05.06 r7040 CARMD-EV-FRMWR2 2017/05/19 06:23:09
[04 Oct 2019, 15:01:16] [Debug] [/dev/cdc-wdm2] received message...
<<<<<< RAW:
<<<<<<   length = 98
<<<<<<   data   =
01:61:00:80:02:02:02:03:00:47:00:55:00:02:04:00:00:00:00:00:01:4B:00:02:00:30:30:32:2E:30:33:34:5F:30:30:30:00:00:00:00:00:13:30:32:2E:32:34:2E:30:35:2E:30:36:5F:56:45:52:49:5A:4F:4E:01:30:30:32:2E:30:33:34:5F:30:30:30:00:00:00:00:00:13:30:32:2E:32:34:2E:30:35:2E:30:36:5F:56:45:52:49:5A:4F:4E

[04 Oct 2019, 15:01:16] [Debug] [/dev/cdc-wdm2] received generic
response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 97
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 85
<<<<<<   message     = "Get Firmware Preference" (0x0047)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "List" (0x01)
<<<<<<   length     = 75
<<<<<<   value      =
02:00:30:30:32:2E:30:33:34:5F:30:30:30:00:00:00:00:00:13:30:32:2E:32:34:2E:30:35:2E:30:36:5F:56:45:52:49:5A:4F:4E:01:30:30:32:2E:30:33:34:5F:30:30:30:00:00:00:00:00:13:30:32:2E:32:34:2E:30:35:2E:30:36:5F:56:45:52:49:5A:4F:4E
<<<<<<   translated = { [0] = '[ type = 'modem' unique_id = '{ [0] =
'48 ' [1] = '48 ' [2] = '50 ' [3] = '46 ' [4] = '48 ' [5] = '51 ' [6]
= '52 ' [7] = '95 ' [8] = '48 ' [9] = '48 ' [10] = '48 ' [11] = '0 '
[12] = '0 ' [13] = '0 ' [14] = '0 ' [15] = '0 '}' build_id =
'02.24.05.06_VERIZON' ] ' [1] = '[ type = 'pri' unique_id = '{ [0] =
'48 ' [1] = '48 ' [2] = '50 ' [3] = '46 ' [4] = '48 ' [5] = '51 ' [6]
= '52 ' [7] = '95 ' [8] = '48 ' [9] = '48 ' [10] = '48 ' [11] = '0 '
[12] = '0 ' [13] = '0 ' [14] = '0 ' [15] = '0 '}' build_id =
'02.24.05.06_VERIZON' ] '}

[04 Oct 2019, 15:01:20] [Debug] [/dev/cdc-wdm2] received message...
<<<<<< RAW:
<<<<<<   length = 131
<<<<<<   data   =
01:82:00:80:02:02:02:04:00:56:55:76:00:02:04:00:00:00:00:00:18:0C:00:30:30:32:2E:30:33:34:5F:30:30:30:00:17:08:00:56:45:52:49:5A:4F:4E:00:16:08:00:30:30:31:2E:30:30:30:00:15:02:00:35:00:13:08:00:31:31:30:33:35:38:32:00:12:15:00:53:57:49:39:58:33:30:43:5F:30:32:2E:32:34:2E:30:35:2E:30:36:00:11:15:00:53:57:49:39:58:33:30:43:5F:30:32:2E:32:34:2E:30:35:2E:30:36:00:10:07:00:45:4D:37:34:35:35:00

[04 Oct 2019, 15:01:20] [Debug] [/dev/cdc-wdm2] received
vendor-specific (0x1199) response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 130
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 118
<<<<<<   message     = "Swi Get Current Firmware" (0x5556)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Config version" (0x18)
<<<<<<   length     = 12
<<<<<<   value      = 30:30:32:2E:30:33:34:5F:30:30:30:00
<<<<<<   translated = 002.034_000
<<<<<< TLV:
<<<<<<   type       = "Carrier" (0x17)
<<<<<<   length     = 8
<<<<<<   value      = 56:45:52:49:5A:4F:4E:00
<<<<<<   translated = VERIZON
<<<<<< TLV:
<<<<<<   type       = "PRI version" (0x16)
<<<<<<   length     = 8
<<<<<<   value      = 30:30:31:2E:30:30:30:00
<<<<<<   translated = 001.000
<<<<<< TLV:
<<<<<<   type       = "Carrier ID" (0x15)
<<<<<<   length     = 2
<<<<<<   value      = 35:00
<<<<<<   translated = 5
<<<<<< TLV:
<<<<<<   type       = "SKU ID" (0x13)
<<<<<<   length     = 8
<<<<<<   value      = 31:31:30:33:35:38:32:00
<<<<<<   translated = 1103582
<<<<<< TLV:
<<<<<<   type       = "AMSS version" (0x12)
<<<<<<   length     = 21
<<<<<<   value      =
53:57:49:39:58:33:30:43:5F:30:32:2E:32:34:2E:30:35:2E:30:36:00
<<<<<<   translated = SWI9X30C_02.24.05.06
<<<<<< TLV:
<<<<<<   type       = "Boot version" (0x11)
<<<<<<   length     = 21
<<<<<<   value      =
53:57:49:39:58:33:30:43:5F:30:32:2E:32:34:2E:30:35:2E:30:36:00
<<<<<<   translated = SWI9X30C_02.24.05.06
<<<<<< TLV:
<<<<<<   type       = "Model" (0x10)
<<<<<<   length     = 7
<<<<<<   value      = 45:4D:37:34:35:35:00
<<<<<<   translated = EM7455

[04 Oct 2019, 15:01:20] [Debug] [qfu,utils] current firmware preference loaded:
[04 Oct 2019, 15:01:20] [Debug] [qfu,utils] [image 0]
[04 Oct 2019, 15:01:20] [Debug] [qfu,utils]     Image type: 'modem'
[04 Oct 2019, 15:01:20] [Debug] [qfu,utils]     Unique ID:  '002.034_000'
[04 Oct 2019, 15:01:20] [Debug] [qfu,utils]     Build ID:
'02.24.05.06_VERIZON'
[04 Oct 2019, 15:01:20] [Debug] [qfu,utils] [image 1]
[04 Oct 2019, 15:01:20] [Debug] [qfu,utils]     Image type: 'pri'
[04 Oct 2019, 15:01:20] [Debug] [qfu,utils]     Unique ID:  '002.034_000'
[04 Oct 2019, 15:01:20] [Debug] [qfu,utils]     Build ID:
'02.24.05.06_VERIZON'
setting firmware preference:
  firmware version: '02.24.05.06'
  config version:   '002.034_000'
  carrier:          'VERIZON'
[04 Oct 2019, 15:01:24] [Debug] [qfu-updater] setting firmware preference...
[04 Oct 2019, 15:01:24] [Debug] [qfu-updater]   modem image: unique id
'?_?', build id '02.24.05.06_?'
[04 Oct 2019, 15:01:24] [Debug] [qfu-updater]   pri image:   unique id
'002.034_000', build id '02.24.05.06_VERIZON'
[04 Oct 2019, 15:01:24] [Debug] [qfu-updater]   override download: yes
[04 Oct 2019, 15:01:24] [Debug] [/dev/cdc-wdm2] sent message...
<<<<<< RAW:
<<<<<<   length = 89
<<<<<<   data   =
01:58:00:00:02:02:00:05:00:48:00:4C:00:10:01:00:01:01:45:00:02:00:3F:5F:3F:00:00:00:00:00:00:00:00:00:00:00:00:00:0D:30:32:2E:32:34:2E:30:35:2E:30:36:5F:3F:01:30:30:32:2E:30:33:34:5F:30:30:30:00:00:00:00:00:13:30:32:2E:32:34:2E:30:35:2E:30:36:5F:56:45:52:49:5A:4F:4E

[04 Oct 2019, 15:01:24] [Debug] [/dev/cdc-wdm2] sent generic request
(translated)...
<<<<<< QMUX:
<<<<<<   length  = 88
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 76
<<<<<<   message     = "Set Firmware Preference" (0x0048)
<<<<<< TLV:
<<<<<<   type       = "Download Override" (0x10)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = yes
<<<<<< TLV:
<<<<<<   type       = "List" (0x01)
<<<<<<   length     = 69
<<<<<<   value      =
02:00:3F:5F:3F:00:00:00:00:00:00:00:00:00:00:00:00:00:0D:30:32:2E:32:34:2E:30:35:2E:30:36:5F:3F:01:30:30:32:2E:30:33:34:5F:30:30:30:00:00:00:00:00:13:30:32:2E:32:34:2E:30:35:2E:30:36:5F:56:45:52:49:5A:4F:4E
<<<<<<   translated = { [0] = '[ type = 'modem' unique_id = '{ [0] =
'63 ' [1] = '95 ' [2] = '63 ' [3] = '0 ' [4] = '0 ' [5] = '0 ' [6] =
'0 ' [7] = '0 ' [8] = '0 ' [9] = '0 ' [10] = '0 ' [11] = '0 ' [12] =
'0 ' [13] = '0 ' [14] = '0 ' [15] = '0 '}' build_id = '02.24.05.06_?'
] ' [1] = '[ type = 'pri' unique_id = '{ [0] = '48 ' [1] = '48 ' [2] =
'50 ' [3] = '46 ' [4] = '48 ' [5] = '51 ' [6] = '52 ' [7] = '95 ' [8]
= '48 ' [9] = '48 ' [10] = '48 ' [11] = '0 ' [12] = '0 ' [13] = '0 '
[14] = '0 ' [15] = '0 '}' build_id = '02.24.05.06_VERIZON' ] '}

[04 Oct 2019, 15:01:24] [Debug] [/dev/cdc-wdm2] received message...
<<<<<< RAW:
<<<<<<   length = 26
<<<<<<   data   =
01:19:00:80:02:02:02:05:00:48:00:0D:00:02:04:00:00:00:00:00:01:03:00:02:00:01

[04 Oct 2019, 15:01:24] [Debug] [/dev/cdc-wdm2] received generic
response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 25
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 13
<<<<<<   message     = "Set Firmware Preference" (0x0048)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Image Download List" (0x01)
<<<<<<   length     = 3
<<<<<<   value      = 02:00:01
<<<<<<   translated = { [0] = 'modem ' [1] = 'pri '}

[04 Oct 2019, 15:01:24] [Debug] [qfu-updater] need to download the
following images: modem, pri
[04 Oct 2019, 15:01:24] [Debug] [qfu-updater] power cycling...
[04 Oct 2019, 15:01:24] [Debug] [/dev/cdc-wdm2] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:02:02:00:06:00:2E:00:04:00:01:01:00:03

[04 Oct 2019, 15:01:24] [Debug] [/dev/cdc-wdm2] sent generic request
(translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Set Operating Mode" (0x002E)
<<<<<< TLV:
<<<<<<   type       = "Mode" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 03
<<<<<<   translated = offline

[04 Oct 2019, 15:01:28] [Debug] [/dev/cdc-wdm2] received message...
<<<<<< RAW:
<<<<<<   length = 20
<<<<<<   data   = 01:13:00:80:02:02:02:06:00:2E:00:07:00:02:04:00:00:00:00:00

[04 Oct 2019, 15:01:28] [Debug] [/dev/cdc-wdm2] received generic
response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 19
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Set Operating Mode" (0x002E)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[04 Oct 2019, 15:01:28] [Debug] [qfu,utils] operating mode set successfully...
[04 Oct 2019, 15:01:28] [Debug] [/dev/cdc-wdm2] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:02:02:00:07:00:2E:00:04:00:01:01:00:04

[04 Oct 2019, 15:01:28] [Debug] [/dev/cdc-wdm2] sent generic request
(translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Set Operating Mode" (0x002E)
<<<<<< TLV:
<<<<<<   type       = "Mode" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 04
<<<<<<   translated = reset

[04 Oct 2019, 15:01:28] [Debug] [/dev/cdc-wdm2] received message...
<<<<<< RAW:
<<<<<<   length = 20
<<<<<<   data   = 01:13:00:80:02:02:02:07:00:2E:00:07:00:02:04:00:00:00:00:00

[04 Oct 2019, 15:01:28] [Debug] [/dev/cdc-wdm2] received generic
response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 19
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 2
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Set Operating Mode" (0x002E)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS

[04 Oct 2019, 15:01:28] [Debug] [qfu,utils] operating mode set successfully...
[04 Oct 2019, 15:01:28] [Debug] [qfu-updater] reset requested successfully...
[04 Oct 2019, 15:01:28] [Debug] [qfu-updater] cleaning up QMI device...
[04 Oct 2019, 15:01:28] [Debug] [/dev/cdc-wdm2] Releasing 'dms' client
with flags 'none'...
[04 Oct 2019, 15:01:28] [Debug] [/dev/cdc-wdm2] Unregistered 'dms'
client with ID '2'
rebooting in download mode...
[04 Oct 2019, 15:01:28] [Debug] [qfu-updater] reset requested, now
waiting for TTY device...
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove ttyUSB3
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove ttyUSB4
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: unbind 2-4:1.0
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: unbind 2-4:1.2
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove ttyUSB5
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove 2-4:1.0
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove 2-4:1.2
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove wwan2
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: unbind 2-4:1.3
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove 2-4:1.3
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove cdc-wdm2
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: unbind 2-4:1.8
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove 2-4:1.8
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove wwan3
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove cdc-wdm3
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: unbind 2-4:1.10
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove 2-4:1.10
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: unbind 2-4
[04 Oct 2019, 15:01:44] [Debug] [qfu-udev] event: remove 2-4
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] event: add 2-4
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] event: add 2-4:1.0
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] event: add 2-4:1.2
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] event: add 2-4:1.10
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] event: add 2-4:1.3
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] event: add cdc-wdm3
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] event: add 2-4:1.8
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] event: add cdc-wdm2
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] event: add ttyUSB3
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] waiting device (tty) matched: ttyUSB3
[04 Oct 2019, 15:02:21] [Debug] [qfu-updater] TTY device found: /dev/ttyUSB3
download mode detected
[04 Oct 2019, 15:02:21] [Debug] [qfu-udev] event: bind 2-4:1.0
[04 Oct 2019, 15:02:21] [Debug] [qfu-sahara-device] opening TTY: /dev/ttyUSB3
[04 Oct 2019, 15:02:21] [Debug] [qfu-sahara-device] setting terminal
in raw mode...
[04 Oct 2019, 15:02:21] [Debug] [qfu-sahara-device] waiting time for
device to boot properly...
[04 Oct 2019, 15:02:23] [Debug] [qfu-sahara-device] initializing
sahara protocol...
[04 Oct 2019, 15:02:26] [Debug] [qfu-updater] sahara device creation
failed: no sahara response received
[04 Oct 2019, 15:02:26] [Debug] [qfu-qdl-device] opening TTY: /dev/ttyUSB3
[04 Oct 2019, 15:02:26] [Debug] [qfu-qdl-device] setting terminal in raw mode...
[04 Oct 2019, 15:02:26] [Debug] [qfu,dload-message] sent sdp:
[04 Oct 2019, 15:02:26] [Debug] [qfu-qdl-device] >> 70:00:00 [3, unframed]
[04 Oct 2019, 15:02:26] [Debug] [qfu-qdl-device] >> 7E:70:00:00:14:46:7E [7]
[04 Oct 2019, 15:02:26] [Debug] [qfu-qdl-device] << 13:70:00:00:6A:9A:7E [7]
[04 Oct 2019, 15:02:26] [Debug] [qfu-qdl-device] << 13:70:00:00 [4, unframed]
[04 Oct 2019, 15:02:26] [Debug] [qfu-updater] qdl device creation
failed: unexpected response received in dload sdp: 0x13
error: unsupported download protocol
[04 Oct 2019, 15:02:26] [Debug] [qfu-udev] event: add ttyUSB4
[04 Oct 2019, 15:02:26] [Debug] [qfu-udev] event: bind 2-4:1.2
[04 Oct 2019, 15:02:26] [Debug] [qfu-udev] event: add ttyUSB5
[04 Oct 2019, 15:02:26] [Debug] [qfu-udev] event: bind 2-4:1.3
[04 Oct 2019, 15:02:26] [Debug] [qfu-udev] event: add wwan2
[04 Oct 2019, 15:02:26] [Debug] [qfu-udev] event: bind 2-4:1.8
[04 Oct 2019, 15:02:26] [Debug] [qfu-udev] event: add wwan3
[04 Oct 2019, 15:02:26] [Debug] [qfu-udev] event: bind 2-4:1.10
[04 Oct 2019, 15:02:26] [Debug] [qfu-udev] event: bind 2-4
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/libqmi-devel/attachments/20191007/55c1b9a8/attachment-0001.html>


More information about the libqmi-devel mailing list