sms sending times out too early?

[Bjørn Mork]

Hello,

I just noticed that SMS sending failures with the MC7710 result in


ModemManager[6067]: <debug> [1387788544.392227] [mm-sms.c:178] generate_3gpp_submit_pdus():   Processing chunk '0' of text with '3' bytes
ModemManager[6067]: <debug> [1387788544.392344] [mm-sms.c:208] generate_3gpp_submit_pdus(): Created SMS part for singlepart SMS
ModemManager[6067]: <debug> [1387788544.392408] [mm-sms-part-3gpp.c:805] mm_sms_part_3gpp_get_submit_pdu(): Creating PDU for part...
ModemManager[6067]: <debug> [1387788544.392458] [mm-sms-part-3gpp.c:892] mm_sms_part_3gpp_get_submit_pdu():   using GSM7 encoding...
ModemManager[6067]: <debug> [1387788544.392519] [mm-sms-part-3gpp.c:957] mm_sms_part_3gpp_get_submit_pdu():   user data length is '3' septets (without UDH)
ModemManager[6067]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 76
<<<<<<   data   = 03:00:00:00:4C:00:00:00:25:00:00:00:01:00:00:00:00:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:03:00:00:00:01:00:00:00:1C:00:00:00:00:00:00:00:08:00:00:00:10:00:00:00:00:01:00:0A:91:74:74:09:00:68:00:00:03:E6:F7:1B
ModemManager[6067]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 76
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 37
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'sms' (533fbeeb-14fe-4467-9f90-33a223e56c3f)
<<<<<<   cid     = 'send' (0x00000003)
<<<<<<   type    = 'set' (0x00000001)

ModemManager[6067]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 48
>>>>>>   data   = 03:00:00:80:30:00:00:00:25:00:00:00:01:00:00:00:00:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:03:00:00:00:02:00:00:00:00:00:00:00
ModemManager[6067]: [/dev/cdc-wdm0] No transaction matched in received message


And with times to show why the received message isn't matched with the
request:


Dec 23 09:49:04 nemi ModemManager[6067]: [/dev/cdc-wdm0] Sent message (translated)...#012<<<<<< Header:#012<<<<<<   length      = 76#012<<<<<<   type        = command (0x00000003)#012<<<<<<   transaction = 37#012<<<<<< Fragment header:#012<<<<<<   total   = 1#012<<<<<<   current = 0#012<<<<<< Contents:#012<<<<<<   service = 'sms' (533fbeeb-14fe-4467-9f90-33a223e56c3f)#012<<<<<<   cid     = 'send' (0x00000003)#012<<<<<<   type    = 'set' (0x00000001)
Dec 23 09:50:35 nemi ModemManager[6067]: [/dev/cdc-wdm0] Received message...#012>>>>>> RAW:#012>>>>>>   length = 48#012>>>>>>   data   = 03:00:00:80:30:00:00:00:25:00:00:00:01:00:00:00:00:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:03:00:00:00:02:00:00:00:00:00:00:00


So the modem tries for 90 seconds before it decides to time out the
transaction.  I suspect that may apply to other transactions which may
time out as well..

Maybe libmbim needs to wait that long as well?


Bjørn