Bug 100374

Summary:	telit: error parsing +CSIM responses
Product:	ModemManager	Reporter:	Aleksander Morgado <aleksander>
Component:	plugins	Assignee:	ModemManager bug user <modemmanager>
Status:	RESOLVED FIXED	QA Contact:
Severity:	normal
Priority:	medium	CC:	c.lobrano
Version:	git master
Hardware:	Other
OS:	All
Whiteboard:
i915 platform:		i915 features:

Description Aleksander Morgado 2017-03-24 10:32:07 UTC

The CSIM response parser fails to parse the '+CSIM: 4,"6A88"' string as response, see full logs below.


ModemManager[10335]: <debug> [1490351381.613520] [mm-iface-modem.c:3092] update_lock_info_context_step(): SIM is ready, running after SIM unlock step...
ModemManager[10335]: <debug> [1490351382.487547] [mm-port-serial.c:1220] mm_port_serial_open(): (ttyUSB0) device open count is 2 (open)
ModemManager[10335]: <debug> [1490351382.487651] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT+CSIM=1<CR>'
ModemManager[10335]: <debug> [1490351382.672725] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR>'
ModemManager[10335]: <debug> [1490351382.673757] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<LF>'
ModemManager[10335]: <debug> [1490351382.673848] [mm-port-serial.c:1220] mm_port_serial_open(): (ttyUSB0) device open count is 3 (open)
ModemManager[10335]: <debug> [1490351382.673894] [mm-port-serial.c:1277] _close_internal(): (ttyUSB0) device open count is 2 (close)
ModemManager[10335]: <debug> [1490351382.673936] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT+CSIM=10,0020000100<CR>'
ModemManager[10335]: <debug> [1490351382.887127] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR>'
ModemManager[10335]: <debug> [1490351382.888121] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<LF>'
ModemManager[10335]: <debug> [1490351382.897120] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '+'
ModemManager[10335]: <debug> [1490351382.898085] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- 'CSIM: '
ModemManager[10335]: <debug> [1490351382.899102] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '4,"63'
ModemManager[10335]: <debug> [1490351382.900086] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- 'C3"'
ModemManager[10335]: <debug> [1490351382.907090] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR>'
ModemManager[10335]: <debug> [1490351382.908089] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<LF>'
ModemManager[10335]: <debug> [1490351382.917111] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR>'
ModemManager[10335]: <debug> [1490351382.918084] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<LF>OK<CR><LF>'
ModemManager[10335]: <debug> [1490351382.918197] [telit/mm-broadband-modem-telit.c:610] csim_query_ready(): PIN unlock retries left: 3
ModemManager[10335]: <debug> [1490351382.918240] [mm-port-serial.c:1220] mm_port_serial_open(): (ttyUSB0) device open count is 3 (open)
ModemManager[10335]: <debug> [1490351382.918277] [mm-port-serial.c:1277] _close_internal(): (ttyUSB0) device open count is 2 (close)
ModemManager[10335]: <debug> [1490351382.918312] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT+CSIM=10,002C000100<CR>'
ModemManager[10335]: <debug> [1490351383.152409] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>'
ModemManager[10335]: <debug> [1490351383.162388] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '+C'
ModemManager[10335]: <debug> [1490351383.163405] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- 'SIM: 4'
ModemManager[10335]: <debug> [1490351383.164356] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- ',"63CA'
ModemManager[10335]: <debug> [1490351383.165380] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '"'
ModemManager[10335]: <debug> [1490351383.172367] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>'
ModemManager[10335]: <debug> [1490351383.182319] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>'
ModemManager[10335]: <debug> [1490351383.183351] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- 'OK<CR><LF>'
ModemManager[10335]: <debug> [1490351383.183470] [telit/mm-broadband-modem-telit.c:614] csim_query_ready(): PUK unlock retries left: 10
ModemManager[10335]: <debug> [1490351383.183513] [mm-port-serial.c:1220] mm_port_serial_open(): (ttyUSB0) device open count is 3 (open)
ModemManager[10335]: <debug> [1490351383.183550] [mm-port-serial.c:1277] _close_internal(): (ttyUSB0) device open count is 2 (close)
ModemManager[10335]: <debug> [1490351383.183587] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT+CSIM=10,0020008100<CR>'
ModemManager[10335]: <debug> [1490351383.387450] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>'
ModemManager[10335]: <debug> [1490351383.397452] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '+CSI'
ModemManager[10335]: <debug> [1490351383.398450] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- 'M: 4,"'
ModemManager[10335]: <debug> [1490351383.399471] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '6A88"'
ModemManager[10335]: <debug> [1490351383.407442] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>'
ModemManager[10335]: <debug> [1490351383.417442] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>OK'
ModemManager[10335]: <debug> [1490351383.418417] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>'
ModemManager[10335]: <warn>  [1490351383.418532] [telit/mm-broadband-modem-telit.c:601] csim_query_ready(): Parse error in step 4: Could not parse reponse '+CSIM: 4,"6A88"'.
ModemManager[10335]: <debug> [1490351383.418576] [mm-port-serial.c:1220] mm_port_serial_open(): (ttyUSB0) device open count is 3 (open)
ModemManager[10335]: <debug> [1490351383.418628] [mm-port-serial.c:1277] _close_internal(): (ttyUSB0) device open count is 2 (close)
ModemManager[10335]: <debug> [1490351383.418667] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT+CSIM=10,002C008100<CR>'
ModemManager[10335]: <debug> [1490351383.702859] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>'
ModemManager[10335]: <debug> [1490351383.712854] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '+CSIM'
ModemManager[10335]: <debug> [1490351383.713848] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- ': 4,"6'
ModemManager[10335]: <debug> [1490351383.714849] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- 'A88"'
ModemManager[10335]: <debug> [1490351383.722840] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>'
ModemManager[10335]: <debug> [1490351383.732811] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR>'
ModemManager[10335]: <debug> [1490351383.733780] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<LF>'
ModemManager[10335]: <warn>  [1490351383.733889] [telit/mm-broadband-modem-telit.c:601] csim_query_ready(): Parse error in step 5: Could not parse reponse '+CSIM: 4,"6A88"'.
ModemManager[10335]: <debug> [1490351383.733932] [mm-port-serial.c:1220] mm_port_serial_open(): (ttyUSB0) device open count is 3 (open)
ModemManager[10335]: <debug> [1490351383.733969] [mm-port-serial.c:1277] _close_internal(): (ttyUSB0) device open count is 2 (close)
ModemManager[10335]: <debug> [1490351383.734007] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT+CSIM=0<CR>'
ModemManager[10335]: <debug> [1490351383.807818] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR>'
ModemManager[10335]: <debug> [1490351383.808824] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): <-- '<LF>'

Comment 1 Carlo Lobrano 2017-03-24 13:47:14 UTC

Hi Aleksander,

I've seen this error when SIM is not ready (#QSS: 3 yet to come), but didn't have time to work on it.

Normally, the expected reply is 6C7X, where X is an hexadecimal representing the remaining retries.

I looked better at 3GPP specification, asking some help, and 6A88 is actually an error code representing "Reference data not found", which fit well with the test conditions (again, SIM not ready).

I would propose a patch considering 6A88 as well in csim_parser, returning a better defined error/warning message.

What do you think?

Comment 2 Aleksander Morgado 2017-04-19 08:46:55 UTC

Fixed in git master, 44daf791bbf0c251882f723e9fcc9c89606d69fc

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.