nrf9160 SMS gets stuck with 'performing re-registration' while using the SMS to configure the IOT device

[Luke Long]

Hello team,

We have a problem here, we are running a new program with 5 years long-term standby IOT device, once we are trying to use SMS to configure the IOT device we found sometimes SMS gets stuck while nrf9160 was sending message back to end users. Then we retested with the SMS sample with both sdk 2.7.0 & sdk 2.8.99 with the latest mdmfw 1.3.7, we still found similar abnormal. 

For sdk 2.7.0, the first 4x round SMS in/out runs well, then we miss the following 5th and 6th, once we try the 7th round it succeeds and retrieve the lost 5th/6th message at the same time (minor issue), then the SMS sample got stuck with 'performing re-registration' (critical issue). 

*** Booting nRF Connect SDK v2.7.0-5cb85570ca43 ***
*** Using Zephyr OS v3.6.99-100befc70c74 ***

SMS sample starting
[00:00:00.586,914] <inf> nrf_modem_lib_trace: Trace thread ready
[00:00:00.594,421] <inf> nrf_modem_lib_trace: Trace level override: 2
> at AT
> AT+CEREG?
*** Booting nRF Connect SDK v2.7.0-5cb85570ca43 ***
*** Using Zephyr OS v3.6.99-100befc70c74 ***

SMS sample starting
[00:00:00.499,908] <inf> nrf_modem_lib_trace: Trace thread ready
[00:00:00.507,385] <inf> nrf_modem_lib_trace: Trace level override: 2
SMS sample is ready for receiving messages
Sending SMS: number=1064899161827, text="SMS sample: testing"
[00:01:23.593,322] <inf> sms: [ACK] in
[00:01:23.593,597] <inf> sms: [ACK] out
[00:01:23.593,597] <inf> sms: [SMS] in
SMS status report received
[00:01:23.593,658] <inf> sms: [SMS] out
[00:03:10.388,458] <inf> sms: [ACK] in
[00:03:10.388,671] <inf> sms: [ACK] out
[00:03:10.388,702] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-25 17:30:49
        Text:   'edrx,ncs2.7-mfm1.3.7,SMS received,1'
        Length: 35
        From:   1064899161827
record = 0x200181a8
[00:03:10.388,946] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0
[00:03:12.549,804] <inf> sms: [ACK] in
[00:03:12.550,109] <inf> sms: [ACK] out
[00:03:12.550,140] <inf> sms: [SMS] in
SMS status report received
[00:03:12.550,201] <inf> sms: [SMS] out
[00:04:24.806,854] <inf> sms: [ACK] in
[00:04:24.807,128] <inf> sms: [ACK] out
[00:04:24.807,159] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-25 17:31:43
        Text:   'edrx,ncs2.7-mfm1.3.7,SMS received,2'
        Length: 35
        From:   1064899161827
record = 0x200181a8
[00:04:24.807,434] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0
[00:04:26.786,254] <inf> sms: [ACK] in
[00:04:26.786,560] <inf> sms: [ACK] out
[00:04:26.786,590] <inf> sms: [SMS] in
SMS status report received
[00:04:26.786,621] <inf> sms: [SMS] out
[00:05:46.917,541] <inf> sms: [ACK] in
[00:05:46.917,785] <inf> sms: [ACK] out
[00:05:46.917,816] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-25 17:33:04
        Text:   'edrx,ncs2.7-mfm1.3.7,SMS received,3'
        Length: 35
        From:   1064899161827
record = 0x200181a8
[00:05:46.918,060] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0
[00:05:48.551,940] <inf> sms: [ACK] in
[00:05:48.552,215] <inf> sms: [ACK] out
[00:05:48.552,246] <inf> sms: [SMS] in
SMS status report received
[00:05:48.552,276] <inf> sms: [SMS] out
[00:07:08.620,239] <inf> sms: [ACK] in
[00:07:08.620,483] <inf> sms: [ACK] out
[00:07:08.620,483] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-25 17:34:25
        Text:   'edrx,ncs2.7-mfm1.3.7,SMS received,4'
        Length: 35
        From:   1064899161827
