Hi, I use a MultiTech MultiConnect Cell 100 (MTC-H5-B03-KIT) as GSM modem. I'm using Kannel version 1.5.0 on a Debian x64 with PlaySMS 1.4.
It works fine mostly now, but I currently got some CMS ERROR 38 on sending SMS (with some concatened), I think it comes because my PDU is giving a false receiver number that can't receive SMS, so it's user error. I've set sms-resend-retry = 1 in the config, I know there was a problem about that with concatened SMS (Kannel ignoring sms-resend-retry: http://www.kannel.org/pipermail/users/2010-January/009862.html), that's why I use the 1.5 that got the patch about that. Here is the log about those retries: 2016-10-11 16:53:45 [6609] [6] WARNING: Maximum retries for message exceeded, discarding it! 2016-10-11 16:53:45 [6609] [6] DEBUG: Set split msg status to 6 2016-10-11 16:53:45 [6629] [6] DEBUG: AT2[multic1]: detecting modem type 2016-10-11 16:53:45 [6629] [6] INFO: AT2[multic1]: opening device 2016-10-11 16:53:45 [6629] [6] DEBUG: AT2[multic1]: device opened. Telnet mode = 0 2016-10-11 16:53:46 [6629] [6] DEBUG: AT2[multic1]: device opened 2016-10-11 16:53:46 [6629] [6] INFO: AT2[multic1]: speed set to 115200 2016-10-11 16:53:46 [6629] [6] DEBUG: AT2[multic1]: --> ATZ^M 2016-10-11 16:53:46 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:46 [6629] [6] DEBUG: AT2[multic1]: --> AT^M 2016-10-11 16:53:47 [6629] [6] DEBUG: AT2[multic1]: <-- AT 2016-10-11 16:53:47 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:47 [6629] [6] DEBUG: AT2[multic1]: --> ^M 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: --> AT^M 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: <-- AT 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: --> AT&F^M 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: <-- AT&F 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: --> ATE0^M 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: <-- ATE0 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: --> ATI^M 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: <-- 332 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: found string <332>, using modem definition <Multitech> 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: --> AT+CSMS=?^M 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: <-- +CSMS: (0,1) 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:49 [6629] [6] INFO: AT2[multic1]: Phase 2+ is supported 2016-10-11 16:53:49 [6629] [6] INFO: AT2[multic1]: Closing device 2016-10-11 16:53:49 [6629] [6] INFO: AT2[multic1]: opening device 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: device opened. Telnet mode = 0 2016-10-11 16:53:49 [6629] [6] DEBUG: AT2[multic1]: device opened 2016-10-11 16:53:49 [6629] [6] INFO: AT2[multic1]: Logging in 2016-10-11 16:53:50 [6629] [6] INFO: AT2[multic1]: init device 2016-10-11 16:53:50 [6629] [6] INFO: AT2[multic1]: speed set to 115200 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: --> ATZ^M 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: --> AT^M 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: <-- AT 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: --> AT&F^M 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: <-- AT&F 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: --> ATE0^M 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: <-- ATE0 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: --> AT+IFC=2,2^M 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: --> AT+CSCA=" +33660003000"^M 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: --> AT+CMGF=0^M 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:50 [6629] [6] DEBUG: AT2[multic1]: --> AT+CSMS=?^M 2016-10-11 16:53:51 [6629] [6] DEBUG: AT2[multic1]: <-- +CSMS: (0,1) 2016-10-11 16:53:51 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:51 [6629] [6] INFO: AT2[multic1]: Phase 2+ is supported 2016-10-11 16:53:51 [6629] [6] DEBUG: AT2[multic1]: --> AT+CSMS=1^M 2016-10-11 16:53:51 [6629] [6] DEBUG: AT2[multic1]: <-- +CSMS: 1,1,1 2016-10-11 16:53:51 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:51 [6629] [6] DEBUG: AT2[multic1]: --> AT+CSQ;+CGDCONT=1,"IP","mmsbouygtel.com";+CSMS=1;#SGACTCFG=1,3,200^M 2016-10-11 16:53:51 [6629] [6] DEBUG: AT2[multic1]: <-- +CSQ: 17,0 2016-10-11 16:53:51 [6629] [6] DEBUG: AT2[multic1]: <-- +CSMS: 1,1,1 2016-10-11 16:53:51 [6629] [6] DEBUG: AT2[multic1]: <-- OK 2016-10-11 16:53:51 [6629] [6] INFO: AT2[multic1]: AT SMSC successfully opened. 2016-10-11 16:53:53 [6629] [6] DEBUG: AT2[multic1]: TP-Validity-Period: 24.0 hours 2016-10-11 16:53:53 [6629] [6] DEBUG: AT2[multic1]: --> AT+CMGS=154^M 2016-10-11 16:53:53 [6629] [6] DEBUG: AT2[multic1]: <-- 2016-10-11 16:53:53 [6629] [6] DEBUG: AT2[multic1]: <-- > 2016-10-11 16:53:53 [6629] [6] DEBUG: AT2[multic1]: send command status: 1 2016-10-11 16:53:53 [6629] [6] DEBUG: AT2[multic1]: --> 0071000B913313420654F60008A78C050003000201005000720065007300 74006100740069006F006E00200075007200670065006E00740065002000 61006E006400720065007300790020006A0075007300710075002700E000 20003100330068002C00200064006900730070006F003F000A0043006F00 72006400690061006C0065006D0065006E0074002C000A005300610072006100680065 2016-10-11 16:53:53 [6629] [6] DEBUG: AT2[multic1]: --> ^Z 2016-10-11 16:53:55 [6629] [6] DEBUG: AT2[multic1]: <-- +CMS ERROR: 38 2016-10-11 16:53:55 [6629] [6] ERROR: AT2[multic1]: +CMS ERROR: +CMS ERROR: 38 2016-10-11 16:53:55 [6629] [6] ERROR: AT2[multic1]: +CMS ERROR: Network out of order (38) 2016-10-11 16:53:55 [6629] [6] DEBUG: AT2[multic1]: send command status: 1 2016-10-11 16:53:57 [6629] [6] DEBUG: AT2[multic1]: TP-Validity-Period: 24.0 hours 2016-10-11 16:53:57 [6629] [6] DEBUG: AT2[multic1]: --> AT+CMGS=68^M 2016-10-11 16:53:57 [6629] [6] DEBUG: AT2[multic1]: <-- > 2016-10-11 16:53:57 [6629] [6] DEBUG: AT2[multic1]: send command status: 1 2016-10-11 16:53:57 [6629] [6] DEBUG: AT2[multic1]: --> 0051000B913313420654F60008A736050003000202002000760069007400 61006C006C00690061006E00630065002E000A0030003100330034003200 340031003800330030 2016-10-11 16:53:57 [6629] [6] DEBUG: AT2[multic1]: --> ^Z 2016-10-11 16:53:59 [6629] [6] DEBUG: AT2[multic1]: <-- > 2016-10-11 16:53:59 [6629] [6] DEBUG: AT2[multic1]: <-- +CMS ERROR: 38 2016-10-11 16:53:59 [6629] [6] ERROR: AT2[multic1]: +CMS ERROR: +CMS ERROR: 38 2016-10-11 16:53:59 [6629] [6] ERROR: AT2[multic1]: +CMS ERROR: Network out of order (38) 2016-10-11 16:53:59 [6629] [6] DEBUG: AT2[multic1]: send command status: 1 2016-10-11 16:54:57 [6629] [6] DEBUG: AT2[multic1]: TP-Validity-Period: 24.0 hours 2016-10-11 16:54:57 [6629] [6] DEBUG: AT2[multic1]: --> AT+CMGS=154^M 2016-10-11 16:54:57 [6629] [6] DEBUG: AT2[multic1]: <-- 2016-10-11 16:54:57 [6629] [6] DEBUG: AT2[multic1]: <-- > 2016-10-11 16:54:57 [6629] [6] DEBUG: AT2[multic1]: send command status: 1 2016-10-11 16:54:57 [6629] [6] DEBUG: AT2[multic1]: --> 0071000B913313420654F60008A78C050003000201005000720065007300 74006100740069006F006E00200075007200670065006E00740065002000 61006E006400720065007300790020006A0075007300710075002700E000 20003100330068002C00200064006900730070006F003F000A0043006F00 72006400690061006C0065006D0065006E0074002C000A005300610072006100680065 2016-10-11 16:54:57 [6629] [6] DEBUG: AT2[multic1]: --> ^Z 2016-10-11 16:54:58 [6629] [6] DEBUG: AT2[multic1]: <-- +CMS ERROR: 38 2016-10-11 16:54:58 [6629] [6] ERROR: AT2[multic1]: +CMS ERROR: +CMS ERROR: 38 2016-10-11 16:54:58 [6629] [6] ERROR: AT2[multic1]: +CMS ERROR: Network out of order (38) 2016-10-11 16:54:58 [6629] [6] DEBUG: AT2[multic1]: send command status: 1 *2016-10-11 16:54:58 [6629] [6] WARNING: Maximum retries for message exceeded, discarding it!* The problem is a that after rebooting the process, it retries the same failing SMS again. It don't really discard the failing SMS, it's an infinite loop of those log lines (2 tries, restart the modem, same SMS that got 2 tries, restart modem, ...). I've read that playSMS don't have resend options, so what's wrong with my system that make SMS comeback in the queue, even when the log says that Kannel discarded it ? Thank you, *Hakim BIMAZGANE* *Lead Développeur* Vit*alliance* * Service professionnel d'aide à domicile*
