Hi, Sorry about that, thought I had CC'ed the group as well.
I will try adding the log-file statement. Thanks. I'm puzzled by the CMS ERROR 69 as well, as it is intermittent. It only seems to occur with certain numbers / messages. Using the SIM to send text SMSs from a standard phone works well. We successfully send SMSs routinely using the very same modem. Were are using a MultiTech MultiModem GPRS (model: MTCBA-G-U-F4). Though in the bearerbox.log it comes up as a WAVECCOM modem. 2013-04-17 09:54:08 [26900] [7] DEBUG: AT2[usb0-modem]: --> ATI^M 2013-04-17 09:54:08 [26900] [7] DEBUG: AT2[usb0-modem]: <-- WAVECOM MODEM 2013-04-17 09:54:08 [26900] [7] DEBUG: AT2[usb0-modem]: <-- 900E 2013-04-17 09:54:08 [26900] [7] DEBUG: AT2[usb0-modem]: <-- OK 2013-04-17 09:54:08 [26900] [7] DEBUG: Loading include file `/etc/kannel/modems.conf' (on line 9 of file /etc/kannel/kannel.conf). 2013-04-17 09:54:08 [26900] [7] DEBUG: Loading include file `/etc/kannel/modems.conf' (on line 9 of file /etc/kannel/kannel.conf). 2013-04-17 09:54:08 [26900] [7] DEBUG: AT2[usb0-modem]: found string <WAVECOM>, using modem definition <Wavecom> 2013-04-17 09:54:08 [26900] [7] DEBUG: AT2[usb0-modem]: --> AT+CSMS=?^M 2013-04-17 09:54:08 [26900] [7] DEBUG: AT2[usb0-modem]: <-- +CSMS: (0,1) 2013-04-17 09:54:08 [26900] [7] DEBUG: AT2[usb0-modem]: <-- OK 2013-04-17 09:54:08 [26900] [7] INFO: AT2[usb0-modem]: Phase 2+ is supported 2013-04-17 09:54:08 [26900] [7] INFO: AT2[usb0-modem]: Closing device 2013-04-17 09:54:08 [26900] [7] INFO: AT2[usb0-modem]: opening device 2013-04-17 09:54:08 [26900] [7] DEBUG: AT2[usb0-modem]: device opened. Telnet mode = 0 2013-04-17 09:54:08 [26900] [7] DEBUG: AT2[usb0-modem]: device opened 2013-04-17 09:54:08 [26900] [7] INFO: AT2[usb0-modem]: Logging in 2013-04-17 09:54:09 [26900] [7] INFO: AT2[usb0-modem]: init device 2013-04-17 09:54:09 [26900] [7] INFO: AT2[usb0-modem]: speed set to 115200 The modems.conf # Modems configuration # # Example and default values # # group = modems # id = "my-id" # name = "my-name" # detect-string = "MODEM" # detect-string2 = "" [Default] # init-string = "AT+CNMI=1,2,0,0,0" [Default] # speed = 9600 [Default] # enable-hwhs = "AT+IFC=2,2" [Default] # need-sleep = false [Default] # no-pin = false [Default] # no-smsc = false [Default] # sendline-sleep = 100 [Default, miliseconds] # keepalive-cmd = "AT" [Default] # broken = false [Default] # message-storage = "SM" # enable-mms = false [Default] # If modemtype=auto, try everyone and defaults to this one group = modems id = generic name = "Generic Modem" group = modems id = wavecom name = Wavecom detect-string = "WAVECOM" init-string = "AT+CMEE=1" group = modems id = premicell name = Premicell detect-string = "PREMICEL" no-pin = true no-smsc = true group = modems id = siemens_tc35 name = "Siemens TC35" detect-string = "SIEMENS" detect-string2 = "TC35" init-string = "AT+CNMI=1,2,0,1,1" speed = 19200 enable-hwhs = "AT\\Q3" need-sleep = true group = modems id = siemens_m20 name = "Siemens M20" detect-string = "SIEMENS" detect-string2 = "M20" speed = 19200 enable-hwhs = "AT\\Q3" keepalive-cmd = "AT+CBC;+CSQ" need-sleep = true group = modems id = siemens_sl45 name = "Siemens SL45" detect-string = "SIEMENS" detect-string2 = "SL45" init-string = "AT+CNMI=1,2,2,2,1" keepalive-cmd = "AT+CBC;+CSQ" speed = 19200 enable-hwhs = "AT\\Q3" need-sleep = true message-storage = "SM" group = modems id = nokiaphone name = "Nokia Phone" detect-string = "Nokia Mobile Phone" need-sleep = true keepalive-cmd = "AT+CBC;+CSQ" enable-mms = true group = modems id = falcom name = "Falcom" detect-string = "Falcom" #no-smsc = true group = modems id = ericsson_r520m name = "Ericsson R520m" detect-string = "R520m" init-string = "AT+CNMI=3,2,0,0" group = modems id = ericsson_t68 name = "Ericsson T68" detect-string = "T68" init-string = "AT+CNMI=3,3" keepalive-cmd = "AT+CBC;+CSQ" broken = true group = modems id = sonyericsson_gr47 name = "Sony Ericsson GR47" detect-string = "GR47" message-storage = "ME" # use this init-string for non sim-buffering init-string = "AT+CNMI=3,2,0,0" # use this init-string for sim-buffering #init-string = "AT+CNMI=3,1,0,0" reset-string = "ATZ" broken = true group = modems id = alcatel name = "Alcatel" detect-string = "Alcatel" init-string = "AT+CNMI=3,2,0,0" group = modems id = sonyericsson_T630-T628 name = "Sony Ericsson T630-T628? init-string = "AT+CNMI=2,3,2,0,0;+CMGF=0? keepalive-cmd = "AT+CBC;+CSQ;+CMGF=0? broken = true group = modems id = sonyericsson_p1i name = "Sony Ericsson P1i" detect-string = "Sony Ericsson P1i" init-string = "ATQ0 V1 E1 S0=0 &C1 &D2 +FCLASS=0" message-storage = "SM" reset-string = "ATZ" broken = true Thanks again for you help with this, anders… On 17/04/2013, at 11:04 PM, Alvaro Cornejo <[email protected]> wrote: > Hi > > Always CC Kannel list so you can get help from others and other can > benefit from this. > > What modem are you using? post your modem.conf file also > > CMS ERROR 69 is puzzling; wavecom documentation said "Requested > facility not implemented". This usually indicates that the network is > unable to provide the requested short message service. > > Have you tried to send sms with this sim from a standard phone? If > that does work, then you need to recheck modem init string. Else it > can be a carrier issue where your sim is not SMS enabled or something > similar. > > In addition, you can define a separate log for your modem. This > usually helps debugging. Just add a log-file statement in smsc-at > config as is on bearerbox config. > > Regards > > Alvaro > > > > > > On 4/17/13, Anders Hofstee <[email protected]> wrote: >> Hi Alvaro, >> >> Thank you for responding. >> >> I have added AT+CMEE=1 to the modem init string in modems.conf. The error >> no longer shows a s a GENERIC Error but rather, if I'm reading it correctly, >> a CMS ERROR: 69 Requested facility not implemented. >> >> Identical messages seem to go through fine. This only affects certain >> numbers. >> Thank you for your assistance! >> >> anders… >> >> >> >> Log snippet below: >> >> 2013-04-17 10:01:08 [27086] [7] INFO: AT2[usb0-modem]: AT SMSC successfully >> opened. >> 2013-04-17 10:01:08 [27086] [8] DEBUG: sms_router: gwlist_len = 1 >> 2013-04-17 10:01:08 [27086] [8] DEBUG: sms_router: handling message >> (0x94573d8 vs 0x94573d8) >> 2013-04-17 10:01:08 [27086] [8] DEBUG: Message routed successfully. >> 2013-04-17 10:01:10 [27086] [7] DEBUG: AT2[usb0-modem]: TP-Validity-Period: >> 24.0 hours >> 2013-04-17 10:01:10 [27086] [7] DEBUG: AT2[usb0-modem]: --> AT+CMGS=151^M >> 2013-04-17 10:01:10 [27086] [7] DEBUG: AT2[usb0-modem]: <-- > >> 2013-04-17 10:01:10 [27086] [7] DEBUG: AT2[usb0-modem]: send command status: >> 1 >> 2013-04-17 10:01:10 [27086] [7] DEBUG: AT2[usb0-modem]: --> >> 0011000B917670774033F70000A79CC2B2DB9E7693DFA0343A0C62A6CF6150D21D76839A617718442DCBCBEAB43B1C0241C3F2B0B8EC9E83C461509A1E06B9D361507A9E7683E4F57008146CA741F3721A840ECBEBEB30A85D76CFC3E7B21BB40E93C3A079B91D768741617A1D34AFC3DF727A1894A68741EFB47B1E069DC3F2B09B9E0E83D2F43008FD0689CBE232681EAE93C3F6323B04 >> 2013-04-17 10:01:10 [27086] [7] DEBUG: AT2[usb0-modem]: --> ^Z >> 2013-04-17 10:01:14 [27086] [7] DEBUG: AT2[usb0-modem]: <-- > >> 2013-04-17 10:01:14 [27086] [7] DEBUG: AT2[usb0-modem]: <-- +CMS ERROR: 69 >> 2013-04-17 10:01:14 [27086] [7] ERROR: AT2[usb0-modem]: CMS ERROR: +CMS >> ERROR: 69 >> 2013-04-17 10:01:14 [27086] [7] ERROR: AT2[usb0-modem]: CMS ERROR: Requested >> facility not implemented (69) >> 2013-04-17 10:01:14 [27086] [7] DEBUG: AT2[usb0-modem]: send command status: >> 1 >> 2013-04-17 10:01:14 [27086] [8] DEBUG: sms_router: handling message >> (0xb5a00818 vs 0xb5a00818) >> 2013-04-17 10:01:14 [27086] [8] DEBUG: re-queing SMS not-yet-to-be resent >> 2013-04-17 10:01:14 [27086] [8] DEBUG: sms_router: handling message >> (0xb5a00818 vs 0xb5a00818) >> 2013-04-17 10:01:14 [27086] [8] DEBUG: re-queing SMS not-yet-to-be resent >> 2013-04-17 10:01:14 [27086] [8] DEBUG: sms_router: time to sleep 30.00 >> secs. >> 2013-04-17 10:01:44 [27086] [8] DEBUG: sms_router: gwlist_len = 1 >> 2013-04-17 10:01:44 [27086] [8] DEBUG: sms_router: handling message >> (0xb5a00818 vs 0xb5a00818) >> 2013-04-17 10:01:44 [27086] [8] DEBUG: re-queing SMS not-yet-to-be resent >> 2013-04-17 10:01:44 [27086] [8] DEBUG: sms_router: handling message >> (0xb5a00818 vs 0xb5a00818) >> 2013-04-17 10:01:44 [27086] [8] DEBUG: re-queing SMS not-yet-to-be resent >> 2013-04-17 10:01:44 [27086] [8] DEBUG: sms_router: time to sleep 30.00 >> secs. >> 2013-04-17 10:02:14 [27086] [8] DEBUG: sms_router: gwlist_len = 1 >> 2013-04-17 10:02:14 [27086] [8] DEBUG: sms_router: handling message >> (0xb5a00818 vs 0xb5a00818) >> 2013-04-17 10:02:14 [27086] [8] DEBUG: Message routed successfully. >> 2013-04-17 10:02:16 [27086] [7] DEBUG: AT2[usb0-modem]: TP-Validity-Period: >> 24.0 hours >> 2013-04-17 10:02:16 [27086] [7] DEBUG: AT2[usb0-modem]: --> AT+CMGS=151^M >> 2013-04-17 10:02:16 [27086] [7] DEBUG: AT2[usb0-modem]: <-- > >> 2013-04-17 10:02:16 [27086] [7] DEBUG: AT2[usb0-modem]: send command status: >> 1 >> 2013-04-17 10:02:16 [27086] [7] DEBUG: AT2[usb0-modem]: --> >> 0011000B917670774033F70000A79CC2B2DB9E7693DFA0343A0C62A6CF6150D21D76839A617718442DCBCBEAB43B1C0241C3F2B0B8EC9E83C461509A1E06B9D361507A9E7683E4F57008146CA741F3721A840ECBEBEB30A85D76CFC3E7B21BB40E93C3A079B91D768741617A1D34AFC3DF727A1894A68741EFB47B1E069DC3F2B09B9E0E83D2F43008FD0689CBE232681EAE93C3F6323B04 >> 2013-04-17 10:02:16 [27086] [7] DEBUG: AT2[usb0-modem]: --> ^Z >> 2013-04-17 10:02:20 [27086] [7] DEBUG: AT2[usb0-modem]: <-- > >> 2013-04-17 10:02:20 [27086] [7] DEBUG: AT2[usb0-modem]: <-- +CMS ERROR: 69 >> 2013-04-17 10:02:20 [27086] [7] ERROR: AT2[usb0-modem]: CMS ERROR: +CMS >> ERROR: 69 >> 2013-04-17 10:02:20 [27086] [7] ERROR: AT2[usb0-modem]: CMS ERROR: Requested >> facility not implemented (69) >> 2013-04-17 10:02:20 [27086] [7] DEBUG: AT2[usb0-modem]: send command status: >> 1 >> 2013-04-17 10:02:20 [27086] [8] DEBUG: sms_router: handling message >> (0xb5a00530 vs 0xb5a00530) >> 2013-04-17 10:02:20 [27086] [8] DEBUG: re-queing SMS not-yet-to-be resent >> 2013-04-17 10:02:20 [27086] [8] DEBUG: sms_router: handling message >> (0xb5a00530 vs 0xb5a00530) >> 2013-04-17 10:02:20 [27086] [8] DEBUG: re-queing SMS not-yet-to-be resent >> 2013-04-17 10:02:20 [27086] [8] DEBUG: sms_router: time to sleep 30.00 >> secs. >> 2013-04-17 10:02:33 [27086] [14] INFO: Connection closed by the box >> <127.0.0.1> >> >> >> >> >> >> On 16/04/2013, at 11:30 PM, Alvaro Cornejo <[email protected]> >> wrote: >> >>> Hi >>> >>> Add AT+CMEE=1 or =2 to your modem init string. It will configure modem >>> to give more detailed info of the error. >>> >>> post new log. >>> >>> Regards >>> >>> Alvaro >>> >>> On 4/16/13, Anders Hofstee <[email protected]> wrote: >>>> Hi all, >>>> >>>> If anyone could help with the following problem it would be very much >>>> appreciated. >>>> >>>> I'm new to kannel and have seem to run across a problem where there are >>>> times when kannel will continue to (re)send messages seemingly >>>> indefinitely >>>> unless stopped. This happens with some of the messages during a bulk >>>> send. >>>> All of the numbers are correctly formatted, though I'm not sure if all >>>> are >>>> active. >>>> >>>> The bearerbox.log shows repeated GENERIC Errors which is often followed >>>> by a >>>> re-queue message. >>>> >>>> A snippet from the bearer.log is below, followed by my kannel.conf >>>> >>>> 2013-04-15 15:21:57 [18162] [7] DEBUG: AT2[usb0-modem]: <-- ERROR >>>> 2013-04-15 15:21:57 [18162] [7] ERROR: AT2[usb0-modem]: Generic error: >>>> ERROR >>>> 2013-04-15 15:21:57 [18162] [7] DEBUG: AT2[usb0-modem]: send command >>>> status: >>>> -1 >>>> 2013-04-15 15:21:59 [18162] [7] DEBUG: AT2[usb0-modem]: >>>> TP-Validity-Period: >>>> 24.0 hours >>>> 2013-04-15 15:21:59 [18162] [7] DEBUG: AT2[usb0-modem]: --> >>>> AT+CMGS=150^M >>>> 2013-04-15 15:21:59 [18162] [7] DEBUG: AT2[usb0-modem]: <-- > >>>> 2013-04-15 15:21:59 [18162] [7] DEBUG: AT2[usb0-modem]: send command >>>> status: >>>> 1 >>>> 2013-04-15 15:21:59 [18162] [7] DEBUG: AT2[usb0-modem]: --> >>>> 0011000B917670676962F20000A79BC979DA0D92D7C3A0343A0CA297DBF03A481C2687D72076989C0EAF41E23028ED0EBB41EE7418340FD7C9651668FD0EB3D36110FA0D4AD3C320773A0C5A87C56537C8FD06A1EBF33A28ED36BFE5EDF03C5C7783C461103C2CA797D3F23068FD768741E230E89D76CFC320F31B549EC3C3F33AA8ECA6CBCBA077D80D42BF41F3709D5C0691D3E1B50B >>>> 2013-04-15 15:21:59 [18162] [7] DEBUG: AT2[usb0-modem]: --> ^Z >>>> 2013-04-15 15:22:03 [18162] [7] DEBUG: AT2[usb0-modem]: <-- > >>>> 2013-04-15 15:22:03 [18162] [7] DEBUG: AT2[usb0-modem]: <-- ERROR >>>> 2013-04-15 15:22:03 [18162] [7] ERROR: AT2[usb0-modem]: Generic error: >>>> ERROR >>>> 2013-04-15 15:22:03 [18162] [7] DEBUG: AT2[usb0-modem]: send command >>>> status: >>>> -1 >>>> 2013-04-15 15:22:05 [18162] [7] DEBUG: AT2[usb0-modem]: >>>> TP-Validity-Period: >>>> 24.0 hours >>>> 2013-04-15 15:22:05 [18162] [7] DEBUG: AT2[usb0-modem]: --> >>>> AT+CMGS=139^M >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: sms_router: gwlist_len = 6 >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: sms_router: handling message >>>> (0xb1202990 vs 0xb1202990) >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: re-queing SMS not-yet-to-be >>>> resent >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: sms_router: handling message >>>> (0xb12027b8 vs 0xb1202990) >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: re-queing SMS not-yet-to-be >>>> resent >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: sms_router: handling message >>>> (0xb1203ca0 vs 0xb1202990) >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: re-queing SMS not-yet-to-be >>>> resent >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: sms_router: handling message >>>> (0xb1200808 vs 0xb1202990) >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: re-queing SMS not-yet-to-be >>>> resent >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: sms_router: handling message >>>> (0xb1203a68 vs 0xb1202990) >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: re-queing SMS not-yet-to-be >>>> resent >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: sms_router: handling message >>>> (0xb1204398 vs 0xb1202990) >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: re-queing SMS not-yet-to-be >>>> resent >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: sms_router: handling message >>>> (0xb1202990 vs 0xb1202990) >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: re-queing SMS not-yet-to-be >>>> resent >>>> 2013-04-15 15:22:05 [18162] [8] DEBUG: sms_router: time to sleep 30.00 >>>> secs. >>>> 2013-04-15 15:22:05 [18162] [7] DEBUG: AT2[usb0-modem]: <-- > >>>> >>>> >>>> >>>> >>>> >>>> >>>> >>>> kannel.conf >>>> >>>> # >>>> # Sample configuration file for Kannel bearerbox on Debian. >>>> # See the documentation for explanations of fields. >>>> # >>>> >>>> # HTTP administration is disabled by default. Make sure you set the >>>> # password if you enable it. >>>> >>>> include = "/etc/kannel/modems.conf" >>>> >>>> >>>> group = core >>>> admin-port = 13000 >>>> admin-password = ****** >>>> status-password = ****** >>>> admin-deny-ip = "*.*.*.*" >>>> admin-allow-ip = "127.0.0.1" >>>> smsbox-port = 13001 >>>> wdp-interface-name = "*" >>>> log-file = "/var/log/kannel/bearerbox.log" >>>> box-deny-ip = "*.*.*.*" >>>> box-allow-ip = "127.0.0.1" >>>> store-type = spool >>>> store-location = "/var/spool/kannel" >>>> >>>> >>>> #group = wapbox >>>> #bearerbox-host = localhost >>>> #log-file = "/var/log/kannel/wapbox.log" >>>> >>>> group = smsc >>>> smsc = fake >>>> smsc-id = FAKE >>>> port = 10000 >>>> connect-allow-ip = 127.0.0.1 >>>> >>>> group = smsbox >>>> bearerbox-host = 127.0.0.1 >>>> sendsms-port = 13013 >>>> sendsms-chars = "0123456789 +-" >>>> log-file = "/var/log/kannel/smsbox.log" >>>> log-level = 0 >>>> access-log = "/var/log/kannel/smsbox-access.log" >>>> reply-couldnotfetch = "Your message could not be processed at this time. >>>> Please try again later. (err=couldnotfetch)" >>>> reply-requestfailed = "Your message could not be processed at this time. >>>> Please try again later. (err=requestfailed)" >>>> reply-couldnotrepresent = "Your message could not be processed at this >>>> time. >>>> Please try again later. (err=couldnotrepresent)" >>>> http-request-retry = 3 >>>> http-queue-delay = 10 >>>> >>>> group = sendsms-user >>>> username = ****** >>>> password = ****** >>>> user-deny-ip = "*.*.*.*" >>>> user-allow-ip = "127.0.0.1;" >>>> >>>> group = sms-service >>>> keyword = ping-kannel >>>> text = "Kannel is online and responding to messages." >>>> >>>> group = smsc >>>> smsc = at >>>> smsc-id = usb0-modem >>>> my-number = *********** >>>> modemtype = auto >>>> device = /dev/ttyUSB0 >>>> >>>> group = sms-service >>>> keyword = default >>>> catch-all = yes >>>> accepted-smsc = usb0-modem >>>> #max-message = 0 >>>> accept-x-kannel-headers = true >>>> assume-plain-text= true >>>> get-url = >>>> http://127.0.0.1:8000/router/receive?message=%b&sender=%p&backend=console >>>> omit-empty = true >>>> >>>> >>>> >>>> >>> >>> >>> -- >>> |-----------------------------------------------------------------------------------------------------------------| >>> 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 >> >> > > > -- > |-----------------------------------------------------------------------------------------------------------------| > 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