record = 0x200181a8
[00:07:08.620,758] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0
[00:07:10.759,582] <inf> sms: [ACK] in
[00:07:10.759,887] <inf> sms: [ACK] out
[00:07:10.759,918] <inf> sms: [SMS] in
SMS status report received
[00:07:10.759,979] <inf> sms: [SMS] out
[00:15:29.848,663] <inf> sms: [ACK] in
[00:15:29.848,907] <inf> sms: [ACK] out
[00:15:29.848,907] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-25 17:43:29
        Text:   'edrx,ncs2.7-mfm1.3.7,SMS received,7'
        Length: 36
        From:   1064899161827
record = 0x200181a8
[00:15:29.849,182] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0
[00:15:31.228,698] <inf> sms: [ACK] in
[00:15:31.228,912] <inf> sms: [ACK] out
[00:15:31.228,942] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-25 17:39:27
        Text:   'edrx,ncs2.7-mfm1.3.7,SMS received,6'
        Length: 35
        From:   1064899161827
record = 0x200181a8
[00:15:31.229,187] <inf> sms: [SMS] out
[00:15:31.872,070] <inf> sms: [ACK] in
[00:15:31.872,314] <inf> sms: [ACK] out
[00:15:31.872,344] <inf> sms: [SMS] in
SMS status report received
[00:15:31.872,375] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8[00:15:32.606,781] <inf> sms: [ACK] in
[00:15:32.606,811] <inf> sms: [ACK] out
[00:15:32.606,811] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-25 17:42:02
        Text:   'edrx,ncs2.7-mfm1.3.7,SMS received,5'
        Length: 35
        From:   1064899161827
record = 0x200181e0[00:15:32.607,116] <inf> sms: [SMS] out
[00:15:33.606,872] <inf> sms: [ACK] in //issue happened
[00:15:33.606,903] <inf> sms: [ACK] out
[00:15:34.607,025] <inf> sms: [ACK] in
[00:15:34.607,086] <inf> sms: [ACK] out
[00:15:35.607,147] <inf> sms: [ACK] in
[00:15:35.607,177] <inf> sms: [ACK] out
[00:15:36.607,299] <inf> sms: [ACK] in
[00:15:36.607,360] <inf> sms: [ACK] out
[00:15:37.607,421] <inf> sms: [ACK] in
[00:15:37.607,452] <inf> sms: [ACK] out
[00:15:38.607,574] <inf> sms: [ACK] in
[00:15:38.607,635] <inf> sms: [ACK] out
[00:15:39.607,696] <inf> sms: [ACK] in
[00:15:39.607,727] <inf> sms: [ACK] out
[00:15:40.607,849] <inf> sms: [ACK] in
[00:15:40.607,910] <inf> sms: [ACK] out
[00:15:41.607,971] <inf> sms: [ACK] in
[00:15:41.608,001] <inf> sms: [ACK] out
[00:15:42.608,123] <inf> sms: [ACK] in
[00:15:42.608,184] <inf> sms: [ACK] out
[00:15:43.608,245] <inf> sms: [ACK] in
[00:15:43.608,276] <inf> sms: [ACK] out
[00:15:44.608,398] <inf> sms: [ACK] in
[00:15:44.608,459] <inf> sms: [ACK] out
[00:15:45.608,520] <inf> sms: [ACK] in
[00:15:45.608,551] <inf> sms: [ACK] out
[00:15:46.608,673] <inf> sms: [ACK] in
[00:15:46.608,734] <inf> sms: [ACK] out
[00:15:47.608,795] <inf> sms: [ACK] in
[00:15:47.608,856] <inf> sms: [ACK] out
[00:15:48.395,355] <inf> sms: [CMS] in
[00:15:48.395,385] <wrn> sms: Modem unregistered the SMS client, performing re-registration //issue happened

For sdk 2.8.99, the first 9x round SMS in/out runs well, then the SMS sample suddently gets stuck with 'performing re-registration'(critical issue). 

*** Booting nRF Connect SDK v2.8.99-14d73e312172 ***
*** Using Zephyr OS v3.7.99-15ffa301b329 ***

SMS sample starting
[00:00:00.573,852] <inf> nrf_modem_lib_trace: Trace thread ready
[00:00:00.581,390] <inf> nrf_modem_lib_trace: Trace level override: 2
SMS sample is ready for receiving messages
Sending SMS: number=1064899161827, text="SMS sample: testing"
SMS status report received

