https://osmocom.org/
https://osmocom.org/favicon.ico?1664741409
2017-05-05T13:08:24Z
Open Source Mobile Communications
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=3786
2017-05-05T13:08:24Z
neels
nhofmeyr@sysmocom.de
<ul></ul><pre>
root@osmo-gsm-tester-rnd:~# mdbus2 -s org.ofono /sierra_1 org.ofono.MessageManager.SendMessage 1009 "test sms to gobi 3"
('/sierra_1/message_CCD3AC8CFFBDCC9135B1E631BCCA872AAB3B476A',)
</pre>
<pre>
[SIGNAL] org.ofono.MessageManager.MessageAdded /sierra_1 :1.873
('/sierra_1/message_CCD3AC8CFFBDCC9135B1E631BCCA872AAB3B476A', {'State': <'pending'>})
[SIGNAL] org.ofono.Message.PropertyChanged /sierra_1/message_CCD3AC8CFFBDCC9135B1E631BCCA872AAB3B476A :1.873
('State', <'sent'>)
[SIGNAL] org.ofono.MessageManager.MessageRemoved /sierra_1 :1.873
('/sierra_1/message_CCD3AC8CFFBDCC9135B1E631BCCA872AAB3B476A',)
</pre>
<pre>
20170505150616847 DRSL <0004> abis_rsl.c:1867 (bts=0,trx=0,ts=0,ss=1) Activating ARFCN(868) SS(1) lctype SDCCH r=OTHER ra=0x1f ta=0
20170505150616847 DRSL <0004> abis_rsl.c:586 (bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) Tx RSL Channel Activate with act_type=INITIAL
20170505150616847 DRSL <0004> abis_rsl.c:1224 (bts=0,trx=0,ts=0,ss=1) state NONE -> ACTIVATION REQUESTED
20170505150616855 DRSL <0004> abis_rsl.c:1590 (bts=0,trx=0,ts=0,ss=1) CHANNEL ACTIVATE ACK
20170505150616855 DRSL <0004> abis_rsl.c:1224 (bts=0,trx=0,ts=0,ss=1) state ACTIVATION REQUESTED -> ACTIVE
20170505150617203 DMM <0002> gsm_04_08.c:1016 <- CM SERVICE REQUEST serv_type=0x04 MI(TMSI)=3660505949
20170505150617226 DMM <0002> gsm_04_08_utils.c:662 -> CM SERVICE ACK
20170505150618380 DLSMS <0022> gsm_04_11.c:805 receiving data (trans_id=d)
20170505150618380 DLSMS <0022> gsm_04_11.c:820 -> (new transaction)
20170505150618380 DLSMS <0022> gsm0411_smc.c:87 SMC(0) instance created for network
20170505150618380 DLSMS <0022> gsm0411_smr.c:85 SMR(0) instance created for network.
20170505150618381 DLSMS <0022> gsm0411_smc.c:567 SMC(0) message MMSMS-EST-IND (CP DATA) received in state IDLE
20170505150618381 DLSMS <0022> gsm0411_smc.c:284 SMC(0) received CP-DATA
20170505150618381 DLSMS <0022> gsm0411_smc.c:133 SMC(0) new CP state IDLE -> MM_ESTABLISHED
20170505150618381 DLSMS <0022> gsm_04_11.c:143 sending CP message (trans=d)
20170505150618381 DLSMS <0022> gsm_04_11.c:127 GSM4.11 TX d9 04
20170505150618381 DLSMS <0022> gsm_04_11.c:769 MNSMS-DATA/EST-IND
20170505150618381 DLSMS <0022> gsm0411_smr.c:483 SMR(0) message MNSMS-EST-IND received in state IDLE
20170505150618381 DLSMS <0022> gsm0411_smr.c:257 SMR(0) RX SMS RP-DATA
20170505150618381 DLSMS <0022> gsm0411_smr.c:139 SMR(0) new RP state IDLE -> WAIT_TO_TX_RP_ACK
20170505150618381 DLSMS <0022> gsm_04_11.c:686 RX SMS RP-DATA (MO)
20170505150618381 DLSMS <0022> gsm_04_11.c:586 RX_RP-DATA: src_len=0, dst_len=2 ud_len=26
20170505150618381 DLSMS <0022> gsm_04_11.c:554 DST(2,91 f7 )
20170505150618381 DLSMS <0022> gsm_04_11.c:478 RX SMS: Sender: 901700000009031, MTI: 0x01, VPF: 0x02, MR: 0x39 PID: 0x00, DCS: 0x00, DA: 1009, UserDataLength: 0x12, UserData: "test sms to gobi 3"
20170505150618381 DLSMS <0022> sms_queue.c:333 Triggering SMS queue
20170505150618406 DLSMS <0022> sms_queue.c:333 Triggering SMS queue
20170505150618406 DLSMS <0022> gsm_04_11.c:513 TX: SMS RP ACK
20170505150618406 DLSMS <0022> gsm0411_smr.c:416 SMR(0) message SM-RL-REPORT_REQ received in state WAIT_TO_TX_RP_ACK
20170505150618406 DLSMS <0022> gsm0411_smr.c:226 SMR(0) TX SMS REPORT
20170505150618406 DLSMS <0022> gsm0411_smr.c:139 SMR(0) new RP state WAIT_TO_TX_RP_ACK -> IDLE
20170505150618407 DLSMS <0022> gsm0411_smc.c:467 SMC(0) message MNSMS-DATA-REQ received in state MM_ESTABLISHED
20170505150618407 DLSMS <0022> gsm0411_smc.c:180 SMC(0) send CP data
20170505150618407 DLSMS <0022> gsm0411_smc.c:133 SMC(0) new CP state MM_ESTABLISHED -> WAIT_CP_ACK
20170505150618407 DLSMS <0022> gsm_04_11.c:143 sending CP message (trans=d)
20170505150618407 DLSMS <0022> gsm_04_11.c:127 GSM4.11 TX d9 01 02 03 0d
20170505150618407 DLSMS <0022> gsm0411_smr.c:185 SMR(0) TX: MNSMS-REL-REQ
20170505150618407 DLSMS <0022> gsm0411_smc.c:467 SMC(0) message MNSMS-REL-REQ received in state WAIT_CP_ACK
20170505150618407 DLSMS <0022> gsm0411_smc.c:332 SMC(0) cannot release yet current state: WAIT_CP_ACK
20170505150619088 DLSMS <0022> gsm_04_11.c:805 receiving data (trans_id=d)
20170505150619088 DLSMS <0022> gsm0411_smc.c:567 SMC(0) message MMSMS-DATA-IND (CP ACK) received in state WAIT_CP_ACK
20170505150619088 DLSMS <0022> gsm0411_smc.c:256 SMC(0) received CP-ACK
20170505150619088 DLSMS <0022> gsm0411_smc.c:133 SMC(0) new CP state WAIT_CP_ACK -> MM_ESTABLISHED
20170505150619088 DLSMS <0022> gsm0411_smc.c:268 SMC(0) we have pending release.
20170505150619088 DLSMS <0022> gsm0411_smc.c:133 SMC(0) new CP state MM_ESTABLISHED -> IDLE
20170505150619088 DLSMS <0022> gsm_04_11.c:166 Got MMSMS_REL_REQ, destroying transaction.
20170505150619088 DLSMS <0022> gsm0411_smr.c:92 SMR(0) clearing SMR instance
20170505150619088 DLSMS <0022> gsm0411_smc.c:94 SMC(0) clearing instance
20170505150619110 DRSL <0004> abis_rsl.c:1224 (bts=0,trx=0,ts=0,ss=1) state ACTIVE -> RELEASE REQUESTED
20170505150619110 DRR <0003> gsm_04_08_utils.c:251 Sending Channel Release: Chan: Number: 1 Type: 1
20170505150619110 DRSL <0004> abis_rsl.c:768 (bts=0,trx=0,ts=0,ss=1) DEACTivate SACCH CMD
20170505150619116 DRSL <0004> abis_rsl.c:2027 (bts=0,trx=0,ts=0,ss=1) waiting for SAPI=0 to be released.
20170505150619322 DRSL <0004> abis_rsl.c:1718 (bts=0,trx=0,ts=0,ss=1) T3111 expired: releasing RF Channel
20170505150619322 DRSL <0004> abis_rsl.c:860 (bts=0,trx=0,ts=0,ss=1) RF Channel Release
20170505150619327 DRSL <0004> abis_rsl.c:931 (bts=0,trx=0,ts=0,ss=1) RF CHANNEL RELEASE ACK
20170505150619327 DRSL <0004> abis_rsl.c:1224 (bts=0,trx=0,ts=0,ss=1) state RELEASE REQUESTED -> NONE
20170505150619381 DLSMS <0022> sms_queue.c:220 Attempting to send 20 SMS
20170505150619394 DLSMS <0022> sms_queue.c:235 Sending SMS round 1
20170505150619394 DLSMS <0022> gsm_04_11.c:1008 Sending SMS: no connection open, start paging 901700000009032
20170505150619394 DMM <0002> gsm_subscriber.c:190 Subscriber 901700000009032 not paged yet.
20170505150619405 DLSMS <0022> sms_queue.c:235 Sending SMS round 2
20170505150619405 DLSMS <0022> sms_queue.c:251 Sending SMS done (loop) (1 attempted)
20170505150619405 DLSMS <0022> sms_queue.c:286 SMSqueue added 1 messages in 2 rounds
20170505150621125 DRSL <0004> abis_rsl.c:1867 (bts=0,trx=0,ts=0,ss=1) Activating ARFCN(868) SS(1) lctype SDCCH r=OTHER ra=0x10 ta=0
20170505150621125 DRSL <0004> abis_rsl.c:586 (bts=0,trx=0,ts=0,pchan=CCCH+SDCCH4) Tx RSL Channel Activate with act_type=INITIAL
20170505150621125 DRSL <0004> abis_rsl.c:1224 (bts=0,trx=0,ts=0,ss=1) state NONE -> ACTIVATION REQUESTED
20170505150621133 DRSL <0004> abis_rsl.c:1590 (bts=0,trx=0,ts=0,ss=1) CHANNEL ACTIVATE ACK
20170505150621133 DRSL <0004> abis_rsl.c:1224 (bts=0,trx=0,ts=0,ss=1) state ACTIVATION REQUESTED -> ACTIVE
20170505150621440 DRR <0003> gsm_04_08.c:1462 PAGING RESPONSE: MI(TMSI)=737688935
20170505150621440 DRR <0003> gsm_04_08.c:1495 <- Channel was requested by 901700000009032
20170505150621461 DLSMS <0022> gsm_04_11.c:965 paging_cb_send_sms(hooknum=1, event=0, msg=(nil),conn=0xe60c70, sms=0xe8dfc0/id: 16)
20170505150621461 DLSMS <0022> gsm_04_11.c:893 gsm411_send_sms()
20170505150621461 DLSMS <0022> gsm0411_smc.c:87 SMC(16) instance created for network
20170505150621461 DLSMS <0022> gsm0411_smr.c:85 SMR(16) instance created for network.
20170505150621461 DLSMS <0022> gsm_04_11.c:946 TX: SMS DELIVER
20170505150621477 DLSMS <0022> gsm0411_smr.c:416 SMR(16) message SM-RL-DATA_REQ received in state IDLE
20170505150621477 DLSMS <0022> gsm0411_smr.c:215 SMR(16) TX SMS RP-DATA
20170505150621477 DLSMS <0022> gsm0411_smr.c:139 SMR(16) new RP state IDLE -> WAIT_FOR_RP_ACK
20170505150621477 DLSMS <0022> gsm0411_smc.c:467 SMC(16) message MNSMS-EST-REQ received in state IDLE
20170505150621477 DLSMS <0022> gsm0411_smc.c:133 SMC(16) new CP state IDLE -> MM_CONN_PENDING
20170505150621477 DLSMS <0022> gsm0411_smc.c:567 SMC(16) message MMSMS-EST-CNF received in state MM_CONN_PENDING
20170505150621477 DLSMS <0022> gsm0411_smc.c:180 SMC(16) send CP data
20170505150621477 DLSMS <0022> gsm0411_smc.c:133 SMC(16) new CP state MM_CONN_PENDING -> WAIT_CP_ACK
20170505150621477 DLSMS <0022> gsm_04_11.c:143 sending CP message (trans=0)
20170505150621477 DLSMS <0022> gsm_04_11.c:127 GSM4.11 TX 09 01 2b 01 00 07 91 44 77 58 10 06 50 00 1f 00 04 80 01 01 00 00 71 50 50 31 60 12 00 12 f4 f2 9c 0e 9a b7 e7 20 fa 1b 74 7e 8b d3 a0 19
20170505150622852 DLSMS <0022> gsm_04_11.c:805 receiving data (trans_id=0)
20170505150622853 DLSMS <0022> gsm0411_smc.c:567 SMC(16) message MMSMS-DATA-IND (CP ACK) received in state WAIT_CP_ACK
20170505150622853 DLSMS <0022> gsm0411_smc.c:256 SMC(16) received CP-ACK
20170505150622853 DLSMS <0022> gsm0411_smc.c:133 SMC(16) new CP state WAIT_CP_ACK -> MM_ESTABLISHED
20170505150623088 DLSMS <0022> gsm_04_11.c:805 receiving data (trans_id=0)
20170505150623088 DLSMS <0022> gsm0411_smc.c:567 SMC(16) message MMSMS-DATA-IND (CP DATA) received in state MM_ESTABLISHED
20170505150623088 DLSMS <0022> gsm0411_smc.c:284 SMC(16) received CP-DATA
20170505150623088 DLSMS <0022> gsm_04_11.c:143 sending CP message (trans=0)
20170505150623088 DLSMS <0022> gsm_04_11.c:127 GSM4.11 TX 09 04
20170505150623088 DLSMS <0022> gsm_04_11.c:769 MNSMS-DATA/EST-IND
20170505150623088 DLSMS <0022> gsm0411_smr.c:483 SMR(16) message MNSMS-DATA-IND received in state WAIT_FOR_RP_ACK
20170505150623088 DLSMS <0022> gsm0411_smr.c:309 SMR(16) RX SMS RP-ACK
20170505150623088 DLSMS <0022> gsm0411_smr.c:139 SMR(16) new RP state WAIT_FOR_RP_ACK -> IDLE
20170505150623088 DLSMS <0022> gsm_04_11.c:712 RX SMS RP-ACK (MO)
20170505150623114 DLSMS <0022> sms_queue.c:220 Attempting to send 20 SMS
20170505150623127 DLSMS <0022> sms_queue.c:230 Sending SMS done (0 attempted)
20170505150623127 DLSMS <0022> sms_queue.c:286 SMSqueue added 0 messages in 0 rounds
20170505150623127 DLSMS <0022> gsm0411_smr.c:185 SMR(16) TX: MNSMS-REL-REQ
20170505150623127 DLSMS <0022> gsm0411_smc.c:467 SMC(16) message MNSMS-REL-REQ received in state MM_ESTABLISHED
20170505150623127 DLSMS <0022> gsm0411_smc.c:133 SMC(16) new CP state MM_ESTABLISHED -> IDLE
20170505150623127 DLSMS <0022> gsm_04_11.c:166 Got MMSMS_REL_REQ, destroying transaction.
20170505150623127 DLSMS <0022> gsm0411_smr.c:92 SMR(16) clearing SMR instance
20170505150623128 DLSMS <0022> gsm0411_smc.c:94 SMC(16) clearing instance
20170505150623147 DRSL <0004> abis_rsl.c:1224 (bts=0,trx=0,ts=0,ss=1) state ACTIVE -> RELEASE REQUESTED
20170505150623147 DRR <0003> gsm_04_08_utils.c:251 Sending Channel Release: Chan: Number: 1 Type: 1
20170505150623147 DRSL <0004> abis_rsl.c:768 (bts=0,trx=0,ts=0,ss=1) DEACTivate SACCH CMD
20170505150623154 DRSL <0004> abis_rsl.c:2027 (bts=0,trx=0,ts=0,ss=1) waiting for SAPI=0 to be released.
20170505150623323 DRSL <0004> abis_rsl.c:1718 (bts=0,trx=0,ts=0,ss=1) T3111 expired: releasing RF Channel
20170505150623323 DRSL <0004> abis_rsl.c:860 (bts=0,trx=0,ts=0,ss=1) RF Channel Release
20170505150623328 DRSL <0004> abis_rsl.c:931 (bts=0,trx=0,ts=0,ss=1) RF CHANNEL RELEASE ACK
20170505150623328 DRSL <0004> abis_rsl.c:1224 (bts=0,trx=0,ts=0,ss=1) state RELEASE REQUESTED -> NONE
</pre>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=3870
2017-05-14T11:17:25Z
laforge
<ul><li><strong>Assignee</strong> changed from <i>118</i> to <i>lynxis</i></li></ul>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=3946
2017-05-15T13:11:35Z
neels
nhofmeyr@sysmocom.de
<ul><li><strong>Related to</strong> <i><a class="issue tracker-1 status-5 priority-1 priority-lowest closed child" href="/issues/2188">Bug #2188</a>: ofono: Gobi modem refuses to set Online=true</i> added</li></ul>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=3980
2017-05-16T16:23:20Z
lynxis
<ul><li><strong>Status</strong> changed from <i>New</i> to <i>Feedback</i></li></ul><p><a class="user active" href="https://osmocom.org/users/91">neels</a>: can you please try: <a class="external" href="https://code.fe80.eu/lynxis/ofono/tree/lynxis_sms_storage">https://code.fe80.eu/lynxis/ofono/tree/lynxis_sms_storage</a></p>
<p>also sent upstream.</p>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=4707
2017-07-24T23:42:29Z
neels
nhofmeyr@sysmocom.de
<ul><li><strong>Assignee</strong> changed from <i>lynxis</i> to <i>pespin</i></li></ul><ul>
<li>neels redirects request to pespin</li>
</ul>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=4801
2017-08-02T13:29:45Z
pespin
<ul><li><strong>Assignee</strong> changed from <i>pespin</i> to <i>lynxis</i></li></ul><p>lynxis, what's the status of the following branch you proposed? Is it already merged upstream? merged in osmo-gsm-tester sysmocom branch? Please update ofono in the main unit as you see is needed.</p>
<p>Anyway I could not test this due to issue <a class="issue tracker-1 status-5 priority-2 priority-default closed child" title="Bug: ofono: Gobi modem: Refuses to Power on (Closed)" href="https://osmocom.org/issues/2421">#2421</a></p>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=4830
2017-08-08T12:35:59Z
lynxis
<ul></ul><p>There is already a fix upstream and in our repo. It fixes the `Can not receive part` of the problem.<br />But there are still SMS in the ME storage we do not receive atm.</p>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=5239
2017-08-29T15:05:19Z
pespin
<ul></ul><p>Using latest ofono sysmocom:lynxis/master There are still issues. Ofono doesn't send a signal when a message is received in a gobi modem.</p>
<p>I added the print log below to ofono:<br /><pre>
$ git diff
diff --git a/drivers/qmimodem/sms.c b/drivers/qmimodem/sms.c
index 79ea042e..72cba557 100644
--- a/drivers/qmimodem/sms.c
+++ b/drivers/qmimodem/sms.c
@@ -361,6 +361,8 @@ static void event_notify(struct qmi_result *result, void *user_data)
DBG("msg format %d PDU length %d", message->msg_format, plen);
ofono_sms_deliver_notify(sms, message->msg_data, plen, plen);
+ } else {
+ DBG("empty message!");
}
}
</pre></p>
<p>Looking at ofono -n -d log, this is printed when an sms is received:<br /><pre>
ofonod[19751]: drivers/qmimodem/qmibridge.c:ask_qmi() READ: QMI QMUX:
QMI length = 20
QMI flags = 0x80
QMI service = "wms"
QMI client = 4
QMI QMI:
QMI flags = "indication"
QMI transaction = 0
QMI tlv_length = 8
QMI message = "Event Report" (0x0001)
QMI TLV:
QMI type = "MT Message" (0x10)
QMI length = 5
QMI value = 01:03:00:00:00
QMI translated = [ storage_type = 'nv' memory_index = '3' ]
ofonod[19751]: drivers/qmimodem/sms.c:event_notify()
ofonod[19751]: drivers/qmimodem/sms.c:event_notify() storage type 1 index 3
ofonod[19751]: drivers/qmimodem/sms.c:event_notify() empty message!
</pre></p>
<p>Which means following line is failing, because the TLV doesn't seem to be there:<br /><pre>
message = qmi_result_get(result, QMI_WMS_RESULT_MESSAGE, &len);
</pre></p>
<p>As a result, the sms is not notified.<br />Is it possible to read the SMS from other place? (ie internal storage of the modem). We can perhaps get it from there?</p>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=5398
2017-09-12T13:20:26Z
lynxis
<ul><li><strong>Status</strong> changed from <i>Feedback</i> to <i>New</i></li><li><strong>Assignee</strong> changed from <i>lynxis</i> to <i>laforge</i></li></ul><p>Yes, it's possible to implement this case.</p>
<p><a class="user active" href="https://osmocom.org/users/7">laforge</a>: Should I continue to work on this? Because it's only an issue on the gobi2000.</p>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=5400
2017-09-12T14:33:20Z
laforge
<ul></ul><p>On Tue, Sep 12, 2017 at 01:20:26PM +0000, lynxis [REDMINE] wrote:</p>
<blockquote>
<p><a class="user active" href="https://osmocom.org/users/7">laforge</a>: Should I continue to work on this? Because it's only an issue on the gobi2000.</p>
</blockquote>
<p>What's the related effort? If it's >= 4h, then do it. If not, let's leave it for now.<br />Low priority.</p>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=5417
2017-09-15T16:10:54Z
laforge
<ul><li><strong>Assignee</strong> changed from <i>laforge</i> to <i>lynxis</i></li></ul>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=5817
2017-10-16T06:33:23Z
lynxis
<ul><li><strong>Status</strong> changed from <i>New</i> to <i>In Progress</i></li></ul>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=5834
2017-10-16T13:58:40Z
pespin
<ul></ul><p>It seems a patch which may fix the issue was submitted today to ofono ml. Name of the topic: "qmi: support SMS receive on Quectel EC21".</p>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=7035
2018-01-04T11:07:32Z
laforge
<ul><li><strong>Priority</strong> changed from <i>High</i> to <i>Normal</i></li></ul>
OsmoGSMTester - Bug #2238: ofono: Gobi modem: cannot receive SMS
https://osmocom.org/issues/2238?journal_id=17067
2020-01-08T22:42:13Z
laforge
<ul><li><strong>Assignee</strong> deleted (<del><i>lynxis</i></del>)</li><li><strong>Parent task</strong> deleted (<del><i>#2187</i></del>)</li></ul>