InvalidTransition after flashing firmware
Nathaniel Haggard
natester at gmail.com
Wed Sep 13 18:10:42 UTC 2017
After flashing the firmware to GENERIC (tmobile) on MC7455 I get an error
about InvalidTransition.
Here are the firmware files:
-rwxr-xr-x 1 root root 64432017 May 19 14:38
SWI9X30C_02.24.05.06.cwe
-rwxr-xr-x 1 root root 3889 Jul 13 20:40
SWI9X30C_02.24.05.06_GENERIC_002.026_000.nvu
Here's the error, and I also attached the entire log.
error: couldn't connect the modem:
'GDBus.Error:org.freedesktop.libqmi.Error.Protocol.InvalidTransition:
Couldn't set operating mode: QMI protocol error (60): 'InvalidTransition''
Here is a longer excerpt:
Wed Sep 13 17:59:47 2017 user.notice ModemManager: hotplug: setting
interface 'broadband' as available
Wed Sep 13 17:59:47 2017 daemon.notice netifd: Interface 'broadband' is
setting up now
Wed Sep 13 17:59:47 2017 daemon.notice netifd: broadband (7376): modem
available at /org/freedesktop/ModemManager1/Modem/0
Wed Sep 13 17:59:47 2017 daemon.notice netifd: broadband (7376): imsi is
310260655174033
Wed Sep 13 17:59:47 2017 daemon.notice netifd: broadband (7376): set APN to
fast.t-mobile.com because apn was auto and IMSI was 310260655174033
Wed Sep 13 17:59:47 2017 daemon.notice netifd: broadband (7376): starting
connection with apn=fast.t-mobile.com,pin=1234
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <info> [1505325587.550808]
[mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect
started...
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.550866]
[mm-iface-modem-simple.c:651] connect_auth_ready(): PIN: 1234
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.550904]
[mm-iface-modem-simple.c:653] connect_auth_ready(): Operator ID:
unspecified
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.550940]
[mm-iface-modem-simple.c:655] connect_auth_ready(): Allowed roaming: yes
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.550976]
[mm-iface-modem-simple.c:657] connect_auth_ready(): APN:
fast.t-mobile.com
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.551013]
[mm-iface-modem-simple.c:665] connect_auth_ready(): IP family:
unspecified
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.551047]
[mm-iface-modem-simple.c:673] connect_auth_ready(): Allowed
authentication: unspecified
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.551084]
[mm-iface-modem-simple.c:675] connect_auth_ready(): User: unspecified
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.551121]
[mm-iface-modem-simple.c:677] connect_auth_ready(): Password: unspecified
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.551159]
[mm-iface-modem-simple.c:679] connect_auth_ready(): Number: unspecified
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <info> [1505325587.551195]
[mm-iface-modem-simple.c:461] connection_step(): Simple connect state
(3/8): Enable
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <info> [1505325587.551272]
[mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.551618]
[mm-broadband-modem.c:8821] enabling_started(): Skipping modem
initialization: device hotplugged
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.551666]
[mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial
port...
Wed Sep 13 17:59:47 2017 daemon.warn [4129]: <warn> [1505325587.552355]
[mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not
fully set
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.552451]
[mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count
is 1 (open)
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.552495]
[mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB2):
running init sequence...
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.552569]
[mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial
port...
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.552716]
[mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count
is 1 (open)
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.552760]
[mm-broadband-modem.c:8837] enabling_started(): Flashing primary AT port
before enabling...
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.552833]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.569542]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.569711]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.579978]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.580145]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.599273]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.599444]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.609063]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.609234]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.619646]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.654312]
[mm-broadband-modem-qmi.c:3276] load_power_state(): Getting device
operating mode...
Wed Sep 13 17:59:47 2017 daemon.debug [4129]: [/dev/cdc-wdm0] sent
message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:02:00:14:00:2D:00:00:00
Wed Sep 13 17:59:47 2017 daemon.debug [4129]: [/dev/cdc-wdm0] sent generic
request (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 2
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 20
<<<<<< tlv_length = 0
<<<<<< message = "Get Operating Mode" (0x002D)
Wed Sep 13 17:59:47 2017 daemon.debug [4129]: [/dev/cdc-wdm0] received
message...
<<<<<< RAW:
<<<<<< length = 24
<<<<<< data =
01:17:00:80:02:02:02:14:00:2D:00:0B:00:02:04:00:00:00:00:00:01:01:00:01
Wed Sep 13 17:59:47 2017 daemon.debug [4129]: [/dev/cdc-wdm0] received
generic response (translated)...
<<<<<< QMUX:
<<<<<< length = 23
<<<<<< flags = 0x80
<<<<<< service = "dms"
<<<<<< client = 2
<<<<<< QMI:
<<<<<< flags = "response"
<<<<<< transaction = 20
<<<<<< tlv_length = 11
<<<<<< message = "Get Operating Mode" (0x002D)
<<<<<< TLV:
<<<<<< type = "Result" (0x02)
<<<<<< length = 4
<<<<<< value = 00:00:00:00
<<<<<< translated = SUCCESS
<<<<<< TLV:
<Wed Sep 13 17:59:47 2017 daemon.info [4129]: <debug> [1505325587.692362]
[mm-broadband-modem-qmi.c:3096] set_operating_mode_context_step(): Setting
device operating mode...
Wed Sep 13 17:59:47 2017 daemon.debug [4129]: [/dev/cdc-wdm0] sent
message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:02:02:00:15:00:2E:00:04:00:01:01:00:00
Wed Sep 13 17:59:47 2017 daemon.debug [4129]: [/dev/cdc-wdm0] sent generic
request (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 2
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 21
<<<<<< tlv_length = 4
<<<<<< message = "Set Operating Mode" (0x002E)
<<<<<< TLV:
<<<<<< type = "Mode" (0x01)
<<<<<< length = 1
<<<<<< value = 00
<<<<<< translated = online
Wed Sep 13 17:59:47 2017 daemon.debug [4129]: [/dev/cdc-wdm0] received
message...
<<<<<< RAW:
<<<<<< length = 20
<<<<<< data =
01:13:00:80:02:02:02:15:00:2E:00:07:00:02:04:00:01:00:3C:00
Wed Sep 13 17:59:47 2017 daemon.debug [4129]: [/dev/cdc-wdm0] received
generic response (translated)...
<<<<<< QMUX:
<<<<<< length = 19
<<<<<< flags = 0x80
<<<<<< service = "dms"
<<<<<< client = 2
<<<<<< QMI:
<<<<<< flags = "response"
<<<<<< transaction = 21
<<<<<< tlv_length = 7
<<<<<< message = "Set Operating Mode" (0x002E)
<<<<<< TLV:
<<<<<< type = "Result" (0x02)
<<<<<< length = 4
<<<<<< value = 01:00:3C:00
<<<<<< translated = FAILURE: InvalidTransiWed Sep 13 17:59:47 2017
daemon.info [4129]: <info> [1505325587.756461] [mm-iface-modem.c:1431]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> disabled)
Wed Sep 13 17:59:47 2017 daemon.notice netifd: broadband (7376): error:
couldn't connect the modem:
'GDBus.Error:org.freedesktop.libqmi.Error.Protocol.InvalidTransition:
Couldn't set operating mode: QMI protocol error (60): 'InvalidTransition
Any idea could be wrong? This worked before I flashed the modem several
times.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20170913/16d76519/attachment-0001.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: bug.gz
Type: application/x-gzip
Size: 40767 bytes
Desc: not available
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20170913/16d76519/attachment-0001.bin>
More information about the ModemManager-devel
mailing list