SMS received:
        Time:   25-06-25 17:50:52
        Text:   'edrx,ncs2.8-mfm1.3.7,SMS received,1'
        Length: 35
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-25 17:52:34
        Text:   'edrx,ncs2.8-mfm1.3.7,SMS received,2'
        Length: 35
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-25 17:53:55
        Text:   'edrx,ncs2.8-mfm1.3.7,SMS received,3'
        Length: 35
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-25 17:55:09
        Text:   'edrx,ncs2.8-mfm1.3.7,SMS received,4'
        Length: 35
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-25 18:00:35
        Text:   'edrx,ncs2.8-mfm1.3.7,SMS received,5'
        Length: 35
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-25 18:01:51
        Text:   'edrx,ncs2.8-mfm1.3.7,SMS received,6'
        Length: 35
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-25 18:01:59
        Text:   'edrx,ncs2.8-mfm1.3.7,SMS received,7'
        Length: 35
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-25 18:04:12
        Text:   'edrx,ncs2.8-mfm1.3.7,SMS received,8'
        Length: 36
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0
SMS received:
        Time:   25-06-25 18:10:50
        Text:   'edrx,ncs2.8-mfm1.3.7,SMS received,9'
        Length: 36
        From:   1064899161827
record = 0x20018328[00:22:38.619,628] <wrn> sms: Modem unregistered the SMS client, performing re-registration //issue happened

Here is my setup:

SDK : both sdk 2.7.0 & sdk 2.8.99 

mdmfw: 1.3.7

Hardware: nrf9160DK with China Mobile NBIOT SIM Card

Also attached the modem trace for both. Please help to check what proble is. Thanks

2806.trace-2025-06-25T09-49-01.798Z-sms_test-edrx82_ncs2.8-SMS sample got stuck.mtrace

3463.trace-2025-06-25T09-27-04.940Z-sms_test-edrx82_ncs2.7-SMS sample got stuck.mtrace

[Jun Qing ZOU]

Modem de-register SMS client due to timeout waiting for ACK from APP core. 

Trace of "2806.trace-2025-06-25T09-49-01.798Z-sms_test-edrx82_ncs2.8-SMS sample got stuck" shows that MFW v1.3.5 was used.
Nordic has fixed a concurrent MT/MO SMS problem only seen in CMCC. Fix was included in mfw_nrf9160_1.3.7.

Optionally you could turn off SMS_STATUS_REPORT to reduce ACK by CONFIG_SMS_STATUS_REPORT=n

[Luke Long]

Hi team,

Here retested with mdmfw1.3.7 for both sdk2.7 & sdk2.8, we still see similar phenomena.

SDK : both sdk 2.7.0 & sdk 2.8.99 

mdmfw: 1.3.7

Hardware: nrf9160DK with China Mobile NBIOT SIM Card

For sdk2.8.99, we saw multiple same waring 'performing re-registration' but app was not stuck  and SMS in/out was still functional.

*** Booting nRF Connect SDK v2.8.99-14d73e312172 ***
*** Using Zephyr OS v3.7.99-15ffa301b329 ***

SMS sample starting
[00:00:00.524,444] <inf> nrf_modem_lib_trace: Trace thread ready
[00:00:00.532,012] <inf> nrf_modem_lib_trace: Trace level override: 2
SMS sample is ready for receiving messages
Sending SMS: number=1064899161827, text="SMS sample: testing"
SMS status report received

SMS received:
        Time:   25-06-27 10:59:33
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,1'
        Length: 42
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:01:10
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,2'
        Length: 42
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:02:30
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,3'
        Length: 42
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:07:47
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,4'
        Length: 42
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:09:44
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,5'
        Length: 42
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:10:46
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,6'
        Length: 42
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:12:15
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,7'
        Length: 42
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:13:54
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,8'
        Length: 42
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:15:16
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,9'
        Length: 42
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:16:29
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,10'
        Length: 43
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:18:12
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,11'
        Length: 43
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 11:20:05
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,12'
        Length: 43
        From:   1064899161827
