Hello, Whenever I send bulk sms, kannel crashes with segmentation fault. It also happens sometimes when sending single sms also. That is, I can't expect when it can crash. I can't trace any error in my config file.
I start kannel as follows :
/usr/local/kannel/sbin/bearerbox -v 0 /usr/local/kannel/smskannel.conf
and
/usr/local/kannel/sbin/smsbox -v 0 /usr/local/kannel/smskannel.conf
I also tried using
/usr/local/kannel/sbin/bearerbox -v 0 --daemonize --parachute
/usr/local/kannel/smskannel.conf
and
/usr/local/kannel/sbin/smsbox -v 0 --daemonize --parachute
/usr/local/kannel/smskannel.conf
I also tried by setting a script to start bearbox and smsbox when they crash.
Both has problems. It does not send sms to all numbers and miss many. May be
because bearbox or smsbox crash in between.
I have set a dlr-table and it shows lots of queued messages. Even when kannel
restarts it does not check the dlr-table for queue.
Status shown :
-------------------------------------------------------
SMS: received 2 (0 queued), sent 1 (0 queued), store size 0
SMS: inbound 0.00 msg/sec, outbound 0.00 msg/sec
DLR: 125 queued, using mysql storage
Box connections:
smsbox:(none), IP 127.0.0.1 (0 queued), (on-line 0d 2h 29m 46s)
SMSC connections:
route1 SMPP:smsc_hostname:port/port:username:NULL (online 155s, rcvd 2,
sent 1, failed 0, queued 0 msgs)
route2 SMPP:smsc_Ip:port/port:ceedaz:system_type (online 8986s, rcvd 0,
sent 0, failed 0, queued 0 msgs)
-------------------------------------------------------
We are developing a web-based software in PHP to send single and bulk sms. We
searched a lot in mail-archives and other sites about this problem. But did not
find any solution out of this. We have got stuck as we can't move further with
this problem.
Do kannel normally has this problem? What all can cause it ? What's the
solution out of this ?
Please help.
----------------------------------------------------------------
OS version: Red Hat Enterprise Linux Server release 5.2 (Tikanga)
Kannel version: 1.4.1
libxml version: libxml2
Used Client: Creating the client using PHP
Single or multi processor machine. : Intel(R) Pentium(R) 4 CPU 1.70GHz
Attach Kannel configuration file (remove passwords and login information!). :
attached
Attach Kannel log files from the failed attempt. : I have attached the only
error or panic message I can see in log files.
--------------------------------------------------------------
Meet people who discuss and share your passions. Go to
http://in.promos.yahoo.com/groups/bestofyahoo/
smskannel.conf
Description: Binary data
I have not attached the entire log. In bearerbox log ------------------- 2008-06-16 07:29:25 [32589] [10] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2008-06-16 07:29:25 [32589] [6] DEBUG: SMPP[route1]: Manually forced source addr ton = 0, source add npi = 1 2008-06-16 07:29:25 [32589] [6] DEBUG: SMPP[route1]: Manually forced dest addr ton = 1, dest add npi = 1 ...........AFTER SOME LINES.............. 2008-06-16 07:29:25 [32589] [6] DEBUG: short_message: "test2..." 2008-06-16 07:29:25 [32589] [6] DEBUG: SMPP PDU dump ends. 2008-06-16 07:29:25 [32589] [10] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2008-06-16 07:29:25 [32589] [10] DEBUG: boxc_receiver: sms received 2008-06-16 07:29:25 [825] [0] INFO: Added logfile `/var/log/kannel/kannel.log' with level `0'. 2008-06-16 07:29:25 [825] [0] INFO: Started access logfile `/var/log/kannel/access.log'. 2008-06-16 07:29:25 [825] [0] INFO: HTTP: Opening server at port 13000. 2008-06-16 07:29:25 [825] [0] DEBUG: Started thread 1 (gwlib/fdset.c:poller) 2008-06-16 07:29:25 [825] [0] DEBUG: Started thread 2 (gwlib/http.c:server_thread) 2008-06-16 07:29:25 [825] [0] DEBUG: Started thread 3 (gw/bb_http.c:httpadmin_run) 2008-06-16 07:29:25 [825] [0] DEBUG: starting smsbox connection module 2008-06-16 07:29:25 [825] [0] INFO: BOXC: 'smsbox-max-pending' not set, using default (100). 2008-06-16 07:29:25 [825] [0] DEBUG: Started thread 4 (gw/bb_boxc.c:sms_to_smsboxes) 2008-06-16 07:29:25 [825] [0] DEBUG: Started thread 5 (gw/bb_boxc.c:smsboxc_run) 2008-06-16 07:29:25 [32589] [6] DEBUG: short_message: "test2..." 2008-06-16 07:29:25 [32589] [6] DEBUG: SMPP PDU dump ends. 2008-06-16 07:29:25 [32589] [10] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2008-06-16 07:29:25 [32589] [10] DEBUG: boxc_receiver: sms received 2008-06-16 07:29:25 [825] [0] INFO: Added logfile `/var/log/kannel/kannel.log' with level `0'. 2008-06-16 07:29:25 [825] [0] INFO: Started access logfile `/var/log/kannel/access.log'. 2008-06-16 07:29:25 [825] [0] INFO: HTTP: Opening server at port 13000. 2008-06-16 07:29:25 [825] [0] DEBUG: Started thread 1 (gwlib/fdset.c:poller) 2008-06-16 07:29:25 [825] [0] DEBUG: Started thread 2 (gwlib/http.c:server_thread) 2008-06-16 07:29:25 [825] [0] DEBUG: Started thread 3 (gw/bb_http.c:httpadmin_run) 2008-06-16 07:29:25 [825] [0] DEBUG: starting smsbox connection module 2008-06-16 07:29:25 [825] [0] INFO: BOXC: 'smsbox-max-pending' not set, using default (100). 2008-06-16 07:29:25 [825] [0] DEBUG: Started thread 4 (gw/bb_boxc.c:sms_to_smsboxes) 2008-06-16 07:29:25 [825] [0] DEBUG: Started thread 5 (gw/bb_boxc.c:smsboxc_run) ------------------------------ In smsbox log ------------------------------ 2008-06-16 07:00:10 [4650] [3] INFO: smsbox: Got HTTP request </cgi-bin/sendsms> from <67.228.127.18> 2008-06-16 07:00:10 [4650] [3] INFO: sendsms used by <kanneluser> 2008-06-16 07:00:10 [4650] [3] INFO: sendsms sender:<kanneluser:soumya> (67.228.127.18) to:<2348033792039 2347032691005 2348 023848341 2348083425606 2348033576155> msg:<test......> 2008-06-16 07:00:10 [4650] [3] DEBUG: Stored UUID b31d780a-ed4f-47b6-b4e3-240a4fbda179 2008-06-16 07:00:10 [4650] [3] DEBUG: message length 10, sending 1 messages 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 4 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] DEBUG: message length 10, sending 1 messages 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 4 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] DEBUG: message length 10, sending 1 messages 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 4 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] DEBUG: message length 10, sending 1 messages 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 4 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] DEBUG: message length 10, sending 1 messages 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 4 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 1 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 47 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 2 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 17 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] DEBUG: Status: 202 Answer: <Sent.> 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 5 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] INFO: Mutex gwlib/list.c:134: 327 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [3] DEBUG: Delayed reply - wait for bearerbox 2008-06-16 07:00:10 [4650] [0] DEBUG: Got ACK (0) of b31d780a-ed4f-47b6-b4e3-240a4fbda179 2008-06-16 07:00:10 [4650] [0] INFO: Mutex gwlib/list.c:134: 3 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [0] DEBUG: HTTP: Destroying HTTPClient area 0x14ac640. 2008-06-16 07:00:10 [4650] [0] DEBUG: HTTP: Destroying HTTPClient for `67.228.127.18'. 2008-06-16 07:00:10 [4650] [0] INFO: Mutex gwlib/conn.c:534: 5 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [0] INFO: Mutex gwlib/conn.c:535: 3 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [0] DEBUG: Got ACK (0) of b31d780a-ed4f-47b6-b4e3-240a4fbda179 2008-06-16 07:00:10 [4650] [0] INFO: Mutex gwlib/list.c:134: 1 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [0] DEBUG: No client - multi-send or ACK to pull-reply 2008-06-16 07:00:10 [4650] [0] DEBUG: Got ACK (0) of b31d780a-ed4f-47b6-b4e3-240a4fbda179 2008-06-16 07:00:10 [4650] [0] INFO: Mutex gwlib/list.c:134: 1 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [0] DEBUG: No client - multi-send or ACK to pull-reply 2008-06-16 07:00:10 [4650] [0] DEBUG: Got ACK (0) of b31d780a-ed4f-47b6-b4e3-240a4fbda179 2008-06-16 07:00:10 [4650] [0] INFO: Mutex gwlib/list.c:134: 1 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [0] DEBUG: No client - multi-send or ACK to pull-reply 2008-06-16 07:00:10 [4650] [0] DEBUG: Got ACK (0) of b31d780a-ed4f-47b6-b4e3-240a4fbda179 2008-06-16 07:00:10 [4650] [0] INFO: Mutex gwlib/list.c:134: 1 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [0] DEBUG: No client - multi-send or ACK to pull-reply 2008-06-16 07:00:10 [4650] [4] INFO: Starting delivery report <kanneluser> from <soumya> 2008-06-16 07:00:10 [4650] [4] INFO: Mutex gwlib/list.c:134: 4 locks, 0 collisions. 2008-06-16 07:00:10 [4650] [9] DEBUG: Parsing URL `http://ceedazsms.com/web2sms/sendsms.php?to=2348033792039&text=test...... &type=8&time=2008-06-16+12:00:10&smsc-id=route1&udh=&msg-id=6291f8a2-5b30-442e-9c40-a102ed55cced&from=soumya&user_id=10000&m essage_id=82&bulk=1': 2008-06-16 07:00:10 [4650] [9] DEBUG: Scheme: http:// 2008-06-16 07:00:10 [4650] [9] DEBUG: Host: ceedazsms.com 2008-06-16 07:00:10 [4650] [9] DEBUG: Port: 80 2008-06-16 07:00:10 [4650] [9] DEBUG: Username: (null) 2008-06-16 07:00:10 [4650] [9] DEBUG: Password: (null) 2008-06-16 07:00:10 [4650] [9] DEBUG: Path: /web2sms/sendsms.php 2008-06-16 07:00:10 [4650] [9] DEBUG: Query: to=2348033792039&text=test......&type=8&time=2008-06-16+12:00:10&smsc-id=rout e1&udh=&msg-id=6291f8a2-5b30-442e-9c40-a102ed55cced&from=soumya&user_id=10000&message_id=82&bulk=1 .............AFTER SOME LINES.............. 008-06-16 07:00:11 [4650] [9] DEBUG: Octet string dump ends. 2008-06-16 07:00:11 [4650] [0] ERROR: Connection closed by the bearerbox. 2008-06-16 07:00:11 [4650] [0] INFO: Received (and handled?) 49 requests in 46512 seconds (0.00 per second) 2008-06-16 07:00:11 [4650] [0] INFO: Kannel smsbox terminating. 2008-06-16 07:00:11 [4650] [7] DEBUG: Thread 7 (gw/heartbeat.c:heartbeat_thread) terminates. 2008-06-16 07:00:11 [4650] [7] INFO: Mutex gwlib/list.c:134: 3 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [0] INFO: Mutex gwlib/list.c:134: 4 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [0] DEBUG: Waiting for 2 (gwlib/http.c:server_thread) to terminate 2008-06-16 07:00:11 [4650] [2] INFO: Mutex gwlib/list.c:134: 3 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [3] DEBUG: HTTP: No clients with requests, quitting. 2008-06-16 07:00:11 [4650] [3] DEBUG: Thread 3 (gw/smsbox.c:sendsms_thread) terminates. 2008-06-16 07:00:11 [4650] [8] DEBUG: HTTP: Status line: <HTTP/1.1 200 OK> 2008-06-16 07:00:11 [4650] [8] DEBUG: HTTP: Received response: 2008-06-16 07:00:11 [4650] [8] DEBUG: Octet string at 0x14b2840: ................AFTER SOME LINES.............. 2008-06-16 07:00:11 [4650] [8] DEBUG: Octet string dump ends. 2008-06-16 07:00:11 [4650] [5] INFO: Mutex gwlib/list.c:134: 6 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [5] INFO: Mutex gwlib/list.c:134: 74 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [5] INFO: Mutex gwlib/list.c:134: 3 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [2] INFO: Mutex gwlib/list.c:134: 6 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [2] INFO: Mutex gwlib/list.c:134: 1 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread) terminates. 2008-06-16 07:00:11 [4650] [2] INFO: Mutex gwlib/list.c:134: 3 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [1] INFO: Mutex gwlib/list.c:134: 784 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [0] INFO: Mutex gwlib/list.c:134: 3 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) terminates. 2008-06-16 07:00:11 [4650] [1] INFO: Mutex gwlib/list.c:134: 3 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [0] DEBUG: Waiting for 4 (gw/smsbox.c:obey_request_thread) to terminate 2008-06-16 07:00:11 [4650] [4] DEBUG: Thread 4 (gw/smsbox.c:obey_request_thread) terminates. 2008-06-16 07:00:11 [4650] [4] INFO: Mutex gwlib/list.c:134: 3 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [0] DEBUG: Waiting for 5 (gw/smsbox.c:url_result_thread) to terminate 2008-06-16 07:00:11 [4650] [5] DEBUG: Thread 5 (gw/smsbox.c:url_result_thread) terminates. 2008-06-16 07:00:11 [4650] [5] INFO: Mutex gwlib/list.c:134: 3 locks, 0 collisions. 2008-06-16 07:00:11 [4650] [0] DEBUG: Waiting for 6 (gw/smsbox.c:http_queue_thread) to terminate 2008-06-16 07:00:11 [4650] [6] DEBUG: Thread 6 (gw/smsbox.c:http_queue_thread) terminates. 2008-06-16 07:00:11 [4650] [6] INFO: Mutex gwlib/list.c:134: 3 locks, 0 collisions. ...............AFTER SOME LINES.............. 2008-06-16 07:29:25 [824] [0] DEBUG: Started thread 6 (gw/smsbox.c:http_queue_thread) 2008-06-16 07:29:25 [824] [0] ERROR: connect failed 2008-06-16 07:29:25 [824] [0] ERROR: System error 111: Connection refused 2008-06-16 07:29:25 [824] [0] ERROR: error connecting to server `127.0.0.1' at port `13001' 2008-06-16 07:29:25 [824] [0] PANIC: Couldn't connect to the bearerbox. 2008-06-16 07:29:25 [824] [0] PANIC: /usr/local/kannel/sbin/smsbox(gw_panic+0x182) [0x439bf2] 2008-06-16 07:29:25 [824] [0] PANIC: /usr/local/kannel/sbin/smsbox(main+0xc94) [0x40e9e4] 2008-06-16 07:29:25 [824] [0] PANIC: /lib64/libc.so.6(__libc_start_main+0xf4) [0x3c56e1d8b4] 2008-06-16 07:29:25 [824] [0] PANIC: /usr/local/kannel/sbin/smsbox [0x40d759] 2008-06-16 07:29:26 [834] [0] INFO: Added logfile `/var/log/kannel/smsbox.log' with level `0'. 2008-06-16 07:29:26 [834] [0] INFO: Service global sender set as '13013' -------------------------------- access log ------------------------- 2008-06-16 07:00:10 send-SMS request added - sender:kanneluser:soumya 67.228.127.18 target:2348033792039 request: 'test..... .' 2008-06-16 07:00:10 send-SMS request added - sender:kanneluser:soumya 67.228.127.18 target:2347032691005 request: 'test..... .' 2008-06-16 07:00:10 send-SMS request added - sender:kanneluser:soumya 67.228.127.18 target:2348023848341 request: 'test..... .' 2008-06-16 07:00:10 send-SMS request added - sender:kanneluser:soumya 67.228.127.18 target:2348083425606 request: 'test..... .' 2008-06-16 07:00:10 send-SMS request added - sender:kanneluser:soumya 67.228.127.18 target:2348033576155 request: 'test..... .' 2008-06-16 07:00:10 Sent SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2348033792039] [flags:-1:0:-1:-1:31] [msg:10:test......] [udh:0:] 2008-06-16 07:00:10 DLR SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2348033792039] [flags:-1:-1:-1:-1:8] [msg:4:ACK/] [udh:0:] 2008-06-16 07:00:10 Sent SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2347032691005] [flags:-1:0:-1:-1:31] [msg:10:test......] [udh:0:] 2008-06-16 07:00:10 DLR SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2347032691005] [flags:-1:-1:-1:-1:8] [msg:4:ACK/] [udh:0:] 2008-06-16 07:00:11 Sent SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2348023848341] [flags:-1:0:-1:-1:31] [msg:10:test......] [udh:0:] 2008-06-16 07:00:11 DLR SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2348023848341] [flags:-1:-1:-1:- 1:8] [msg:4:ACK/] [udh:0:] 2008-06-16 07:00:11 Log begins 2008-06-16 07:00:11 Log ends 2008-06-16 07:00:11 Log begins 2008-06-16 07:07:15 DLR SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2347032691005] [flags:-1:-1:-1:-1:1] [msg:132:id:8dcd5205-124c-4f78-a917-83a6aecf1fb5 sub:000 dlvrd:001 submit date:080616173219 done date:080616173924 stat:DELIVRD err:000 text:] [udh:0:] 2008-06-16 07:07:16 DLR SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2348033792039] [flags:-1:-1:-1:-1:1] [msg:132:id:c8c87763-2f05-4ef0-a28c-f28692b49955 sub:000 dlvrd:001 submit date:080616173219 done date:080616173926 stat:DELIVRD err:000 text:] [udh:0:] 2008-06-16 07:29:25 send-SMS request added - sender:kanneluser:soumya 67.228.127.18 target:2348033792039 request: 'test2...' 2008-06-16 07:29:25 send-SMS request added - sender:kanneluser:soumya 67.228.127.18 target:2347032691005 request: 'test2...' 2008-06-16 07:29:25 send-SMS request added - sender:kanneluser:soumya 67.228.127.18 target:2348023848341 request: 'test2...' 2008-06-16 07:29:25 send-SMS request added - sender:kanneluser:soumya 67.228.127.18 target:2348083425606 request: 'test2...' 2008-06-16 07:29:25 Log begins 2008-06-16 07:29:25 Log begins 2008-06-16 07:29:26 Log begins 2008-06-16 07:29:28 Sent SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2348023848341] [flags:-1:0:-1:-1:31] [msg:8:test2...] [udh:0:] 2008-06-16 07:29:28 DLR SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2348023848341] [flags:-1:-1:-1:-1:8] [msg:4:ACK/] [udh:0:] 2008-06-16 07:29:29 DLR SMS [SMSC:route1] [SVC:kanneluser] [ACT:] [BINF:] [from:soumya] [to:2348023848341] [flags:-1:-1:-1:-1:2] [msg:132:id:fadd0996-00b1-416a-977d-1d70704e4296 sub:000 dlvrd:000 submit date:080616180138 done date:080616180138 stat:UNDELIV err:000 text:] [udh:0:]
