Hi all..
Im have installing sms server using wvdial, kannel and playsms on centos,
and im using modem Fastrack Xtend FX009,
The first day im running kannel no problem my sms server is running well,
no error, but after sending more than 1000 massages for tested my server has
error log on bearerbox, this is my error log:
---- error log on bearerbox ---------------------
[root@centos ~]# bearerbox /etc/kannel.conf
2011-05-02 15:19:18 [5466] [0] INFO: Debug_lvl = -1, log_file = <none>,
log_lvl = 0
2011-05-02 15:19:18 [5466] [0] WARNING: DLR: using default 'internal' for
storage type.
2011-05-02 15:19:18 [5466] [0] INFO: DLR using storage type: internal
2011-05-02 15:19:18 [5466] [0] DEBUG: Kannel bearerbox version `1.4.2'.
Build `Jan 21 2009 15:13:10', compiler `4.1.2 20071124 (Red Hat 4.1.2-42)'.
System Linux, release 2.6.18-194.32.1.el5, version #1 SMP Wed Jan 5 17:52:25
EST 2011, machine x86_64.
Hostname centos, IP 127.0.0.1.
Libxml version 2.6.26.
Using OpenSSL 0.9.8b 04 May 2006.
Using SQLite 3.3.6.
Using native malloc.
2011-05-02 15:19:18 [5466] [0] INFO: Added logfile
`/var/log/kannel/kannel.log' with level `0'.
2011-05-02 15:19:18 [5466] [0] INFO: Started access logfile
`/var/log/kannel/access.log'.
2011-05-02 15:19:18 [5466] [0] WARNING: 'store-file' option deprecated,
please use 'store-location' and 'store-type' instead.
2011-05-02 15:19:18 [5466] [0] INFO: HTTP: Opening server at port 13000.
2011-05-02 15:19:18 [5466] [0] DEBUG: Started thread 1
(gwlib/fdset.c:poller)
2011-05-02 15:19:18 [5466] [0] DEBUG: Started thread 2
(gwlib/http.c:server_thread)
2011-05-02 15:19:18 [5466] [0] DEBUG: Started thread 3
(gw/bb_http.c:httpadmin_run)
2011-05-02 15:19:18 [5466] [0] DEBUG: starting smsbox connection module
2011-05-02 15:19:18 [5466] [0] INFO: BOXC: 'smsbox-max-pending' not set,
using default (100).
2011-05-02 15:19:18 [5466] [0] DEBUG: Started thread 4
(gw/bb_boxc.c:sms_to_smsboxes)
2011-05-02 15:19:18 [5466] [0] DEBUG: Started thread 5
(gw/bb_boxc.c:smsboxc_run)
2011-05-02 15:19:18 [5466] [4] DEBUG: Thread 4
(gw/bb_boxc.c:sms_to_smsboxes) maps to pid 5466.
2011-05-02 15:19:18 [5466] [5] DEBUG: Thread 5 (gw/bb_boxc.c:smsboxc_run)
maps to pid 5466.
2011-05-02 15:19:18 [5466] [0] INFO: Set SMS resend frequency to 60 seconds.
2011-05-02 15:19:18 [5466] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps
to pid 5466.
2011-05-02 15:19:18 [5466] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread)
maps to pid 5466.
2011-05-02 15:19:18 [5466] [3] DEBUG: Thread 3 (gw/bb_http.c:httpadmin_run)
maps to pid 5466.
2011-05-02 15:19:18 [5466] [0] INFO: SMS resend retry set to unlimited.
2011-05-02 15:19:18 [5466] [0] DEBUG: smsbox MO concatenated message
handling enabled
2011-05-02 15:19:18 [5466] [0] INFO: DLR rerouting for smsc id <WAVECOM>
disabled.
2011-05-02 15:19:18 [5466] [0] INFO: AT2[WAVECOM]: configuration doesn't
show modemtype. will autodetect
2011-05-02 15:19:18 [5466] [0] DEBUG: Started thread 6
(gw/smsc/smsc_at.c:at2_device_thread)
2011-05-02 15:19:18 [5466] [0] DEBUG: Started thread 7
(gw/bb_smscconn.c:sms_router)
2011-05-02 15:19:18 [5466] [0] INFO:
----------------------------------------
2011-05-02 15:19:18 [5466] [0] INFO: Kannel bearerbox II version 1.4.2
starting
2011-05-02 15:19:18 [5466] [0] INFO: Loading store file
`/var/log/kannel/kannel.store'
2011-05-02 15:19:18 [5466] [6] DEBUG: Thread 6
(gw/smsc/smsc_at.c:at2_device_thread) maps to pid 5466.
2011-05-02 15:19:18 [5466] [7] DEBUG: Thread 7 (gw/bb_smscconn.c:sms_router)
maps to pid 5466.
2011-05-02 15:19:18 [5466] [0] INFO: Store-file size 418, starting to unpack
2011-05-02 15:19:18 [5466] [0] INFO: Retrieved 2 messages, non-acknowledged
messages: 2
2011-05-02 15:19:18 [5466] [0] DEBUG: Started thread 8
(gw/bb_store_file.c:store_dumper)
2011-05-02 15:19:18 [5466] [0] INFO: MAIN: Start-up done, entering mainloop
2011-05-02 15:19:18 [5466] [0] DEBUG: AT2[WAVECOM]: start called
2011-05-02 15:19:18 [5466] [6] DEBUG: AT2[WAVECOM]: detecting modem type
2011-05-02 15:19:18 [5466] [6] INFO: AT2[WAVECOM]: opening device
2011-05-02 15:19:18 [5466] [8] DEBUG: Thread 8
(gw/bb_store_file.c:store_dumper) maps to pid 5466.
2011-05-02 15:19:18 [5466] [7] DEBUG: sms_router: handling message
(0x1aaa71c0 vs 0x1aaa71c0)
2011-05-02 15:19:18 [5466] [7] DEBUG: Routing failed, re-queued.
2011-05-02 15:19:18 [5466] [7] DEBUG: sms_router: handling message
(0x1aaa75c0 vs 0x1aaa71c0)
2011-05-02 15:19:18 [5466] [7] DEBUG: Routing failed, re-queued.
2011-05-02 15:19:18 [5466] [7] DEBUG: sms_router: handling message
(0x1aaa71c0 vs 0x1aaa71c0)
2011-05-02 15:19:18 [5466] [7] DEBUG: Routing failed, re-queued.
2011-05-02 15:19:18 [5466] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2011-05-02 15:19:18 [5466] [7] DEBUG: sms_router: gwlist_len = 2
2011-05-02 15:19:18 [5466] [7] DEBUG: sms_router: handling message
(0x1aaa75c0 vs 0x1aaa75c0)
2011-05-02 15:19:18 [5466] [7] DEBUG: Routing failed, re-queued.
2011-05-02 15:19:18 [5466] [7] DEBUG: sms_router: handling message
(0x1aaa71c0 vs 0x1aaa75c0)
2011-05-02 15:19:18 [5466] [7] DEBUG: Routing failed, re-queued.
2011-05-02 15:19:18 [5466] [7] DEBUG: sms_router: handling message
(0x1aaa75c0 vs 0x1aaa75c0)
2011-05-02 15:19:18 [5466] [7] DEBUG: Routing failed, re-queued.
2011-05-02 15:19:18 [5466] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2011-05-02 15:19:18 [5466] [6] DEBUG: AT2[WAVECOM]: device opened. Telnet
mode = 0
2011-05-02 15:19:19 [5466] [6] DEBUG: AT2[WAVECOM]: device opened
2011-05-02 15:19:19 [5466] [6] INFO: AT2[WAVECOM]: speed set to 460800
2011-05-02 15:19:19 [5466] [6] DEBUG: AT2[WAVECOM]: --> ^M
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT^M
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- AT
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT&F^M
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- AT&F
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: --> ATE0^M
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- ATE0
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: --> ATI^M
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- WAVECOM WIRELESS CPU
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- MULTIBAND 900E
1800
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: found string <WAVECOM>,
using modem definition <WAVECOM>
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT+CSMS=?^M
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- +CSMS: (0,1)
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:21 [5466] [6] INFO: AT2[WAVECOM]: Phase 2+ is supported
2011-05-02 15:19:21 [5466] [6] INFO: AT2[WAVECOM]: Closing device
2011-05-02 15:19:21 [5466] [6] INFO: AT2[WAVECOM]: opening device
2011-05-02 15:19:21 [5466] [6] DEBUG: AT2[WAVECOM]: device opened. Telnet
mode = 0
2011-05-02 15:19:22 [5466] [6] DEBUG: AT2[WAVECOM]: device opened
2011-05-02 15:19:22 [5466] [6] INFO: AT2[WAVECOM]: Logging in
2011-05-02 15:19:23 [5466] [6] INFO: AT2[WAVECOM]: init device
2011-05-02 15:19:23 [5466] [6] INFO: AT2[WAVECOM]: speed set to 460800
2011-05-02 15:19:23 [5466] [6] DEBUG: AT2[WAVECOM]: --> ATZ^M
2011-05-02 15:19:23 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:23 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT^M
2011-05-02 15:19:23 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:23 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT&F^M
2011-05-02 15:19:24 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:24 [5466] [6] DEBUG: AT2[WAVECOM]: --> ATE0^M
2011-05-02 15:19:24 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:24 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT+IFC=2,2^M
2011-05-02 15:19:24 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:24 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT+CPIN?^M
2011-05-02 15:19:24 [5466] [6] DEBUG: AT2[WAVECOM]: <-- +CPIN: READY
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: -->
AT+CSCA="+628315000032"^M
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT+CMGF=0^M
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT+CSMS=?^M
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: <-- +CSMS: (0,1)
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:35 [5466] [6] INFO: AT2[WAVECOM]: Phase 2+ is supported
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT+CSMS=1^M
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: <-- +CSMS: 1,1,1
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: --> ATQ0 V1 E1 S0=0 &C1
&D2 +FCLASS=0^M
2011-05-02 15:19:35 [5466] [6] DEBUG: AT2[WAVECOM]: <-- OK
2011-05-02 15:19:35 [5466] [6] INFO: AT2[WAVECOM]: AT SMSC successfully
opened.
2011-05-02 15:19:35 [5466] [7] DEBUG: sms_router: gwlist_len = 2
2011-05-02 15:19:35 [5466] [7] DEBUG: sms_router: handling message
(0x1aaa71c0 vs 0x1aaa71c0)
2011-05-02 15:19:35 [5466] [7] DEBUG: Message routed successfully.
2011-05-02 15:19:35 [5466] [7] DEBUG: sms_router: handling message
(0x1aaa75c0 vs 0x1aaa75c0)
2011-05-02 15:19:35 [5466] [7] DEBUG: Message routed successfully.
2011-05-02 15:19:37 [5466] [6] DEBUG: AT2[WAVECOM]: TP-Validity-Period: 24.0
hours
2011-05-02 15:19:37 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT+CMGS=34^M
2011-05-02 15:19:37 [5466] [6] DEBUG: AT2[WAVECOM]: <-- AT+CMGS=34
2011-05-02 15:19:37 [5466] [6] DEBUG: AT2[WAVECOM]: <-- >
2011-05-02 15:19:37 [5466] [6] DEBUG: AT2[WAVECOM]: send command status: 1
2011-05-02 15:19:37 [5466] [6] DEBUG: AT2[WAVECOM]: -->
0011000C818076046550650000A716EE77BB2C07CDC3F03028ED4EFF40EBFBFABEBE03
2011-05-02 15:19:37 [5466] [6] DEBUG: AT2[WAVECOM]: --> ^Z
2011-05-02 15:19:38 [5466] [8] DEBUG: Dumping 2 messages to store
2011-05-02 15:19:39 [5466] [6] DEBUG: AT2[WAVECOM]: <-- >
0011000C818076046550650000A716EE77BB2C07CDC3F03028ED4EFF40EBFBFABEBE03
2011-05-02 15:19:39 [5466] [6] DEBUG: AT2[WAVECOM]: <-- ERROR
2011-05-02 15:19:39 [5466] [6] ERROR: AT2[WAVECOM]: Generic error: ERROR
2011-05-02 15:19:39 [5466] [6] DEBUG: AT2[WAVECOM]: send command status: -1
2011-05-02 15:19:39 [5466] [7] DEBUG: sms_router: handling message
(0x1aaa8bb0 vs 0x1aaa8bb0)
2011-05-02 15:19:39 [5466] [7] DEBUG: re-queing SMS not-yet-to-be resent
2011-05-02 15:19:39 [5466] [7] DEBUG: sms_router: handling message
(0x1aaa8bb0 vs 0x1aaa8bb0)
2011-05-02 15:19:39 [5466] [7] DEBUG: re-queing SMS not-yet-to-be resent
2011-05-02 15:19:39 [5466] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2011-05-02 15:19:41 [5466] [6] DEBUG: AT2[WAVECOM]: TP-Validity-Period: 24.0
hours
2011-05-02 15:19:41 [5466] [6] DEBUG: AT2[WAVECOM]: --> AT+CMGS=18^M
2011-05-02 15:19:41 [5466] [6] DEBUG: AT2[WAVECOM]: <-- AT+CMGS=18>
2011-05-02 15:19:41 [5466] [6] DEBUG: AT2[WAVECOM]: send command status: 1
2011-05-02 15:19:41 [5466] [6] DEBUG: AT2[WAVECOM]: -->
0011000C818076046550650000A704E876BB0D
2011-05-02 15:19:41 [5466] [6] DEBUG: AT2[WAVECOM]: --> ^Z
2011-05-02 15:19:43 [5466] [6] DEBUG: AT2[WAVECOM]: <-- >
0011000C818076046550650000A704E876BB0D
2011-05-02 15:19:43 [5466] [6] DEBUG: AT2[WAVECOM]: <-- ERROR
2011-05-02 15:19:43 [5466] [6] ERROR: AT2[WAVECOM]: Generic error: ERROR
2011-05-02 15:19:43 [5466] [6] DEBUG: AT2[WAVECOM]: send command status: -1
---------------------------------------------- end error -----------------
and this is my kannel.conf
--------------------------------------------- kannel.conf
-------------------------------------------------
group = core
admin-port = 13000
admin-password =
log-file = "/var/log/kannel/kannel.log"
log-level = 0
access-log = "/var/log/kannel/access.log"
smsbox-port = 13001
store-file = "/var/log/kannel/kannel.store"
group = smsc
smsc = at
host = 192.168.1.4
port = 13013
smsc-id = WAVECOM
modem-type = WAVECOM
device = /dev/ttyACM0
speed = 460800
sms-center = "+628315000032"
group = modems
id = WAVECOM
name = WAVECOM
detect-string = WAVECOM
reset-string = "ATZ"
init-string = "ATQ0 V1 E1 S0=0 &C1 &D2 +FCLASS=0"
#init-string = "AT+CNMI=2,3,0,1,0;+CMEE=2"
message-storage = "SM"
need-sleep = true
group = smsbox
bearerbox-host = 192.168.1.4
sendsms-port = 13131
sendsms-chars = "0123456789+"
global-sender = "+628315000032"
log-file = "/var/log/kannel/smsbox.log"
log-level= 0
access-log = "/var/log/kannel/access.log"
group = sendsms-user
username = playsms
password = playsms
group = sms-service
keyword = default
accept-x-kannel-headers = true
max-messages = 10
assume-plain-text = true
catch-all = true
get-url= "http://192.168.1.4/sms/incomming.php?t=%t&q=%q&a=%a";
----------------------------- end kannel.conf-------------------------
im had try to fix it using:
1. Change usb port on my server but same result
2. Change usb with serial port but same result
3. Give report error in kannel.conf init, this error: CMS ERROR: 41
Im just think the storage massages in the modem has full, btw i am don't
know how to reset it, its not found on modem user guides.
Thanks for help.
Rama