record = 0x200182f0SMS status report received
sms_rsp_handler: record = 0x200182f0
SMS received:
        Time:   25-06-27 11:20:43
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,13'
        Length: 43
        From:   1064899161827
record = 0x20018328[00:23:51.860,504] <wrn> sms: Modem unregistered the SMS client, performing re-registration
send sms [SMS sample: testing] to 1064899161827, err 0
[00:23:52.167,633] <wrn> sms: AT+CNMA=1 response not OK: +CMS ERROR: 300

SMS status report received
sms_rsp_handler: record = 0x20018328send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 11:22:08
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,14'
        Length: 43
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received
SMS status report received

SMS received:
        Time:   25-06-27 11:23:09
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,15'
        Length: 43
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:24:23
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,16'
        Length: 43
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0SMS status report received

SMS received:
        Time:   25-06-27 11:26:44
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,17'
        Length: 43
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 11:26:53
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,18'
        Length: 43
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0SMS status report received
[00:30:23.763,366] <wrn> sms: Modem unregistered the SMS client, performing re-registration
send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 11:27:47
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,19'
        Length: 43
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0SMS status report received
[00:30:41.523,925] <wrn> sms: Modem unregistered the SMS client, performing re-registration
send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 11:28:04
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,20'
        Length: 43
        From:   1064899161827
record = 0x200182f0sms_rsp_handler: record = 0x200182f0
SMS received:
        Time:   25-06-27 11:28:14
        Text:   'SDK2.8.99-mfm1.3.7,edrx81.92,SMS Testing,21'
        Length: 43
        From:   1064899161827
record = 0x20018328SMS status report received
[00:31:00.405,761] <wrn> sms: Modem unregistered the SMS client, performing re-registration

For sdk2.7, we aslo saw multiple same waring 'performing re-registration' but app got stuck agian as below.

*** Booting nRF Connect SDK v2.7.0-5cb85570ca43 ***
*** Using Zephyr OS v3.6.99-100befc70c74 ***

SMS sample starting
[00:00:00.529,449] <inf> nrf_modem_lib_trace: Trace thread ready
[00:00:00.536,987] <inf> nrf_modem_lib_trace: Trace level override: 2
SMS sample is ready for receiving messages
Sending SMS: number=1064899161827, text="SMS sample: testing"
[00:01:26.972,229] <inf> sms: [ACK] in
[00:01:26.972,503] <inf> sms: [ACK] out
[00:01:26.972,534] <inf> sms: [SMS] in
SMS status report received
[00:01:26.972,564] <inf> sms: [SMS] out
[00:02:41.071,350] <inf> sms: [ACK] in
[00:02:41.071,594] <inf> sms: [ACK] out
[00:02:41.071,624] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:38:13
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,1'
        Length: 39
        From:   1064899161827
record = 0x200181a8[00:02:41.071,868] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0[00:02:42.549,682] <inf> sms: [ACK] in
[00:02:42.549,957] <inf> sms: [ACK] out
[00:02:42.549,987] <inf> sms: [SMS] in
SMS status report received
[00:02:42.550,018] <inf> sms: [SMS] out
[00:04:12.515,319] <inf> sms: [ACK] in
[00:04:12.515,594] <inf> sms: [ACK] out
[00:04:12.515,625] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:39:27
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,2'
        Length: 39
        From:   1064899161827
record = 0x200181a8[00:04:12.515,869] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0[00:04:13.987,670] <inf> sms: [ACK] in
[00:04:13.987,945] <inf> sms: [ACK] out
[00:04:13.987,976] <inf> sms: [SMS] in
SMS status report received
[00:04:13.988,037] <inf> sms: [SMS] out
[00:05:24.916,717] <inf> sms: [ACK] in
[00:05:24.916,961] <inf> sms: [ACK] out
[00:05:24.916,992] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:40:27
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,3'
        Length: 39
        From:   1064899161827
record = 0x200181a8[00:05:24.917,236] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8[00:05:26.070,770] <inf> sms: [ACK] in
[00:05:26.070,800] <inf> sms: [ACK] out
[00:05:26.070,800] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:40:39
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,4'
        Length: 39
        From:   1064899161827
