Hi Alvaro, OK I made the change last night and now we have some more descriptive errors.
2011-08-03 09:40:15 [12728] [39] DEBUG: AT2[wavecom_74]: TP-Validity-Period: 24.0 hours 2011-08-03 09:40:15 [12728] [39] DEBUG: AT2[wavecom_74]: --> AT+CMGS=152^M 2011-08-03 09:40:16 [12728] [39] DEBUG: AT2[wavecom_74]: <-- > 2011-08-03 09:40:16 [12728] [39] DEBUG: AT2[wavecom_74]: send command status: 1 2011-08-03 09:40:16 [12728] [39] DEBUG: AT2[wavecom_74]: --> 0031000B917227442266F70000A79D46F9BB0D0ACF41EC349DCE2E83C2739034E6C2C140F0B21C440EE75DA0ECBB0E1A87DDA073990EAAC 341F437485A838160301808F99EC3D3F4301B340CCFD1206178BC0699E5EF3668C84E97DDF432BB0C62A6CD6517485A86B3F320E4730A4D82C26E32485C068DC36C76990C7ACB4153EA130AA2BF416F381DF4AED35D2 0699D5D9E838270383BEF02 2011-08-03 09:40:16 [12728] [39] DEBUG: AT2[wavecom_74]: --> ^Z 2011-08-03 09:40:29 [12728] [39] DEBUG: AT2[wavecom_74]: <-- > 2011-08-03 09:40:29 [12728] [39] DEBUG: AT2[wavecom_74]: <-- +CMS ERROR: 513 2011-08-03 09:40:29 [12728] [39] ERROR: AT2[wavecom_74]: +CMS ERROR: +CMS ERROR: 513 2011-08-03 09:40:29 [12728] [39] ERROR: AT2[wavecom_74]: +CMS ERROR: Lower layer falure (SMS) (513) 2011-08-03 09:40:29 [12728] [39] DEBUG: AT2[wavecom_74]: send command status: 1 Does anyone have any idea how to solve this ? Thanks, Donald On 02 Aug 2011, at 4:29 PM, Alvaro Cornejo wrote: > Hi > > Looks ok. Lets wait to see the logs later on. > > Also, I don't see the CNMI init string in your config file. So I asume > it comes from the default smsc-at init. > > Regards > > Alvaro > > > > |-----------------------------------------------------------------------------------------------------------------| > Envíe y Reciba Datos y mensajes de Texto (SMS) hacia y desde cualquier > celular y Nextel > en el Perú, México y en mas de 180 paises. Use aplicaciones 2 vias via > SMS y GPRS online > Visitenos en www.perusms.NET www.smsglobal.com.mx y > www.pravcom.com > > > > On Tue, Aug 2, 2011 at 9:04 AM, Donald Jackson <djack...@kannel.org> wrote: >> Hi Alvaro, >> >> I'll add the verbose strings this evening, here is the init sequence >> currently. >> >> 2011-08-02 09:58:47 [4426] [95] DEBUG: AT2[wavecom_73]: device opened. >> Telnet mode = 0 >> 2011-08-02 09:58:47 [4426] [95] DEBUG: AT2[wavecom_73]: device opened >> 2011-08-02 09:58:47 [4426] [95] INFO: AT2[wavecom_73]: Logging in >> 2011-08-02 09:58:48 [4426] [95] INFO: AT2[wavecom_73]: init device >> 2011-08-02 09:58:48 [4426] [95] INFO: AT2[wavecom_73]: speed set to 9600 >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: --> ATZ^M >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: <-- ATZ >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT^M >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: <-- AT >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT&F^M >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: <-- AT&F >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: --> ATE0^M >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: <-- ATE0 >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT+IFC=2,2^M >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >> 2011-08-02 09:58:48 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT+CPIN?^M >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: <-- +CPIN: READY >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: --> >> AT+CSCA="+xxxxxx"^M >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT+CMGF=0^M >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT+CSMS=?^M >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: <-- +CSMS: (0,1) >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >> 2011-08-02 09:58:49 [4426] [95] INFO: AT2[wavecom_73]: Phase 2+ is supported >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT+CSMS=1^M >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: <-- +CSMS: 1,1,1 >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: --> >> AT+CNMI=1,2,0,1,0^M >> 2011-08-02 09:58:49 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >> 2011-08-02 09:58:49 [4426] [95] INFO: AT2[wavecom_73]: AT SMSC successfully >> opened. >> >> Thanks, >> Donald >> >> On 02 Aug 2011, at 3:55 PM, Alvaro Cornejo wrote: >> >>> Hi >>> >>> Difficult to know without more info. It can be a problem with the >>> modem itself or the network. >>> >>> I dont see any init string for your modem. >>> >>> Add an init-string with "at+cmee=1" or 2 to the modem config so we can >>> have more verbose errors. >>> >>> Also, what is the port=10000 in the at smsc for? If you are not >>> connecting through IP you don't need it. >>> >>> Can you post the logs for the modem initialization/connection? >>> >>> Regards >>> >>> Alvaro >>> >>> >>> >>> |-----------------------------------------------------------------------------------------------------------------| >>> Envíe y Reciba Datos y mensajes de Texto (SMS) hacia y desde cualquier >>> celular y Nextel >>> en el Perú, México y en mas de 180 paises. Use aplicaciones 2 vias via >>> SMS y GPRS online >>> Visitenos en www.perusms.NET www.smsglobal.com.mx y >>> www.pravcom.com >>> >>> >>> >>> On Tue, Aug 2, 2011 at 6:44 AM, Donald Jackson <djack...@kannel.org> wrote: >>>> Hi there, >>>> >>>> I was wondering if anyone with some experience with GSM modems on Kannel >>>> could take a look at these logs and see if there is anything we can >>>> potentially configure to prevent the error. >>>> >>>> We are using Wavecom WMOD2 modems connected over USB. We are having >>>> sending messages fail intermittently (some work, some don't) and we aren't >>>> sure why. Below is a log with an example two messages (one worked, one >>>> didn't). I have removed the phone numbers. >>>> >>>> Log: >>>> >>>> 2011-08-02 10:38:22 [4426] [95] DEBUG: AT2[wavecom_73]: >>>> TP-Validity-Period: 24.0 hours >>>> 2011-08-02 10:38:22 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT+CMGS=152^M >>>> 2011-08-02 10:38:22 [4426] [95] DEBUG: AT2[wavecom_73]: <-- > >>>> 2011-08-02 10:38:22 [4426] [95] DEBUG: AT2[wavecom_73]: send command >>>> status: 1 >>>> 2011-08-02 10:38:22 [4426] [95] DEBUG: AT2[wavecom_73]: --> >>>> 0031000B917228280820F20000A79D46F9BB0D0ACF41EC349DCE2E83C2739034E6C2C140F0B21C440EE75DA0ECBB0E1A87DDA073990EAAC3 >>>> 41F437485A838160301808F99EC3D3F4301B340CCFD1206178BC0699E5EF3668C84E97DDF432BB0C62A6CD6517485A86B3F320E4730A4D82C26E32485C068DC36C76990C7ACB4153EA130AA2BF416F381DF4AED35D20 >>>> 699D5D9E838270383BEF02 >>>> 2011-08-02 10:38:22 [4426] [95] DEBUG: AT2[wavecom_73]: --> ^Z >>>> 2011-08-02 10:38:35 [4426] [95] DEBUG: AT2[wavecom_73]: <-- > >>>> 2011-08-02 10:38:35 [4426] [95] DEBUG: AT2[wavecom_73]: <-- ERROR >>>> 2011-08-02 10:38:35 [4426] [95] ERROR: AT2[wavecom_73]: Generic error: >>>> ERROR >>>> 2011-08-02 10:38:35 [4426] [95] DEBUG: AT2[wavecom_73]: send command >>>> status: -1 >>>> 2011-08-02 10:38:39 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT+CPMS?^M >>>> 2011-08-02 10:38:39 [4426] [95] DEBUG: AT2[wavecom_73]: <-- +CPMS: >>>> "SM",0,25,"SM",0,25 >>>> 2011-08-02 10:38:39 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >>>> 2011-08-02 10:39:07 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT^M >>>> 2011-08-02 10:39:07 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >>>> 2011-08-02 10:39:11 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT+CPMS?^M >>>> 2011-08-02 10:39:11 [4426] [95] DEBUG: AT2[wavecom_73]: <-- +CPMS: >>>> "SM",0,25,"SM",0,25 >>>> 2011-08-02 10:39:11 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >>>> 2011-08-02 10:39:21 [4426] [95] DEBUG: AT2[wavecom_73]: >>>> TP-Validity-Period: 24.0 hours >>>> 2011-08-02 10:39:21 [4426] [95] DEBUG: AT2[wavecom_73]: --> AT+CMGS=152^M >>>> 2011-08-02 10:39:21 [4426] [95] DEBUG: AT2[wavecom_73]: <-- > >>>> 2011-08-02 10:39:21 [4426] [95] DEBUG: AT2[wavecom_73]: send command >>>> status: 1 >>>> 2011-08-02 10:39:21 [4426] [95] DEBUG: AT2[wavecom_73]: --> >>>> 0031000B917228187555F20000A79D46F9BB0D0ACF41EC349DCE2E83C2739034E6C2C140F0B21C440EE75DA0ECBB0E1A87DDA073990EAAC3 >>>> 41F437485A838160301808F99EC3D3F4301B340CCFD1206178BC0699E5EF3668C84E97DDF432BB0C62A6CD6517485A86B3F320E4730A4D82C26E32485C068DC36C76990C7ACB4153EA130AA2BF416F381DF4AED35D20 >>>> 699D5D9E838270383BEF02 >>>> 2011-08-02 10:39:22 [4426] [95] DEBUG: AT2[wavecom_73]: --> ^Z >>>> 2011-08-02 10:39:27 [4426] [95] DEBUG: AT2[wavecom_73]: <-- > >>>> 2011-08-02 10:39:27 [4426] [95] DEBUG: AT2[wavecom_73]: <-- +CMGS: 54 >>>> 2011-08-02 10:39:27 [4426] [95] DEBUG: AT2[wavecom_73]: <-- OK >>>> 2011-08-02 10:39:27 [4426] [95] DEBUG: AT2[wavecom_73]: send command >>>> status: 0 >>>> 2011-08-02 10:39:27 [4426] [95] DEBUG: DLR[mysql]: Adding DLR >>>> smsc=wavecom_73, ts=54, src=+xxxx, dst=+xxxx, mask=19, boxc= >>>> >>>> Config: >>>> >>>> group = modems >>>> id = wavecom >>>> name = Wavecom >>>> detect-string = "WAVECOM" >>>> >>>> group=smsc >>>> smsc=at >>>> smsc-id=wavecom_73 >>>> allowed-smsc-id="modempool;wavecom_73" >>>> log-file=/var/log/kannel/wavecom_73.log >>>> port=10000 >>>> modemtype=wavecom >>>> device=/dev/serial/by-path/pci-0000:03:00.0-usb-0:1.3.2.4.5:1.0-port0 >>>> connect-allow-ip="*.*.*.*" >>>> sms-center=+xxxxx >>>> my-number=+xxxxx >>>> sim-buffering=true >>>> keepalive=30 >>>> >>>> Thanks very much, >>>> Donald >>>> >>>> >>>> >>>> >>>> >> >>
