Hi, Any clue ? My problem is still here, my modem is on since last week and it still reboot a lot because of those SMS stuck into the queue...
If no clue, I wanted to delete them from the spool directory, but I don't know how to detect them... Thank you, *Hakim BIMAZGANE* *Lead Développeur* Vit*alliance* * Service professionnel d'aide à domicile* 2016-10-11 18:38 GMT+02:00 Hakim Bimazgane <hakim.bimazg...@vitalliance.fr>: > 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* >