record = 0x200181e0[00:05:26.071,105] <inf> sms: [SMS] out
[00:05:27.070,861] <inf> sms: [ACK] in
[00:05:27.070,892] <inf> sms: [ACK] out
[00:05:28.071,014] <inf> sms: [ACK] in
[00:05:28.071,075] <inf> sms: [ACK] out
[00:05:29.071,136] <inf> sms: [ACK] in
[00:05:29.071,166] <inf> sms: [ACK] out
[00:05:30.071,289] <inf> sms: [ACK] in
[00:05:30.071,350] <inf> sms: [ACK] out
[00:05:31.071,411] <inf> sms: [ACK] in
[00:05:31.071,441] <inf> sms: [ACK] out
[00:05:32.071,563] <inf> sms: [ACK] in
[00:05:32.071,624] <inf> sms: [ACK] out
[00:05:33.071,685] <inf> sms: [ACK] in
[00:05:33.071,716] <inf> sms: [ACK] out
[00:05:34.071,838] <inf> sms: [ACK] in
[00:05:34.071,899] <inf> sms: [ACK] out
[00:05:35.071,960] <inf> sms: [ACK] in
[00:05:35.071,990] <inf> sms: [ACK] out
[00:05:36.072,113] <inf> sms: [ACK] in
[00:05:36.072,174] <inf> sms: [ACK] out
[00:05:37.072,235] <inf> sms: [ACK] in
[00:05:37.072,265] <inf> sms: [ACK] out
[00:05:38.072,387] <inf> sms: [ACK] in
[00:05:38.072,448] <inf> sms: [ACK] out
[00:05:39.072,509] <inf> sms: [ACK] in
[00:05:39.072,540] <inf> sms: [ACK] out
[00:05:40.072,662] <inf> sms: [ACK] in
[00:05:40.072,723] <inf> sms: [ACK] out
[00:05:41.072,784] <inf> sms: [ACK] in
[00:05:41.072,845] <inf> sms: [ACK] out
[00:05:41.830,535] <inf> sms: [CMS] in
[00:05:41.830,566] <wrn> sms: Modem unregistered the SMS client, performing re-registration
send sms [SMS sample: testing] to 1064899161827, err 0[00:05:42.072,967] <inf> sms: [ACK] in
[00:05:42.073,211] <inf> sms: [ACK] out
[00:05:42.073,394] <wrn> sms: AT+CNMA=1 response not OK: +CMS ERROR: 300

sms_rsp_handler: record = 0x200181e0[00:05:43.271,118] <inf> sms: [ACK] in
[00:05:43.271,148] <inf> sms: [ACK] out
[00:05:43.271,179] <inf> sms: [SMS] in
SMS status report received
[00:05:43.271,209] <inf> sms: [SMS] out
send sms [SMS sample: testing] to 1064899161827, err 0[00:05:44.271,209] <inf> sms: [ACK] in
[00:05:44.271,453] <inf> sms: [ACK] out
[00:05:49.031,524] <inf> sms: [ACK] in
[00:05:49.031,738] <inf> sms: [ACK] out
[00:05:49.031,768] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:41:24
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,5'
        Length: 39
        From:   1064899161827
record = 0x200181a8[00:05:49.032,012] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0[00:05:55.676,727] <inf> sms: [ACK] in
[00:05:55.676,940] <inf> sms: [ACK] out
[00:05:55.676,971] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:41:31
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,6'
        Length: 39
        From:   1064899161827
record = 0x200181a8[00:05:55.677,215] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0[00:06:17.781,738] <inf> sms: [ACK] in
[00:06:17.782,012] <inf> sms: [ACK] out
[00:06:17.782,043] <inf> sms: [SMS] in
SMS status report received
[00:06:17.782,073] <inf> sms: [SMS] out
[00:06:19.848,815] <inf> sms: [ACK] in
[00:06:19.849,090] <inf> sms: [ACK] out
[00:06:19.849,121] <inf> sms: [SMS] in
SMS status report received
[00:06:19.849,151] <inf> sms: [SMS] out
[00:06:21.957,916] <inf> sms: [ACK] in
[00:06:21.958,160] <inf> sms: [ACK] out
[00:06:21.958,190] <inf> sms: [SMS] in
SMS status report received
[00:06:21.958,251] <inf> sms: [SMS] out
[00:08:23.726,562] <inf> sms: [ACK] in
[00:08:23.726,806] <inf> sms: [ACK] out
[00:08:23.726,806] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:43:11
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,8'
        Length: 39
        From:   1064899161827
