LE910 (used with qmi generic) not working anymore with 1.6-rc2

Thu Apr 7 07:24:55 UTC 2016

Hi all,

LE910 (used with qmi generic) seems not to be working anymore with
1.6-rc2. Last lines of the log are:

ModemManagere[21197]: <debug> [1460013743.234084]
[mm-plugin-manager.c:434] port_context_defer_until_suggested():
[plugin manager] task 2,wwan0: completed, got suggested plugin
(Generic)
ModemManagere[21197]: <debug> [1460013743.234090]
[mm-plugin-manager.c:265] port_context_complete(): [plugin manager]
task 2,wwan0: finished in '1.621374' seconds
ModemManagere[21197]: <debug> [1460013743.234096]
[mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin
manager] task 2,wwan0: best plugin matches device reported one:
Generic
ModemManagere[21197]: <debug> [1460013743.234102]
[mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager]
task 2: still 5 running probes (5 active): ttyUSB1, ttyUSB2, ttyUSB0,
ttyUSB4, ttyUSB3
ModemManagere[21197]: <debug> [1460013744.112978]
[mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed():
[plugin manager] task 2: min probing time elapsed
ModemManagere[21197]: <debug> [1460013744.113019]
[mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager]
task 2: still 5 running probes (5 active): ttyUSB1, ttyUSB2, ttyUSB0,
ttyUSB4, ttyUSB3
ModemManagere[21197]: <debug> [1460013744.230433]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB3): <-- '<CR><LF>+CME
ERROR: 100<CR><LF>'
ModemManagere[21197]: <debug> [1460013744.230539]
[mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure
code 100: Unknown error
ModemManagere[21197]: <debug> [1460013744.230584]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CME
ERROR: 100<CR><LF>'
ModemManagere[21197]: <debug> [1460013744.230594]
[mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure
code 100: Unknown error
ModemManagere[21197]: <debug> [1460013744.230627]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB3): --> 'AT<CR>'
ModemManagere[21197]: <debug> [1460013744.230639]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT<CR>'
ModemManagere[21197]: <debug> [1460013744.432433]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <--
'<CR><LF>OK<CR><LF>'
ModemManagere[21197]: <debug> [1460013744.432588]
[mm-port-probe.c:109] mm_port_probe_set_result_at(): (tty/ttyUSB2)
port is AT-capable
ModemManagere[21197]: <debug> [1460013744.432678]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB3): <--
'<CR><LF>OK<CR><LF>'
ModemManagere[21197]: <debug> [1460013744.432725]
[mm-port-probe.c:109] mm_port_probe_set_result_at(): (tty/ttyUSB3)
port is AT-capable
ModemManagere[21197]: <debug> [1460013744.432808]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGMI<CR>'
ModemManagere[21197]: <debug> [1460013744.432849]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB3): --> 'AT+CGMI<CR>'
ModemManagere[21197]: <debug> [1460013745.137141]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <--
'<CR><LF>Telit<CR><LF><CR><LF>OK<CR><LF>'
ModemManagere[21197]: <debug> [1460013745.137280]
[mm-port-probe.c:136] mm_port_probe_set_result_at_vendor():
(tty/ttyUSB2) vendor probing finished
ModemManagere[21197]: <debug> [1460013745.137347] [mm-plugin.c:479]
apply_post_probing_filters(): (Cinterion) [ttyUSB2] filtered by vendor
strings
ModemManagere[21197]: <debug> [1460013745.137367]
[mm-plugin-manager.c:519] port_context_next(): [plugin manager] task
2,ttyUSB2: checking with plugin 'Generic'
ModemManagere[21197]: <debug> [1460013745.137397] [mm-plugin.c:804]
mm_plugin_supports_port(): (Generic) [ttyUSB2] probe required: 'at,
qcdm'
ModemManagere[21197]: <debug> [1460013745.137413]
[mm-port-probe.c:1348] mm_port_probe_run(): (tty/ttyUSB2) port probing
finished: no more probings needed
ModemManagere[21197]: <debug> [1460013745.137449]
[mm-port-serial.c:1354] _close_internal(): (ttyUSB2) device open count
is 0 (close)
ModemManagere[21197]: <debug> [1460013745.137466]
[mm-port-serial.c:1370] _close_internal(): (ttyUSB2) closing serial
port...
ModemManagere[21197]: <debug> [1460013745.137782]
[mm-port-serial.c:1419] _close_internal(): (ttyUSB2) serial port
closed
ModemManagere[21197]: <debug> [1460013745.137854]
[mm-port-serial.c:1481] port_serial_close_force(): (ttyUSB2) forced to
close port
ModemManagere[21197]: <debug> [1460013745.137909]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB3): <--
'<CR><LF>Telit<CR><LF><CR><LF>OK<CR><LF>'
ModemManagere[21197]: <debug> [1460013745.137963]
[mm-port-probe.c:136] mm_port_probe_set_result_at_vendor():
(tty/ttyUSB3) vendor probing finished
ModemManagere[21197]: <debug> [1460013745.137991] [mm-plugin.c:479]
apply_post_probing_filters(): (Cinterion) [ttyUSB3] filtered by vendor
strings
ModemManagere[21197]: <debug> [1460013745.138008]
[mm-plugin-manager.c:519] port_context_next(): [plugin manager] task
2,ttyUSB3: checking with plugin 'Generic'
ModemManagere[21197]: <debug> [1460013745.138040] [mm-plugin.c:804]
mm_plugin_supports_port(): (Generic) [ttyUSB3] probe required: 'at,
qcdm'
ModemManagere[21197]: <debug> [1460013745.138057]
[mm-port-probe.c:1348] mm_port_probe_run(): (tty/ttyUSB3) port probing
finished: no more probings needed
ModemManagere[21197]: <debug> [1460013745.138087]
[mm-port-serial.c:1354] _close_internal(): (ttyUSB3) device open count
is 0 (close)
ModemManagere[21197]: <debug> [1460013745.138102]
[mm-port-serial.c:1370] _close_internal(): (ttyUSB3) closing serial
port...
ModemManagere[21197]: <debug> [1460013745.138322]
[mm-port-serial.c:1419] _close_internal(): (ttyUSB3) serial port
closed
ModemManagere[21197]: <debug> [1460013745.138376]
[mm-port-serial.c:1481] port_serial_close_force(): (ttyUSB3) forced to
close port
ModemManagere[21197]: <debug> [1460013745.138405]
[mm-plugin-manager.c:283] port_context_supported(): [plugin manager]
task 2,ttyUSB2: found best plugin for port (Generic)
ModemManagere[21197]: <debug> [1460013745.138427]
[mm-plugin-manager.c:265] port_context_complete(): [plugin manager]
task 2,ttyUSB2: finished in '3.516840' seconds
ModemManagere[21197]: <debug> [1460013745.138450]
[mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin
manager] task 2,ttyUSB2: best plugin matches device reported one:
Generic
ModemManagere[21197]: <debug> [1460013745.138472]
[mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager]
task 2: still 4 running probes (4 active): ttyUSB1, ttyUSB0, ttyUSB4,
ttyUSB3
ModemManagere[21197]: <debug> [1460013745.138522]
[mm-plugin-manager.c:283] port_context_supported(): [plugin manager]
task 2,ttyUSB3: found best plugin for port (Generic)
ModemManagere[21197]: <debug> [1460013745.138540]
[mm-plugin-manager.c:265] port_context_complete(): [plugin manager]
task 2,ttyUSB3: finished in '3.520779' seconds
ModemManagere[21197]: <debug> [1460013745.138557]
[mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin
manager] task 2,ttyUSB3: best plugin matches device reported one:
Generic
ModemManagere[21197]: <debug> [1460013745.138573]
[mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager]
task 2: still 3 running probes (3 active): ttyUSB1, ttyUSB0, ttyUSB4
ModemManagere[21197]: <debug> [1460013746.528809]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT<CR>'
ModemManagere[21197]: <debug> [1460013747.045286]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT<CR>'
ModemManagere[21197]: <debug> [1460013747.045399]
[mm-port-serial-at.c:459] debug_log(): (ttyUSB4): --> 'AT<CR>'
ModemManagere[21197]: <debug> [1460013749.436573]
[mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at():
Parsing AT got: 'Sending command failed: 'Resource temporarily
unavailable''
ModemManagere[21197]: <debug> [1460013749.436753]
[mm-plugin-manager.c:519] port_context_next(): [plugin manager] task
2,ttyUSB1: checking with plugin 'Generic'
ModemManagere[21197]: <debug> [1460013749.436817] [mm-plugin.c:804]
mm_plugin_supports_port(): (Generic) [ttyUSB1] probe required: 'at,
qcdm'
**
ERROR:mm-port-probe.c:1319:mm_port_probe_run: assertion failed:
(self->priv->task == NULL)

Trying to understand the issue, it seems that commit

commit  1939c5ace50240127276efacec5c7f166483bb79
port-probe: simplify task completion

has introduced the problem.

Does this make sense?

Thanks,
Daniele