record = 0x200181a8[00:08:23.727,081] <inf> sms: [SMS] out
[00:08:24.618,591] <inf> sms: [ACK] in
[00:08:24.618,865] <inf> sms: [ACK] out
[00:08:24.618,896] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:43:18
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,9'
        Length: 39
        From:   1064899161827
record = 0x200181e0[00:08:24.619,140] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0[00:08:25.979,583] <inf> sms: [ACK] in
[00:08:25.979,858] <inf> sms: [ACK] out
[00:08:25.979,888] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:43:39
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,10'
        Length: 40
        From:   1064899161827
record = 0x200181a8[00:08:25.980,163] <inf> sms: [SMS] out
[00:08:26.675,964] <inf> sms: [ACK] in
[00:08:26.676,208] <inf> sms: [ACK] out
[00:08:26.676,239] <inf> sms: [SMS] in
SMS status report received
[00:08:26.676,300] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181e0send sms [SMS sample: testing] to 1064899161827, err 0[00:08:28.218,017] <inf> sms: [ACK] in
[00:08:28.218,292] <inf> sms: [ACK] out
[00:08:28.218,322] <inf> sms: [SMS] in
SMS status report received
[00:08:28.218,353] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8send sms [SMS sample: testing] to 1064899161827, err 0[00:08:29.898,101] <inf> sms: [ACK] in
[00:08:29.898,376] <inf> sms: [ACK] out
[00:08:29.898,406] <inf> sms: [SMS] in
SMS status report received
[00:08:29.898,437] <inf> sms: [SMS] out
[00:09:46.594,268] <inf> sms: [ACK] in
[00:09:46.594,543] <inf> sms: [ACK] out
[00:09:46.594,573] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:44:49
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,11'
        Length: 40
        From:   1064899161827
record = 0x200181a8[00:09:46.594,818] <inf> sms: [SMS] out
sms_rsp_handler: record = 0x200181a8[00:09:47.842,285] <inf> sms: [ACK] in
[00:09:47.842,315] <inf> sms: [ACK] out
[00:09:47.842,346] <inf> sms: [SMS] in

SMS received:
        Time:   25-06-27 11:45:02
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,12'
        Length: 40
        From:   1064899161827
record = 0x200181e0[00:09:47.842,620] <inf> sms: [SMS] out
[00:09:48.842,407] <inf> sms: [ACK] in
[00:09:48.842,437] <inf> sms: [ACK] out
[00:09:49.842,559] <inf> sms: [ACK] in
[00:09:49.842,620] <inf> sms: [ACK] out
[00:09:50.842,681] <inf> sms: [ACK] in
[00:09:50.842,712] <inf> sms: [ACK] out
[00:09:51.842,834] <inf> sms: [ACK] in
[00:09:51.842,895] <inf> sms: [ACK] out
[00:09:52.842,956] <inf> sms: [ACK] in
[00:09:52.842,987] <inf> sms: [ACK] out
[00:09:53.843,109] <inf> sms: [ACK] in
[00:09:53.843,170] <inf> sms: [ACK] out
[00:09:54.843,231] <inf> sms: [ACK] in
[00:09:54.843,261] <inf> sms: [ACK] out
[00:09:55.843,383] <inf> sms: [ACK] in
[00:09:55.843,444] <inf> sms: [ACK] out
[00:09:56.843,505] <inf> sms: [ACK] in
[00:09:56.843,536] <inf> sms: [ACK] out
[00:09:57.843,658] <inf> sms: [ACK] in
[00:09:57.843,719] <inf> sms: [ACK] out
[00:09:58.843,780] <inf> sms: [ACK] in
[00:09:58.843,811] <inf> sms: [ACK] out
[00:09:59.843,933] <inf> sms: [ACK] in
[00:09:59.843,994] <inf> sms: [ACK] out
[00:10:00.844,055] <inf> sms: [ACK] in
[00:10:00.844,085] <inf> sms: [ACK] out
[00:10:01.844,207] <inf> sms: [ACK] in
[00:10:01.844,268] <inf> sms: [ACK] out
[00:10:02.844,329] <inf> sms: [ACK] in
[00:10:02.844,360] <inf> sms: [ACK] out
[00:10:03.844,482] <inf> sms: [ACK] in
[00:10:03.844,543] <inf> sms: [ACK] out
[00:10:04.844,604] <inf> sms: [ACK] in
[00:10:04.844,635] <inf> sms: [ACK] out
[00:10:05.844,757] <inf> sms: [ACK] in
[00:10:05.844,818] <inf> sms: [ACK] out
[00:10:06.844,909] <inf> sms: [ACK] in
[00:10:06.844,940] <inf> sms: [ACK] out
[00:10:07.845,092] <inf> sms: [ACK] in
[00:10:07.845,123] <inf> sms: [ACK] out
send sms [SMS sample: testing] to 1064899161827, err 0[00:10:08.845,184] <inf> sms: [ACK] in
[00:10:08.845,397] <inf> sms: [ACK] out
[00:10:08.845,672] <wrn> sms: AT+CNMA=1 response not OK: +CMS ERROR: 302

sms_rsp_handler: record = 0x200181e0[00:10:09.083,160] <err> sms: Sending AT command failed, err=196909
[00:10:09.083,190] <err> sms: +CMS ERROR: 301
send sms [SMS sample: testing] to 1064899161827, err 196909[00:10:10.305,511] <inf> sms: [CMS] in
[00:10:10.305,541] <wrn> sms: Modem unregistered the SMS client, performing re-registration

Attached both modem trace, please help us to know what is for next step. Thanks

trace-2025-06-27T02-57-38.699Z-SDK2.8-mdm1.3.7-TTL 21 rounds-same warning & NO STUCK.mtrace

trace-2025-06-27T03-35-43.016Z-SDK2.7-mdm1.3.7-TTL 19 rounds-same warning & STUCK.mtrace

[Jun Qing ZOU]

Thank you for the testing. 
In both logs, "TP-SRR: A status report is requested" is set in SMS-SUBMIT, which means
 NCS v2.7.0: this is always set in libsms
 NCS v2.8.0: CONFIG_SMS_STATUS_REPORT default "y", can change to "n" to disable SRR/ACK

In the NG log, the ACK (AT+CNMA=1) got an CMS error and PDU remains not ack-ed, leading to de-register.
TS27.005
300 ME failure
301 SMS service of ME reserved
302 operation not allowed

Could you set CONFIG_SMS_STATUS_REPORT=n in NCS v2.8.0 and test further to see whether the app could be stable enough or not?
BTW the fix in 1.3.7 was for misbehavior from network side. There is no plan to further patch 1.3.7 modem firmware.

[Luke Long]

Thanks for the help.

Here we retested SMS sample again with sdk2.8.99/mdmfw1.3.7/disable SRR and ACK, we still found a similar warning 'Performing re-registration' but APP & SMS in/out are still functional

Comparing SDK2.7 and SDK 2.8, it looks like
1) Re-registration and Err-code 300 happened for both SDK 2.7 and SDK 2.8
2) Err-code 302 only happened for SDK 2.7
3) App stuck only happened for SDK 2.7

Does that make sense for the current design?

By the way, we also want to understand the following:
Q1: Our project is currently built on SDK 2.7+mdmfw1.3.7, does that mean we have to update to SDK2.8 to resolve this issue?
Q2: It sounds like this SMS re-registration issue only happens in the CMCC network, then we should be OK for foreign customers using SDK2.7+mdmfw1.3.7?
Q3: We also see SMS-in wrong-sequence issue sometimes in CMCC, is this only related to CMCC or NBIOT networks? Can we expect better performance for foreign customers or Cat-m1 network?

> at AT
*** Booting nRF Connect SDK v2.8.99-14d73e312172 ***
*** Using Zephyr OS v3.7.99-15ffa301b329 ***

SMS sample starting
[00:00:00.551,727] <inf> nrf_modem_lib_trace: Trace thread ready
[00:00:00.559,295] <inf> nrf_modem_lib_trace: Trace level override: 2
SMS sample is ready for receiving messages
Sending SMS: number=1064899161827, text="SMS sample: testing"

SMS received:
        Time:   25-06-27 15:27:38
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,03'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0
SMS received:
        Time:   25-06-27 11:54:02
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,17'
        Length: 40
        From:   1064899161827
record = 0x20018318[00:10:27.787,384] <wrn> sms: Modem unregistered the SMS client, performing re-registration
send sms [SMS sample: testing] to 1064899161827, err 0[00:10:28.143,463] <wrn> sms: AT+CNMA=1 response not OK: +CMS ERROR: 300


SMS received:
        Time:   25-06-27 11:55:03
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,18'
        Length: 40
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x20018318send sms [SMS sample: testing] to 1064899161827, err 0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 11:55:03
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,18'
        Length: 40
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0
SMS received:
        Time:   25-06-27 11:56:25
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,SMS Testing,19'
        Length: 40
        From:   1064899161827
record = 0x20018318[00:11:06.378,662] <wrn> sms: Modem unregistered the SMS client, performing re-registration
send sms [SMS sample: testing] to 1064899161827, err 0[00:11:07.124,786] <wrn> sms: AT+CNMA=1 response not OK: +CMS ERROR: 300

sms_rsp_handler: record = 0x20018318
SMS received:
        Time:   25-06-27 15:13:15
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,disable SRR/ACK,01'
        Length: 44
        From:   1064899161827
record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:13:15
        Text:   'SDK2.7-mfm1.3.7,edrx81.92,disable SRR/ACK,01'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:19:43
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,01'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0
SMS received:
        Time:   25-06-27 15:26:21
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,02'
        Length: 44
        From:   1064899161827
record = 0x20018318[00:11:45.933,959] <wrn> sms: Modem unregistered the SMS client, performing re-registration
send sms [SMS sample: testing] to 1064899161827, err 0[00:11:46.256,042] <wrn> sms: AT+CNMA=1 response not OK: +CMS ERROR: 300

sms_rsp_handler: record = 0x20018318send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:30:05
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,04'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:32:24
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,05'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:33:14
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,06'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:34:04
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,07'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:34:17
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,08'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:35:18
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,09'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:35:36
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,10'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:36:28
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,11'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:36:42
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,12'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0
SMS received:
        Time:   25-06-27 15:36:54
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,13'
        Length: 44
        From:   1064899161827
record = 0x20018318[00:20:04.670,318] <wrn> sms: Modem unregistered the SMS client, performing re-registration
send sms [SMS sample: testing] to 1064899161827, err 0[00:20:04.832,946] <wrn> sms: AT+CNMA=1 response not OK: +CMS ERROR: 300

sms_rsp_handler: record = 0x20018318send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:38:04
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,14'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0
SMS received:
        Time:   25-06-27 15:38:09
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,15'
        Length: 44
        From:   1064899161827
record = 0x20018318[00:21:23.918,914] <wrn> sms: Modem unregistered the SMS client, performing re-registration
send sms [SMS sample: testing] to 1064899161827, err 0[00:21:24.165,008] <wrn> sms: AT+CNMA=1 response not OK: +CMS ERROR: 300

sms_rsp_handler: record = 0x20018318
SMS received:
        Time:   25-06-27 15:38:15
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,16'
        Length: 44
        From:   1064899161827
record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: testing] to 1064899161827, err 0
SMS received:
        Time:   25-06-27 15:38:15
        Text:   'SDK2.8-mfm1.3.7,edrx81.92,disable SRR/ACK,16'
        Length: 44
        From:   1064899161827
record = 0x200182e0sms_rsp_handler: record = 0x200182e0send sms [SMS sample: sting] to 1064899161827, err 0

trace-2025-06-27T07-17-39.683Z--SDK2.8-mdm1.3.7-disable SRR ACK-TTL 16 rounds-same warning & NO STUCK & Duplicate round16.mtrace

[Jun Qing ZOU]

Thanks for the testing.
Q1: no you can stay with NCS v2.7.0 but please consider backporting libsms to remove SRR
Q2/Q3: previously only saw the SMS issue in CMCC, not reproduced in any of Japanese carriers at least. Please test in your target network anyway

[Luke Long]

Understood, we will follow it up with our customer in local network to see if everything is OK. Thanks for the great help.