Routing failed, re-queued

View: New views
7 Messages — Rating Filter:   Alert me  

Routing failed, re-queued

by digiTonik :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

I am attempting to test a connection to my smsc by sending a sample message.

http://127.0.0.1:13013/cgi-bin/sendsms?username=test&password=test123&to=251911127145&test=whyisthisnotworking

bearerbox receives the message from smsbox however it continues to display the message "Routing failed, re-queued"

Here are my configurations and log file trace

conf
-----
#--------------------------------------------------------------------------
#CORE
#--------------------------------------------------------------------------
group = core
admin-port = 13002
smsbox-port = 13003
admin-password = test123
admin-deny-ip = "*.*.*.*"
log-file = "/var/log/kannel/etc_bearerbox.log"
box-deny-ip = "*.*.*.*"
box-allow-ip = "127.0.0.1;"

#-------------------------
#SMSBOX (pg. 72)
#
#-------------------------
group = smsbox
bearerbox-host = 127.0.0.1
sendsms-port = 13013
global-sender = 13013

#-------------------------
#SMSC Connection
#-------------------------
group = smsc
smsc = smpp
smsc-id = etcSMSC
host = ***.***.**.***
port = ****
smsc-username = "user"
smsc-password = passwd
system-type = "VMA"


#--------------------------------------------
#SENDSMS-User (one or more)
# sms push out to a user
# required to send an HTTP response to a user
#--------------------------------------------
group = sendsms-user
username = test
password = test123


smsbox log
===========
2009-11-05 15:48:59 [23674] [0] INFO: Service global sender set as '13013'
2009-11-05 15:48:59 [23674] [0] INFO: HTTP: Opening server at port 13013.
2009-11-05 15:48:59 [23674] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps to pid 23674.
2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 1 (gwlib/fdset.c:poller)
2009-11-05 15:48:59 [23674] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread) maps to pid 23674.
2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 2 (gwlib/http.c:server_thread)
2009-11-05 15:48:59 [23674] [0] INFO: Set up send sms service at port 13013
2009-11-05 15:48:59 [23674] [3] DEBUG: Thread 3 (gw/smsbox.c:sendsms_thread) maps to pid 23674.
2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 3 (gw/smsbox.c:sendsms_thread)
2009-11-05 15:48:59 [23674] [0] DEBUG: ----------------------------------------------
2009-11-05 15:48:59 [23674] [0] DEBUG: Kannel smsbox version 1.4.3 starting
2009-11-05 15:48:59 [23674] [0] DEBUG:   dumping group (sendsms-user):
2009-11-05 15:48:59 [23674] [0] DEBUG:     <group> = <sendsms-user>
2009-11-05 15:48:59 [23674] [0] DEBUG:     <username> = <test>
2009-11-05 15:48:59 [23674] [0] DEBUG:     <password> = <test123>
2009-11-05 15:48:59 [23674] [4] DEBUG: Thread 4 (gw/smsbox.c:obey_request_thread) maps to pid 23674.
2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 4 (gw/smsbox.c:obey_request_thread)
2009-11-05 15:48:59 [23674] [5] DEBUG: Thread 5 (gw/smsbox.c:url_result_thread) maps to pid 23674.
2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 5 (gw/smsbox.c:url_result_thread)
2009-11-05 15:48:59 [23674] [6] DEBUG: Thread 6 (gw/smsbox.c:http_queue_thread) maps to pid 23674.
2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 6 (gw/smsbox.c:http_queue_thread)
2009-11-05 15:48:59 [23674] [0] INFO: Connected to bearerbox at 127.0.0.1 port 13003.
2009-11-05 15:48:59 [23674] [7] DEBUG: Thread 7 (gw/heartbeat.c:heartbeat_thread) maps to pid 23674.
2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 7 (gw/heartbeat.c:heartbeat_thread)
2009-11-05 15:50:13 [23674] [2] DEBUG: HTTP: Creating HTTPClient for `127.0.0.1'.
2009-11-05 15:50:13 [23674] [2] DEBUG: HTTP: Created HTTPClient area 0x9d9ffe8.
2009-11-05 15:50:13 [23674] [3] INFO: smsbox: Got HTTP request </cgi-bin/sendsms> from <127.0.0.1>
2009-11-05 15:50:13 [23674] [3] INFO: sendsms used by <test>
2009-11-05 15:50:13 [23674] [3] INFO: sendsms sender:<test:13013> (127.0.0.1) to:<251911127145> msg:<whyisthisnotworking>
2009-11-05 15:50:13 [23674] [3] DEBUG: Stored UUID 307e3833-5026-4577-89aa-67c53d1fd384
2009-11-05 15:50:13 [23674] [3] DEBUG: message length 19, sending 1 messages
2009-11-05 15:50:13 [23674] [3] DEBUG: Status: 202 Answer: <Sent.>
2009-11-05 15:50:13 [23674] [3] DEBUG: Delayed reply - wait for bearerbox
2009-11-05 15:50:13 [23674] [0] DEBUG: Got ACK (3) of 307e3833-5026-4577-89aa-67c53d1fd384


bearerbox log
===========
2009-11-05 15:48:17 [23664] [0] INFO: ----------------------------------------
2009-11-05 15:48:17 [23664] [0] INFO: Kannel bearerbox II version 1.4.3 starting
2009-11-05 15:48:17 [23664] [0] INFO: MAIN: Start-up done, entering mainloop
2009-11-05 15:48:17 [23664] [7] DEBUG: Thread 7 (gw/bb_smscconn.c:sms_router) maps to pid 23664.
2009-11-05 15:48:59 [23664] [5] INFO: Client connected from <127.0.0.1>
2009-11-05 15:48:59 [23664] [8] DEBUG: Thread 8 (gw/bb_boxc.c:function) maps to pid 23664.
2009-11-05 15:48:59 [23664] [9] DEBUG: Thread 9 (gw/bb_boxc.c:boxc_sender) maps to pid 23664.
2009-11-05 15:48:59 [23664] [8] DEBUG: Started thread 9 (gw/bb_boxc.c:boxc_sender)
2009-11-05 15:48:59 [23664] [5] DEBUG: Started thread 8 (gw/bb_boxc.c:function)
2009-11-05 15:50:13 [23664] [8] DEBUG: boxc_receiver: sms received
2009-11-05 15:50:13 [23664] [8] DEBUG: send_msg: sending msg to box: <127.0.0.1>
2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message (0x8eb7e40 vs 0x8eb7e40)
2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message (0x8eb7e40 vs 0x8eb7e40)
2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: gwlist_len = 1
2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message (0x8eb7e40 vs 0x8eb7e40)
2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message (0x8eb7e40 vs 0x8eb7e40)
2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: gwlist_len = 1
2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: handling message (0x8eb7e40 vs 0x8eb7e40)
2009-11-05 15:50:43 [23664] [7] DEBUG: Routing failed, re-queued.
2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: handling message (0x8eb7e40 vs 0x8eb7e40)
2009-11-05 15:50:43 [23664] [7] DEBUG: Routing failed, re-queued.
2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: time to sleep 30.00 secs.

Re: Routing failed, re-queued

by Nikos Balkanas-2 :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

Hi,

This is because your SMSc is never started, and bb doesn't know where to
send the SMS. It doesn't even attempt to start it. Are you sure you are
giving it the correct conf file?

BR,
Nikos
----- Original Message -----
From: "digiTonik" <MABekele@...>
To: <users@...>
Sent: Friday, November 06, 2009 9:38 AM
Subject: Routing failed, re-queued


>
> I am attempting to test a connection to my smsc by sending a sample
> message.
>
> http://127.0.0.1:13013/cgi-bin/sendsms?username=test&password=test123&to=251911127145&test=whyisthisnotworking
>
> bearerbox receives the message from smsbox however it continues to display
> the message "Routing failed, re-queued"
>
> Here are my configurations and log file trace
>
> conf
> -----
> #--------------------------------------------------------------------------
> #CORE
> #--------------------------------------------------------------------------
> group = core
> admin-port = 13002
> smsbox-port = 13003
> admin-password = test123
> admin-deny-ip = "*.*.*.*"
> log-file = "/var/log/kannel/etc_bearerbox.log"
> box-deny-ip = "*.*.*.*"
> box-allow-ip = "127.0.0.1;"
>
> #-------------------------
> #SMSBOX (pg. 72)
> #
> #-------------------------
> group = smsbox
> bearerbox-host = 127.0.0.1
> sendsms-port = 13013
> global-sender = 13013
>
> #-------------------------
> #SMSC Connection
> #-------------------------
> group = smsc
> smsc = smpp
> smsc-id = etcSMSC
> host = ***.***.**.***
> port = ****
> smsc-username = "user"
> smsc-password = passwd
> system-type = "VMA"
>
>
> #--------------------------------------------
> #SENDSMS-User (one or more)
> # sms push out to a user
> # required to send an HTTP response to a user
> #--------------------------------------------
> group = sendsms-user
> username = test
> password = test123
>
>
> smsbox log
> ===========
> 2009-11-05 15:48:59 [23674] [0] INFO: Service global sender set as '13013'
> 2009-11-05 15:48:59 [23674] [0] INFO: HTTP: Opening server at port 13013.
> 2009-11-05 15:48:59 [23674] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
> maps
> to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 1
> (gwlib/fdset.c:poller)
> 2009-11-05 15:48:59 [23674] [2] DEBUG: Thread 2
> (gwlib/http.c:server_thread)
> maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 2
> (gwlib/http.c:server_thread)
> 2009-11-05 15:48:59 [23674] [0] INFO: Set up send sms service at port
> 13013
> 2009-11-05 15:48:59 [23674] [3] DEBUG: Thread 3
> (gw/smsbox.c:sendsms_thread)
> maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 3
> (gw/smsbox.c:sendsms_thread)
> 2009-11-05 15:48:59 [23674] [0] DEBUG:
> ----------------------------------------------
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Kannel smsbox version 1.4.3
> starting
> 2009-11-05 15:48:59 [23674] [0] DEBUG:   dumping group (sendsms-user):
> 2009-11-05 15:48:59 [23674] [0] DEBUG:     <group> = <sendsms-user>
> 2009-11-05 15:48:59 [23674] [0] DEBUG:     <username> = <test>
> 2009-11-05 15:48:59 [23674] [0] DEBUG:     <password> = <test123>
> 2009-11-05 15:48:59 [23674] [4] DEBUG: Thread 4
> (gw/smsbox.c:obey_request_thread) maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 4
> (gw/smsbox.c:obey_request_thread)
> 2009-11-05 15:48:59 [23674] [5] DEBUG: Thread 5
> (gw/smsbox.c:url_result_thread) maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 5
> (gw/smsbox.c:url_result_thread)
> 2009-11-05 15:48:59 [23674] [6] DEBUG: Thread 6
> (gw/smsbox.c:http_queue_thread) maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 6
> (gw/smsbox.c:http_queue_thread)
> 2009-11-05 15:48:59 [23674] [0] INFO: Connected to bearerbox at 127.0.0.1
> port 13003.
> 2009-11-05 15:48:59 [23674] [7] DEBUG: Thread 7
> (gw/heartbeat.c:heartbeat_thread) maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 7
> (gw/heartbeat.c:heartbeat_thread)
> 2009-11-05 15:50:13 [23674] [2] DEBUG: HTTP: Creating HTTPClient for
> `127.0.0.1'.
> 2009-11-05 15:50:13 [23674] [2] DEBUG: HTTP: Created HTTPClient area
> 0x9d9ffe8.
> 2009-11-05 15:50:13 [23674] [3] INFO: smsbox: Got HTTP request
> </cgi-bin/sendsms> from <127.0.0.1>
> 2009-11-05 15:50:13 [23674] [3] INFO: sendsms used by <test>
> 2009-11-05 15:50:13 [23674] [3] INFO: sendsms sender:<test:13013>
> (127.0.0.1) to:<251911127145> msg:<whyisthisnotworking>
> 2009-11-05 15:50:13 [23674] [3] DEBUG: Stored UUID
> 307e3833-5026-4577-89aa-67c53d1fd384
> 2009-11-05 15:50:13 [23674] [3] DEBUG: message length 19, sending 1
> messages
> 2009-11-05 15:50:13 [23674] [3] DEBUG: Status: 202 Answer: <Sent.>
> 2009-11-05 15:50:13 [23674] [3] DEBUG: Delayed reply - wait for bearerbox
> 2009-11-05 15:50:13 [23674] [0] DEBUG: Got ACK (3) of
> 307e3833-5026-4577-89aa-67c53d1fd384
>
>
> bearerbox log
> ===========
> 2009-11-05 15:48:17 [23664] [0] INFO:
> ----------------------------------------
> 2009-11-05 15:48:17 [23664] [0] INFO: Kannel bearerbox II version 1.4.3
> starting
> 2009-11-05 15:48:17 [23664] [0] INFO: MAIN: Start-up done, entering
> mainloop
> 2009-11-05 15:48:17 [23664] [7] DEBUG: Thread 7
> (gw/bb_smscconn.c:sms_router) maps to pid 23664.
> 2009-11-05 15:48:59 [23664] [5] INFO: Client connected from <127.0.0.1>
> 2009-11-05 15:48:59 [23664] [8] DEBUG: Thread 8 (gw/bb_boxc.c:function)
> maps
> to pid 23664.
> 2009-11-05 15:48:59 [23664] [9] DEBUG: Thread 9 (gw/bb_boxc.c:boxc_sender)
> maps to pid 23664.
> 2009-11-05 15:48:59 [23664] [8] DEBUG: Started thread 9
> (gw/bb_boxc.c:boxc_sender)
> 2009-11-05 15:48:59 [23664] [5] DEBUG: Started thread 8
> (gw/bb_boxc.c:function)
> 2009-11-05 15:50:13 [23664] [8] DEBUG: boxc_receiver: sms received
> 2009-11-05 15:50:13 [23664] [8] DEBUG: send_msg: sending msg to box:
> <127.0.0.1>
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: time to sleep 30.00
> secs.
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: gwlist_len = 1
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: time to sleep 30.00
> secs.
> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: gwlist_len = 1
> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:43 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:43 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: time to sleep 30.00
> secs.
> --
> View this message in context:
> http://old.nabble.com/Routing-failed%2C-re-queued-tp26227897p26227897.html
> Sent from the Kannel - User mailing list archive at Nabble.com.
>



Re: Routing failed, re-queued

by digiTonik :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

I am unable to ping the smsc from my machine. I was trying to eliminate the possibility that I entered the wrong configuration in kannel.

Nikos Balkanas-2 wrote:
Hi,

This is because your SMSc is never started, and bb doesn't know where to
send the SMS. It doesn't even attempt to start it. Are you sure you are
giving it the correct conf file?

BR,
Nikos
----- Original Message -----
From: "digiTonik" <MABekele@gmail.com>
To: <users@kannel.org>
Sent: Friday, November 06, 2009 9:38 AM
Subject: Routing failed, re-queued


>
> I am attempting to test a connection to my smsc by sending a sample
> message.
>
> http://127.0.0.1:13013/cgi-bin/sendsms?username=test&password=test123&to=251911127145&test=whyisthisnotworking
>
> bearerbox receives the message from smsbox however it continues to display
> the message "Routing failed, re-queued"
>
> Here are my configurations and log file trace
>
> conf
> -----
> #--------------------------------------------------------------------------
> #CORE
> #--------------------------------------------------------------------------
> group = core
> admin-port = 13002
> smsbox-port = 13003
> admin-password = test123
> admin-deny-ip = "*.*.*.*"
> log-file = "/var/log/kannel/etc_bearerbox.log"
> box-deny-ip = "*.*.*.*"
> box-allow-ip = "127.0.0.1;"
>
> #-------------------------
> #SMSBOX (pg. 72)
> #
> #-------------------------
> group = smsbox
> bearerbox-host = 127.0.0.1
> sendsms-port = 13013
> global-sender = 13013
>
> #-------------------------
> #SMSC Connection
> #-------------------------
> group = smsc
> smsc = smpp
> smsc-id = etcSMSC
> host = ***.***.**.***
> port = ****
> smsc-username = "user"
> smsc-password = passwd
> system-type = "VMA"
>
>
> #--------------------------------------------
> #SENDSMS-User (one or more)
> # sms push out to a user
> # required to send an HTTP response to a user
> #--------------------------------------------
> group = sendsms-user
> username = test
> password = test123
>
>
> smsbox log
> ===========
> 2009-11-05 15:48:59 [23674] [0] INFO: Service global sender set as '13013'
> 2009-11-05 15:48:59 [23674] [0] INFO: HTTP: Opening server at port 13013.
> 2009-11-05 15:48:59 [23674] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
> maps
> to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 1
> (gwlib/fdset.c:poller)
> 2009-11-05 15:48:59 [23674] [2] DEBUG: Thread 2
> (gwlib/http.c:server_thread)
> maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 2
> (gwlib/http.c:server_thread)
> 2009-11-05 15:48:59 [23674] [0] INFO: Set up send sms service at port
> 13013
> 2009-11-05 15:48:59 [23674] [3] DEBUG: Thread 3
> (gw/smsbox.c:sendsms_thread)
> maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 3
> (gw/smsbox.c:sendsms_thread)
> 2009-11-05 15:48:59 [23674] [0] DEBUG:
> ----------------------------------------------
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Kannel smsbox version 1.4.3
> starting
> 2009-11-05 15:48:59 [23674] [0] DEBUG:   dumping group (sendsms-user):
> 2009-11-05 15:48:59 [23674] [0] DEBUG:     <group> = <sendsms-user>
> 2009-11-05 15:48:59 [23674] [0] DEBUG:     <username> = <test>
> 2009-11-05 15:48:59 [23674] [0] DEBUG:     <password> = <test123>
> 2009-11-05 15:48:59 [23674] [4] DEBUG: Thread 4
> (gw/smsbox.c:obey_request_thread) maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 4
> (gw/smsbox.c:obey_request_thread)
> 2009-11-05 15:48:59 [23674] [5] DEBUG: Thread 5
> (gw/smsbox.c:url_result_thread) maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 5
> (gw/smsbox.c:url_result_thread)
> 2009-11-05 15:48:59 [23674] [6] DEBUG: Thread 6
> (gw/smsbox.c:http_queue_thread) maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 6
> (gw/smsbox.c:http_queue_thread)
> 2009-11-05 15:48:59 [23674] [0] INFO: Connected to bearerbox at 127.0.0.1
> port 13003.
> 2009-11-05 15:48:59 [23674] [7] DEBUG: Thread 7
> (gw/heartbeat.c:heartbeat_thread) maps to pid 23674.
> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 7
> (gw/heartbeat.c:heartbeat_thread)
> 2009-11-05 15:50:13 [23674] [2] DEBUG: HTTP: Creating HTTPClient for
> `127.0.0.1'.
> 2009-11-05 15:50:13 [23674] [2] DEBUG: HTTP: Created HTTPClient area
> 0x9d9ffe8.
> 2009-11-05 15:50:13 [23674] [3] INFO: smsbox: Got HTTP request
> </cgi-bin/sendsms> from <127.0.0.1>
> 2009-11-05 15:50:13 [23674] [3] INFO: sendsms used by <test>
> 2009-11-05 15:50:13 [23674] [3] INFO: sendsms sender:<test:13013>
> (127.0.0.1) to:<251911127145> msg:<whyisthisnotworking>
> 2009-11-05 15:50:13 [23674] [3] DEBUG: Stored UUID
> 307e3833-5026-4577-89aa-67c53d1fd384
> 2009-11-05 15:50:13 [23674] [3] DEBUG: message length 19, sending 1
> messages
> 2009-11-05 15:50:13 [23674] [3] DEBUG: Status: 202 Answer: <Sent.>
> 2009-11-05 15:50:13 [23674] [3] DEBUG: Delayed reply - wait for bearerbox
> 2009-11-05 15:50:13 [23674] [0] DEBUG: Got ACK (3) of
> 307e3833-5026-4577-89aa-67c53d1fd384
>
>
> bearerbox log
> ===========
> 2009-11-05 15:48:17 [23664] [0] INFO:
> ----------------------------------------
> 2009-11-05 15:48:17 [23664] [0] INFO: Kannel bearerbox II version 1.4.3
> starting
> 2009-11-05 15:48:17 [23664] [0] INFO: MAIN: Start-up done, entering
> mainloop
> 2009-11-05 15:48:17 [23664] [7] DEBUG: Thread 7
> (gw/bb_smscconn.c:sms_router) maps to pid 23664.
> 2009-11-05 15:48:59 [23664] [5] INFO: Client connected from <127.0.0.1>
> 2009-11-05 15:48:59 [23664] [8] DEBUG: Thread 8 (gw/bb_boxc.c:function)
> maps
> to pid 23664.
> 2009-11-05 15:48:59 [23664] [9] DEBUG: Thread 9 (gw/bb_boxc.c:boxc_sender)
> maps to pid 23664.
> 2009-11-05 15:48:59 [23664] [8] DEBUG: Started thread 9
> (gw/bb_boxc.c:boxc_sender)
> 2009-11-05 15:48:59 [23664] [5] DEBUG: Started thread 8
> (gw/bb_boxc.c:function)
> 2009-11-05 15:50:13 [23664] [8] DEBUG: boxc_receiver: sms received
> 2009-11-05 15:50:13 [23664] [8] DEBUG: send_msg: sending msg to box:
> <127.0.0.1>
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: time to sleep 30.00
> secs.
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: gwlist_len = 1
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: time to sleep 30.00
> secs.
> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: gwlist_len = 1
> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:43 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: handling message
> (0x8eb7e40 vs 0x8eb7e40)
> 2009-11-05 15:50:43 [23664] [7] DEBUG: Routing failed, re-queued.
> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: time to sleep 30.00
> secs.
> --
> View this message in context:
> http://old.nabble.com/Routing-failed%2C-re-queued-tp26227897p26227897.html
> Sent from the Kannel - User mailing list archive at Nabble.com.
>


Re: Routing failed, re-queued

by Nikos Balkanas :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

Hi,

Don't try pinging. There may be a firewall in between. Instead try:

telnet <IP> <port>

And see if that reponds. If not, call them.

But the question is not even that. Bb doesn't even attempt to start it.
Seems it doesn't read the right configuration. Please post the whole startup
logs.

PS: Your posted SMSc configuration seems fine.

BR,
Nikos
----- Original Message -----
From: "digiTonik" <MABekele@...>
To: <users@...>
Sent: Friday, November 06, 2009 12:03 PM
Subject: Re: Routing failed, re-queued


>
> I am unable to ping the smsc from my machine. I was trying to eliminate
> the
> possibility that I entered the wrong configuration in kannel.
>
>
> Nikos Balkanas-2 wrote:
>>
>> Hi,
>>
>> This is because your SMSc is never started, and bb doesn't know where to
>> send the SMS. It doesn't even attempt to start it. Are you sure you are
>> giving it the correct conf file?
>>
>> BR,
>> Nikos
>> ----- Original Message -----
>> From: "digiTonik" <MABekele@...>
>> To: <users@...>
>> Sent: Friday, November 06, 2009 9:38 AM
>> Subject: Routing failed, re-queued
>>
>>
>>>
>>> I am attempting to test a connection to my smsc by sending a sample
>>> message.
>>>
>>> http://127.0.0.1:13013/cgi-bin/sendsms?username=test&password=test123&to=251911127145&test=whyisthisnotworking
>>>
>>> bearerbox receives the message from smsbox however it continues to
>>> display
>>> the message "Routing failed, re-queued"
>>>
>>> Here are my configurations and log file trace
>>>
>>> conf
>>> -----
>>> #--------------------------------------------------------------------------
>>> #CORE
>>> #--------------------------------------------------------------------------
>>> group = core
>>> admin-port = 13002
>>> smsbox-port = 13003
>>> admin-password = test123
>>> admin-deny-ip = "*.*.*.*"
>>> log-file = "/var/log/kannel/etc_bearerbox.log"
>>> box-deny-ip = "*.*.*.*"
>>> box-allow-ip = "127.0.0.1;"
>>>
>>> #-------------------------
>>> #SMSBOX (pg. 72)
>>> #
>>> #-------------------------
>>> group = smsbox
>>> bearerbox-host = 127.0.0.1
>>> sendsms-port = 13013
>>> global-sender = 13013
>>>
>>> #-------------------------
>>> #SMSC Connection
>>> #-------------------------
>>> group = smsc
>>> smsc = smpp
>>> smsc-id = etcSMSC
>>> host = ***.***.**.***
>>> port = ****
>>> smsc-username = "user"
>>> smsc-password = passwd
>>> system-type = "VMA"
>>>
>>>
>>> #--------------------------------------------
>>> #SENDSMS-User (one or more)
>>> # sms push out to a user
>>> # required to send an HTTP response to a user
>>> #--------------------------------------------
>>> group = sendsms-user
>>> username = test
>>> password = test123
>>>
>>>
>>> smsbox log
>>> ===========
>>> 2009-11-05 15:48:59 [23674] [0] INFO: Service global sender set as
>>> '13013'
>>> 2009-11-05 15:48:59 [23674] [0] INFO: HTTP: Opening server at port
>>> 13013.
>>> 2009-11-05 15:48:59 [23674] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
>>> maps
>>> to pid 23674.
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 1
>>> (gwlib/fdset.c:poller)
>>> 2009-11-05 15:48:59 [23674] [2] DEBUG: Thread 2
>>> (gwlib/http.c:server_thread)
>>> maps to pid 23674.
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 2
>>> (gwlib/http.c:server_thread)
>>> 2009-11-05 15:48:59 [23674] [0] INFO: Set up send sms service at port
>>> 13013
>>> 2009-11-05 15:48:59 [23674] [3] DEBUG: Thread 3
>>> (gw/smsbox.c:sendsms_thread)
>>> maps to pid 23674.
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 3
>>> (gw/smsbox.c:sendsms_thread)
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG:
>>> ----------------------------------------------
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG: Kannel smsbox version 1.4.3
>>> starting
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG:   dumping group (sendsms-user):
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG:     <group> = <sendsms-user>
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG:     <username> = <test>
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG:     <password> = <test123>
>>> 2009-11-05 15:48:59 [23674] [4] DEBUG: Thread 4
>>> (gw/smsbox.c:obey_request_thread) maps to pid 23674.
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 4
>>> (gw/smsbox.c:obey_request_thread)
>>> 2009-11-05 15:48:59 [23674] [5] DEBUG: Thread 5
>>> (gw/smsbox.c:url_result_thread) maps to pid 23674.
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 5
>>> (gw/smsbox.c:url_result_thread)
>>> 2009-11-05 15:48:59 [23674] [6] DEBUG: Thread 6
>>> (gw/smsbox.c:http_queue_thread) maps to pid 23674.
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 6
>>> (gw/smsbox.c:http_queue_thread)
>>> 2009-11-05 15:48:59 [23674] [0] INFO: Connected to bearerbox at
>>> 127.0.0.1
>>> port 13003.
>>> 2009-11-05 15:48:59 [23674] [7] DEBUG: Thread 7
>>> (gw/heartbeat.c:heartbeat_thread) maps to pid 23674.
>>> 2009-11-05 15:48:59 [23674] [0] DEBUG: Started thread 7
>>> (gw/heartbeat.c:heartbeat_thread)
>>> 2009-11-05 15:50:13 [23674] [2] DEBUG: HTTP: Creating HTTPClient for
>>> `127.0.0.1'.
>>> 2009-11-05 15:50:13 [23674] [2] DEBUG: HTTP: Created HTTPClient area
>>> 0x9d9ffe8.
>>> 2009-11-05 15:50:13 [23674] [3] INFO: smsbox: Got HTTP request
>>> </cgi-bin/sendsms> from <127.0.0.1>
>>> 2009-11-05 15:50:13 [23674] [3] INFO: sendsms used by <test>
>>> 2009-11-05 15:50:13 [23674] [3] INFO: sendsms sender:<test:13013>
>>> (127.0.0.1) to:<251911127145> msg:<whyisthisnotworking>
>>> 2009-11-05 15:50:13 [23674] [3] DEBUG: Stored UUID
>>> 307e3833-5026-4577-89aa-67c53d1fd384
>>> 2009-11-05 15:50:13 [23674] [3] DEBUG: message length 19, sending 1
>>> messages
>>> 2009-11-05 15:50:13 [23674] [3] DEBUG: Status: 202 Answer: <Sent.>
>>> 2009-11-05 15:50:13 [23674] [3] DEBUG: Delayed reply - wait for
>>> bearerbox
>>> 2009-11-05 15:50:13 [23674] [0] DEBUG: Got ACK (3) of
>>> 307e3833-5026-4577-89aa-67c53d1fd384
>>>
>>>
>>> bearerbox log
>>> ===========
>>> 2009-11-05 15:48:17 [23664] [0] INFO:
>>> ----------------------------------------
>>> 2009-11-05 15:48:17 [23664] [0] INFO: Kannel bearerbox II version 1.4.3
>>> starting
>>> 2009-11-05 15:48:17 [23664] [0] INFO: MAIN: Start-up done, entering
>>> mainloop
>>> 2009-11-05 15:48:17 [23664] [7] DEBUG: Thread 7
>>> (gw/bb_smscconn.c:sms_router) maps to pid 23664.
>>> 2009-11-05 15:48:59 [23664] [5] INFO: Client connected from <127.0.0.1>
>>> 2009-11-05 15:48:59 [23664] [8] DEBUG: Thread 8 (gw/bb_boxc.c:function)
>>> maps
>>> to pid 23664.
>>> 2009-11-05 15:48:59 [23664] [9] DEBUG: Thread 9
>>> (gw/bb_boxc.c:boxc_sender)
>>> maps to pid 23664.
>>> 2009-11-05 15:48:59 [23664] [8] DEBUG: Started thread 9
>>> (gw/bb_boxc.c:boxc_sender)
>>> 2009-11-05 15:48:59 [23664] [5] DEBUG: Started thread 8
>>> (gw/bb_boxc.c:function)
>>> 2009-11-05 15:50:13 [23664] [8] DEBUG: boxc_receiver: sms received
>>> 2009-11-05 15:50:13 [23664] [8] DEBUG: send_msg: sending msg to box:
>>> <127.0.0.1>
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
>>> (0x8eb7e40 vs 0x8eb7e40)
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
>>> (0x8eb7e40 vs 0x8eb7e40)
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: time to sleep 30.00
>>> secs.
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: gwlist_len = 1
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
>>> (0x8eb7e40 vs 0x8eb7e40)
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: handling message
>>> (0x8eb7e40 vs 0x8eb7e40)
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: Routing failed, re-queued.
>>> 2009-11-05 15:50:13 [23664] [7] DEBUG: sms_router: time to sleep 30.00
>>> secs.
>>> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: gwlist_len = 1
>>> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: handling message
>>> (0x8eb7e40 vs 0x8eb7e40)
>>> 2009-11-05 15:50:43 [23664] [7] DEBUG: Routing failed, re-queued.
>>> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: handling message
>>> (0x8eb7e40 vs 0x8eb7e40)
>>> 2009-11-05 15:50:43 [23664] [7] DEBUG: Routing failed, re-queued.
>>> 2009-11-05 15:50:43 [23664] [7] DEBUG: sms_router: time to sleep 30.00
>>> secs.
>>> --
>>> View this message in context:
>>> http://old.nabble.com/Routing-failed%2C-re-queued-tp26227897p26227897.html
>>> Sent from the Kannel - User mailing list archive at Nabble.com.
>>>
>>
>>
>>
>>
>
> --
> View this message in context:
> http://old.nabble.com/Routing-failed%2C-re-queued-tp26227897p26228806.html
> Sent from the Kannel - User mailing list archive at Nabble.com.
>
>



Re: Routing failed, re-queued

by digiTonik :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

I am unable to telnet to the address.

Here is the full log:

bearerbox
=======
2009-11-06 13:31:05 [18052] [0] INFO: Added logfile `/var/log/kannel/bearerbox.log' with level `0'.
2009-11-06 13:31:05 [18052] [0] INFO: HTTP: Opening server at port 13002.
2009-11-06 13:31:05 [18052] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 1 (gwlib/fdset.c:poller)
2009-11-06 13:31:05 [18052] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread) maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 2 (gwlib/http.c:server_thread)
2009-11-06 13:31:05 [18052] [3] DEBUG: Thread 3 (gw/bb_http.c:httpadmin_run) maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 3 (gw/bb_http.c:httpadmin_run)
2009-11-06 13:31:05 [18052] [0] DEBUG: starting smsbox connection module
2009-11-06 13:31:05 [18052] [0] INFO: BOXC: 'smsbox-max-pending' not set, using default (100).
2009-11-06 13:31:05 [18052] [4] DEBUG: Thread 4 (gw/bb_boxc.c:sms_to_smsboxes) maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 4 (gw/bb_boxc.c:sms_to_smsboxes)
2009-11-06 13:31:05 [18052] [5] DEBUG: Thread 5 (gw/bb_boxc.c:smsboxc_run) maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 5 (gw/bb_boxc.c:smsboxc_run)
2009-11-06 13:31:05 [18052] [0] INFO: Set SMS resend frequency to 60 seconds.
2009-11-06 13:31:05 [18052] [0] INFO: SMS resend retry set to unlimited.
2009-11-06 13:31:05 [18052] [0] DEBUG: smsbox MO concatenated message handling enabled
2009-11-06 13:31:05 [18052] [0] INFO: DLR rerouting for smsc id <etcSMSC> disabled.
2009-11-06 13:31:05 [18052] [6] DEBUG: Thread 6 (gw/smsc/smsc_smpp.c:io_thread) maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 6 (gw/smsc/smsc_smpp.c:io_thread)
2009-11-06 13:31:05 [18052] [7] DEBUG: Thread 7 (gw/bb_smscconn.c:sms_router) maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 7 (gw/bb_smscconn.c:sms_router)
2009-11-06 13:31:05 [18052] [0] INFO: ----------------------------------------
2009-11-06 13:31:05 [18052] [0] INFO: Kannel bearerbox II version 1.4.3 starting
2009-11-06 13:31:05 [18052] [0] INFO: MAIN: Start-up done, entering mainloop
2009-11-06 13:31:50 [18052] [6] ERROR: connect failed
2009-11-06 13:31:50 [18052] [6] ERROR: System error 113: No route to host
2009-11-06 13:31:50 [18052] [6] ERROR: error connecting to server `***.***.**.***' at port `****'
2009-11-06 13:31:50 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to server.
2009-11-06 13:31:50 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to SMS center (retrying in 10 seconds).
2009-11-06 13:32:43 [18052] [5] INFO: Client connected from <127.0.0.1>
2009-11-06 13:32:43 [18052] [8] DEBUG: Thread 8 (gw/bb_boxc.c:function) maps to pid 18052.
2009-11-06 13:32:43 [18052] [9] DEBUG: Thread 9 (gw/bb_boxc.c:boxc_sender) maps to pid 18052.
2009-11-06 13:32:43 [18052] [8] DEBUG: Started thread 9 (gw/bb_boxc.c:boxc_sender)
2009-11-06 13:32:43 [18052] [5] DEBUG: Started thread 8 (gw/bb_boxc.c:function)
2009-11-06 13:33:14 [18052] [8] DEBUG: boxc_receiver: sms received
2009-11-06 13:33:14 [18052] [8] DEBUG: send_msg: sending msg to box: <127.0.0.1>
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message (0x8545850 vs 0x8545850)
2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message (0x8545850 vs 0x8545850)
2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: gwlist_len = 1
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message (0x8545850 vs 0x8545850)
2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message (0x8545850 vs 0x8545850)
2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: gwlist_len = 1
2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: handling message (0x8545850 vs 0x8545850)
2009-11-06 13:33:44 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: handling message (0x8545850 vs 0x8545850)
2009-11-06 13:33:44 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2009-11-06 13:33:56 [18052] [0] WARNING: Killing signal or HTTP admin command received, shutting down...
2009-11-06 13:33:56 [18052] [0] DEBUG: Shutting down Kannel...
2009-11-06 13:33:56 [18052] [0] DEBUG: shutting down smsc
2009-11-06 13:33:56 [18052] [0] DEBUG: Shutting down SMSCConn SMPP:***.***.**.***:****/****:user:VMA (slow)
2009-11-06 13:34:14 [18052] [7] DEBUG: sms_router: gwlist_len = 1
2009-11-06 13:34:14 [18052] [7] DEBUG: sms_router: handling message (0x8545850 vs 0x8545850)
2009-11-06 13:34:14 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:34:14 [18052] [7] DEBUG: Thread 7 (gw/bb_smscconn.c:sms_router) terminates.
2009-11-06 13:35:09 [18052] [6] ERROR: connect failed
2009-11-06 13:35:09 [18052] [6] ERROR: System error 110: Connection timed out
2009-11-06 13:35:09 [18052] [6] ERROR: error connecting to server `***.***.**.***' at port `****'
2009-11-06 13:35:09 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to server.
2009-11-06 13:35:09 [18052] [6] DEBUG: Thread 6 (gw/smsc/smsc_smpp.c:io_thread) terminates.
2009-11-06 13:35:09 [18052] [0] DEBUG: SMSCConn SMPP:***.***.**.***:****/****:user:VMA shut down.
2009-11-06 13:35:09 [18052] [0] DEBUG: shutting down udp
2009-11-06 13:35:09 [18052] [4] DEBUG: Thread 4 (gw/bb_boxc.c:sms_to_smsboxes) terminates.
2009-11-06 13:35:09 [18052] [9] DEBUG: send_msg: sending msg to box: <127.0.0.1>
2009-11-06 13:35:09 [18052] [9] DEBUG: Thread 9 (gw/bb_boxc.c:boxc_sender) terminates.
2009-11-06 13:35:10 [18052] [8] INFO: Connection closed by the box <127.0.0.1>
2009-11-06 13:35:10 [18052] [8] DEBUG: Thread 8 (gw/bb_boxc.c:function) terminates.
2009-11-06 13:35:10 [18052] [5] DEBUG: Thread 5 (gw/bb_boxc.c:smsboxc_run) terminates.
2009-11-06 13:35:10 [18052] [0] INFO: All flow threads have died, killing core
2009-11-06 13:35:10 [18052] [0] DEBUG: Waiting for 2 (gwlib/http.c:server_thread) to terminate
2009-11-06 13:35:10 [18052] [3] DEBUG: HTTP: No clients with requests, quitting.
2009-11-06 13:35:10 [18052] [3] DEBUG: Thread 3 (gw/bb_http.c:httpadmin_run) terminates.
2009-11-06 13:35:11 [18052] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread) terminates.
2009-11-06 13:35:11 [18052] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) terminates.
2009-11-06 13:35:11 [18052] [0] DEBUG: final clean-up for SMSCConn
2009-11-06 13:35:11 [18052] [0] DEBUG: smsbox MO concatenated message handling cleaned up
2009-11-06 13:35:11 [18052] [0] INFO: Total WDP messages: received 0, sent 0
2009-11-06 13:35:11 [18052] [0] INFO: Total SMS messages: received 0, sent 0
2009-11-06 13:35:11 [18052] [0] DEBUG: Immutable octet strings: 181.

smsbox
=====
2009-11-06 13:32:43 [18083] [0] INFO: Added logfile `/var/log/kannel/smsbox.log' with level `0'.
2009-11-06 13:32:43 [18083] [0] INFO: Service global sender set as '13013'
2009-11-06 13:32:43 [18083] [0] INFO: HTTP: Opening server at port 13013.
2009-11-06 13:32:43 [18083] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 1 (gwlib/fdset.c:poller)
2009-11-06 13:32:43 [18083] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 2 (gwlib/http.c:server_thread)
2009-11-06 13:32:43 [18083] [0] INFO: Set up send sms service at port 13013
2009-11-06 13:32:43 [18083] [3] DEBUG: Thread 3 (gw/smsbox.c:sendsms_thread) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 3 (gw/smsbox.c:sendsms_thread)
2009-11-06 13:32:43 [18083] [0] DEBUG: ----------------------------------------------
2009-11-06 13:32:43 [18083] [0] DEBUG: Kannel smsbox version 1.4.3 starting
2009-11-06 13:32:43 [18083] [0] DEBUG:   dumping group (sendsms-user):
2009-11-06 13:32:43 [18083] [0] DEBUG:     <group> = <sendsms-user>
2009-11-06 13:32:43 [18083] [0] DEBUG:     <username> = <test>
2009-11-06 13:32:43 [18083] [0] DEBUG:     <password> = <test123>
2009-11-06 13:32:43 [18083] [4] DEBUG: Thread 4 (gw/smsbox.c:obey_request_thread) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 4 (gw/smsbox.c:obey_request_thread)
2009-11-06 13:32:43 [18083] [5] DEBUG: Thread 5 (gw/smsbox.c:url_result_thread) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 5 (gw/smsbox.c:url_result_thread)
2009-11-06 13:32:43 [18083] [6] DEBUG: Thread 6 (gw/smsbox.c:http_queue_thread) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 6 (gw/smsbox.c:http_queue_thread)
2009-11-06 13:32:43 [18083] [0] INFO: Connected to bearerbox at 127.0.0.1 port 13003.
2009-11-06 13:32:43 [18083] [7] DEBUG: Thread 7 (gw/heartbeat.c:heartbeat_thread) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 7 (gw/heartbeat.c:heartbeat_thread)
2009-11-06 13:33:14 [18083] [2] DEBUG: HTTP: Creating HTTPClient for `127.0.0.1'.
2009-11-06 13:33:14 [18083] [2] DEBUG: HTTP: Created HTTPClient area 0x823e4d8.
2009-11-06 13:33:14 [18083] [3] INFO: smsbox: Got HTTP request </cgi-bin/sendsms> from <127.0.0.1>
2009-11-06 13:33:14 [18083] [3] INFO: sendsms used by <test>
2009-11-06 13:33:14 [18083] [3] INFO: sendsms sender:<test:13013> (127.0.0.1) to:<251911127145> msg:<whyisthisnotworking>
2009-11-06 13:33:14 [18083] [3] DEBUG: Stored UUID 7f51e7db-3020-493f-a301-0b6993878b11
2009-11-06 13:33:14 [18083] [3] DEBUG: message length 19, sending 1 messages
2009-11-06 13:33:14 [18083] [3] DEBUG: Status: 202 Answer: <Sent.>
2009-11-06 13:33:14 [18083] [3] DEBUG: Delayed reply - wait for bearerbox
2009-11-06 13:33:14 [18083] [0] DEBUG: Got ACK (3) of 7f51e7db-3020-493f-a301-0b6993878b11
2009-11-06 13:33:14 [18083] [0] DEBUG: HTTP: Resetting HTTPClient for `127.0.0.1'.
2009-11-06 13:34:14 [18083] [1] DEBUG: Timeout for fd:23 appeares.
2009-11-06 13:34:14 [18083] [1] DEBUG: HTTP: Destroying HTTPClient area 0x823e4d8.
2009-11-06 13:34:14 [18083] [1] DEBUG: HTTP: Destroying HTTPClient for `127.0.0.1'.
2009-11-06 13:35:09 [18083] [0] INFO: Bearerbox told us to die
2009-11-06 13:35:09 [18083] [0] INFO: Received (and handled?) 0 requests in 146 seconds (0.00 per second)
2009-11-06 13:35:09 [18083] [0] INFO: Kannel smsbox terminating.
2009-11-06 13:35:09 [18083] [7] DEBUG: Thread 7 (gw/heartbeat.c:heartbeat_thread) terminates.
2009-11-06 13:35:09 [18083] [0] DEBUG: Waiting for 2 (gwlib/http.c:server_thread) to terminate
2009-11-06 13:35:09 [18083] [3] DEBUG: HTTP: No clients with requests, quitting.
2009-11-06 13:35:09 [18083] [3] DEBUG: Thread 3 (gw/smsbox.c:sendsms_thread) terminates.
2009-11-06 13:35:10 [18083] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread) terminates.
2009-11-06 13:35:10 [18083] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) terminates.
2009-11-06 13:35:10 [18083] [0] DEBUG: Waiting for 4 (gw/smsbox.c:obey_request_thread) to terminate
2009-11-06 13:35:10 [18083] [6] DEBUG: Thread 6 (gw/smsbox.c:http_queue_thread) terminates.
2009-11-06 13:35:10 [18083] [4] DEBUG: Thread 4 (gw/smsbox.c:obey_request_thread) terminates.
2009-11-06 13:35:10 [18083] [0] DEBUG: Waiting for 5 (gw/smsbox.c:url_result_thread) to terminate
2009-11-06 13:35:10 [18083] [5] DEBUG: Thread 5 (gw/smsbox.c:url_result_thread) terminates.
2009-11-06 13:35:10 [18083] [0] DEBUG: Immutable octet strings: 147.


Nikos Balkanas wrote:
Hi,

Don't try pinging. There may be a firewall in between. Instead try:

telnet <IP> <port>

And see if that reponds. If not, call them.

But the question is not even that. Bb doesn't even attempt to start it.
Seems it doesn't read the right configuration. Please post the whole startup
logs.

PS: Your posted SMSc configuration seems fine.

BR,
Nikos

Re: Routing failed, re-queued

by Isaac Sanni-Thomas :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

Hi,
Confirm that the SMSC is alive. Confirm with the systems administrators
that access has been granted to your server hosting bb.
They can allow pings for test just to be sure the SMSC is accessible.
Since messages are sent over SMPP, wherever the firewall exist,either on
your side or on the MNO's side, confirm they are allowing SMPP traffic
to and from your server.

2009-11-06 13:31:50 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to
server.

That line shows that traffic to the SMSC is denied or the SMSC's server
is not alive.

regards,
Isaac



On 11/06/2009 10:56 AM, digiTonik wrote:

> I am unable to telnet to the address.
>
> Here is the full log:
>
> bearerbox
> =======
> 2009-11-06 13:31:05 [18052] [0] INFO: Added logfile
> `/var/log/kannel/bearerbox.log' with level `0'.
> 2009-11-06 13:31:05 [18052] [0] INFO: HTTP: Opening server at port 13002.
> 2009-11-06 13:31:05 [18052] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps
> to pid 18052.
> 2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 1
> (gwlib/fdset.c:poller)
> 2009-11-06 13:31:05 [18052] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread)
> maps to pid 18052.
> 2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 2
> (gwlib/http.c:server_thread)
> 2009-11-06 13:31:05 [18052] [3] DEBUG: Thread 3 (gw/bb_http.c:httpadmin_run)
> maps to pid 18052.
> 2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 3
> (gw/bb_http.c:httpadmin_run)
> 2009-11-06 13:31:05 [18052] [0] DEBUG: starting smsbox connection module
> 2009-11-06 13:31:05 [18052] [0] INFO: BOXC: 'smsbox-max-pending' not set,
> using default (100).
> 2009-11-06 13:31:05 [18052] [4] DEBUG: Thread 4
> (gw/bb_boxc.c:sms_to_smsboxes) maps to pid 18052.
> 2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 4
> (gw/bb_boxc.c:sms_to_smsboxes)
> 2009-11-06 13:31:05 [18052] [5] DEBUG: Thread 5 (gw/bb_boxc.c:smsboxc_run)
> maps to pid 18052.
> 2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 5
> (gw/bb_boxc.c:smsboxc_run)
> 2009-11-06 13:31:05 [18052] [0] INFO: Set SMS resend frequency to 60
> seconds.
> 2009-11-06 13:31:05 [18052] [0] INFO: SMS resend retry set to unlimited.
> 2009-11-06 13:31:05 [18052] [0] DEBUG: smsbox MO concatenated message
> handling enabled
> 2009-11-06 13:31:05 [18052] [0] INFO: DLR rerouting for smsc id<etcSMSC>
> disabled.
> 2009-11-06 13:31:05 [18052] [6] DEBUG: Thread 6
> (gw/smsc/smsc_smpp.c:io_thread) maps to pid 18052.
> 2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 6
> (gw/smsc/smsc_smpp.c:io_thread)
> 2009-11-06 13:31:05 [18052] [7] DEBUG: Thread 7
> (gw/bb_smscconn.c:sms_router) maps to pid 18052.
> 2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 7
> (gw/bb_smscconn.c:sms_router)
> 2009-11-06 13:31:05 [18052] [0] INFO:
> ----------------------------------------
> 2009-11-06 13:31:05 [18052] [0] INFO: Kannel bearerbox II version 1.4.3
> starting
> 2009-11-06 13:31:05 [18052] [0] INFO: MAIN: Start-up done, entering mainloop
> 2009-11-06 13:31:50 [18052] [6] ERROR: connect failed
> 2009-11-06 13:31:50 [18052] [6] ERROR: System error 113: No route to host
> 2009-11-06 13:31:50 [18052] [6] ERROR: error connecting to server
> `***.***.**.***' at port `****'
> 2009-11-06 13:31:50 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to
> server.
> 2009-11-06 13:31:50 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to
> SMS center (retrying in 10 seconds).
> 2009-11-06 13:32:43 [18052] [5] INFO: Client connected from<127.0.0.1>
> 2009-11-06 13:32:43 [18052] [8] DEBUG: Thread 8 (gw/bb_boxc.c:function) maps
> to pid 18052.
> 2009-11-06 13:32:43 [18052] [9] DEBUG: Thread 9 (gw/bb_boxc.c:boxc_sender)
> maps to pid 18052.
> 2009-11-06 13:32:43 [18052] [8] DEBUG: Started thread 9
> (gw/bb_boxc.c:boxc_sender)
> 2009-11-06 13:32:43 [18052] [5] DEBUG: Started thread 8
> (gw/bb_boxc.c:function)
> 2009-11-06 13:33:14 [18052] [8] DEBUG: boxc_receiver: sms received
> 2009-11-06 13:33:14 [18052] [8] DEBUG: send_msg: sending msg to box:
> <127.0.0.1>
> 2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message
> (0x8545850 vs 0x8545850)
> 2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
> 2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message
> (0x8545850 vs 0x8545850)
> 2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
> 2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: time to sleep 30.00 secs.
> 2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: gwlist_len = 1
> 2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message
> (0x8545850 vs 0x8545850)
> 2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
> 2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message
> (0x8545850 vs 0x8545850)
> 2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
> 2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: time to sleep 30.00 secs.
> 2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: gwlist_len = 1
> 2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: handling message
> (0x8545850 vs 0x8545850)
> 2009-11-06 13:33:44 [18052] [7] DEBUG: Routing failed, re-queued.
> 2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: handling message
> (0x8545850 vs 0x8545850)
> 2009-11-06 13:33:44 [18052] [7] DEBUG: Routing failed, re-queued.
> 2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: time to sleep 30.00 secs.
> 2009-11-06 13:33:56 [18052] [0] WARNING: Killing signal or HTTP admin
> command received, shutting down...
> 2009-11-06 13:33:56 [18052] [0] DEBUG: Shutting down Kannel...
> 2009-11-06 13:33:56 [18052] [0] DEBUG: shutting down smsc
> 2009-11-06 13:33:56 [18052] [0] DEBUG: Shutting down SMSCConn
> SMPP:***.***.**.***:****/****:user:VMA (slow)
> 2009-11-06 13:34:14 [18052] [7] DEBUG: sms_router: gwlist_len = 1
> 2009-11-06 13:34:14 [18052] [7] DEBUG: sms_router: handling message
> (0x8545850 vs 0x8545850)
> 2009-11-06 13:34:14 [18052] [7] DEBUG: Routing failed, re-queued.
> 2009-11-06 13:34:14 [18052] [7] DEBUG: Thread 7
> (gw/bb_smscconn.c:sms_router) terminates.
> 2009-11-06 13:35:09 [18052] [6] ERROR: connect failed
> 2009-11-06 13:35:09 [18052] [6] ERROR: System error 110: Connection timed
> out
> 2009-11-06 13:35:09 [18052] [6] ERROR: error connecting to server
> `***.***.**.***' at port `****'
> 2009-11-06 13:35:09 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to
> server.
> 2009-11-06 13:35:09 [18052] [6] DEBUG: Thread 6
> (gw/smsc/smsc_smpp.c:io_thread) terminates.
> 2009-11-06 13:35:09 [18052] [0] DEBUG: SMSCConn
> SMPP:***.***.**.***:****/****:user:VMA shut down.
> 2009-11-06 13:35:09 [18052] [0] DEBUG: shutting down udp
> 2009-11-06 13:35:09 [18052] [4] DEBUG: Thread 4
> (gw/bb_boxc.c:sms_to_smsboxes) terminates.
> 2009-11-06 13:35:09 [18052] [9] DEBUG: send_msg: sending msg to box:
> <127.0.0.1>
> 2009-11-06 13:35:09 [18052] [9] DEBUG: Thread 9 (gw/bb_boxc.c:boxc_sender)
> terminates.
> 2009-11-06 13:35:10 [18052] [8] INFO: Connection closed by the box
> <127.0.0.1>
> 2009-11-06 13:35:10 [18052] [8] DEBUG: Thread 8 (gw/bb_boxc.c:function)
> terminates.
> 2009-11-06 13:35:10 [18052] [5] DEBUG: Thread 5 (gw/bb_boxc.c:smsboxc_run)
> terminates.
> 2009-11-06 13:35:10 [18052] [0] INFO: All flow threads have died, killing
> core
> 2009-11-06 13:35:10 [18052] [0] DEBUG: Waiting for 2
> (gwlib/http.c:server_thread) to terminate
> 2009-11-06 13:35:10 [18052] [3] DEBUG: HTTP: No clients with requests,
> quitting.
> 2009-11-06 13:35:10 [18052] [3] DEBUG: Thread 3 (gw/bb_http.c:httpadmin_run)
> terminates.
> 2009-11-06 13:35:11 [18052] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread)
> terminates.
> 2009-11-06 13:35:11 [18052] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
> terminates.
> 2009-11-06 13:35:11 [18052] [0] DEBUG: final clean-up for SMSCConn
> 2009-11-06 13:35:11 [18052] [0] DEBUG: smsbox MO concatenated message
> handling cleaned up
> 2009-11-06 13:35:11 [18052] [0] INFO: Total WDP messages: received 0, sent 0
> 2009-11-06 13:35:11 [18052] [0] INFO: Total SMS messages: received 0, sent 0
> 2009-11-06 13:35:11 [18052] [0] DEBUG: Immutable octet strings: 181.
>
> smsbox
> =====
> 2009-11-06 13:32:43 [18083] [0] INFO: Added logfile
> `/var/log/kannel/smsbox.log' with level `0'.
> 2009-11-06 13:32:43 [18083] [0] INFO: Service global sender set as '13013'
> 2009-11-06 13:32:43 [18083] [0] INFO: HTTP: Opening server at port 13013.
> 2009-11-06 13:32:43 [18083] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps
> to pid 18083.
> 2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 1
> (gwlib/fdset.c:poller)
> 2009-11-06 13:32:43 [18083] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread)
> maps to pid 18083.
> 2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 2
> (gwlib/http.c:server_thread)
> 2009-11-06 13:32:43 [18083] [0] INFO: Set up send sms service at port 13013
> 2009-11-06 13:32:43 [18083] [3] DEBUG: Thread 3 (gw/smsbox.c:sendsms_thread)
> maps to pid 18083.
> 2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 3
> (gw/smsbox.c:sendsms_thread)
> 2009-11-06 13:32:43 [18083] [0] DEBUG:
> ----------------------------------------------
> 2009-11-06 13:32:43 [18083] [0] DEBUG: Kannel smsbox version 1.4.3 starting
> 2009-11-06 13:32:43 [18083] [0] DEBUG:   dumping group (sendsms-user):
> 2009-11-06 13:32:43 [18083] [0] DEBUG:<group>  =<sendsms-user>
> 2009-11-06 13:32:43 [18083] [0] DEBUG:<username>  =<test>
> 2009-11-06 13:32:43 [18083] [0] DEBUG:<password>  =<test123>
> 2009-11-06 13:32:43 [18083] [4] DEBUG: Thread 4
> (gw/smsbox.c:obey_request_thread) maps to pid 18083.
> 2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 4
> (gw/smsbox.c:obey_request_thread)
> 2009-11-06 13:32:43 [18083] [5] DEBUG: Thread 5
> (gw/smsbox.c:url_result_thread) maps to pid 18083.
> 2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 5
> (gw/smsbox.c:url_result_thread)
> 2009-11-06 13:32:43 [18083] [6] DEBUG: Thread 6
> (gw/smsbox.c:http_queue_thread) maps to pid 18083.
> 2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 6
> (gw/smsbox.c:http_queue_thread)
> 2009-11-06 13:32:43 [18083] [0] INFO: Connected to bearerbox at 127.0.0.1
> port 13003.
> 2009-11-06 13:32:43 [18083] [7] DEBUG: Thread 7
> (gw/heartbeat.c:heartbeat_thread) maps to pid 18083.
> 2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 7
> (gw/heartbeat.c:heartbeat_thread)
> 2009-11-06 13:33:14 [18083] [2] DEBUG: HTTP: Creating HTTPClient for
> `127.0.0.1'.
> 2009-11-06 13:33:14 [18083] [2] DEBUG: HTTP: Created HTTPClient area
> 0x823e4d8.
> 2009-11-06 13:33:14 [18083] [3] INFO: smsbox: Got HTTP request
> </cgi-bin/sendsms>  from<127.0.0.1>
> 2009-11-06 13:33:14 [18083] [3] INFO: sendsms used by<test>
> 2009-11-06 13:33:14 [18083] [3] INFO: sendsms sender:<test:13013>
> (127.0.0.1) to:<251911127145>  msg:<whyisthisnotworking>
> 2009-11-06 13:33:14 [18083] [3] DEBUG: Stored UUID
> 7f51e7db-3020-493f-a301-0b6993878b11
> 2009-11-06 13:33:14 [18083] [3] DEBUG: message length 19, sending 1 messages
> 2009-11-06 13:33:14 [18083] [3] DEBUG: Status: 202 Answer:<Sent.>
> 2009-11-06 13:33:14 [18083] [3] DEBUG: Delayed reply - wait for bearerbox
> 2009-11-06 13:33:14 [18083] [0] DEBUG: Got ACK (3) of
> 7f51e7db-3020-493f-a301-0b6993878b11
> 2009-11-06 13:33:14 [18083] [0] DEBUG: HTTP: Resetting HTTPClient for
> `127.0.0.1'.
> 2009-11-06 13:34:14 [18083] [1] DEBUG: Timeout for fd:23 appeares.
> 2009-11-06 13:34:14 [18083] [1] DEBUG: HTTP: Destroying HTTPClient area
> 0x823e4d8.
> 2009-11-06 13:34:14 [18083] [1] DEBUG: HTTP: Destroying HTTPClient for
> `127.0.0.1'.
> 2009-11-06 13:35:09 [18083] [0] INFO: Bearerbox told us to die
> 2009-11-06 13:35:09 [18083] [0] INFO: Received (and handled?) 0 requests in
> 146 seconds (0.00 per second)
> 2009-11-06 13:35:09 [18083] [0] INFO: Kannel smsbox terminating.
> 2009-11-06 13:35:09 [18083] [7] DEBUG: Thread 7
> (gw/heartbeat.c:heartbeat_thread) terminates.
> 2009-11-06 13:35:09 [18083] [0] DEBUG: Waiting for 2
> (gwlib/http.c:server_thread) to terminate
> 2009-11-06 13:35:09 [18083] [3] DEBUG: HTTP: No clients with requests,
> quitting.
> 2009-11-06 13:35:09 [18083] [3] DEBUG: Thread 3 (gw/smsbox.c:sendsms_thread)
> terminates.
> 2009-11-06 13:35:10 [18083] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread)
> terminates.
> 2009-11-06 13:35:10 [18083] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
> terminates.
> 2009-11-06 13:35:10 [18083] [0] DEBUG: Waiting for 4
> (gw/smsbox.c:obey_request_thread) to terminate
> 2009-11-06 13:35:10 [18083] [6] DEBUG: Thread 6
> (gw/smsbox.c:http_queue_thread) terminates.
> 2009-11-06 13:35:10 [18083] [4] DEBUG: Thread 4
> (gw/smsbox.c:obey_request_thread) terminates.
> 2009-11-06 13:35:10 [18083] [0] DEBUG: Waiting for 5
> (gw/smsbox.c:url_result_thread) to terminate
> 2009-11-06 13:35:10 [18083] [5] DEBUG: Thread 5
> (gw/smsbox.c:url_result_thread) terminates.
> 2009-11-06 13:35:10 [18083] [0] DEBUG: Immutable octet strings: 147.
>
>
>
> Nikos Balkanas wrote:
>    
>> Hi,
>>
>> Don't try pinging. There may be a firewall in between. Instead try:
>>
>> telnet<IP>  <port>
>>
>> And see if that reponds. If not, call them.
>>
>> But the question is not even that. Bb doesn't even attempt to start it.
>> Seems it doesn't read the right configuration. Please post the whole
>> startup
>> logs.
>>
>> PS: Your posted SMSc configuration seems fine.
>>
>> BR,
>> Nikos
>>
>>      
>    


Re: Routing failed, re-queued

by Alejandro Guerrieri :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

I agree, you should first do the "telnet test". Basically, try to telnet to the SMPP port, you should be able to connect.

If you can't there's definitely a networking issue.

Regards,

Alejandro

On Fri, Nov 6, 2009 at 12:20 PM, Isaac Sanni-Thomas <isaacsannithomas@...> wrote:
Hi,
Confirm that the SMSC is alive. Confirm with the systems administrators that access has been granted to your server hosting bb.
They can allow pings for test just to be sure the SMSC is accessible. Since messages are sent over SMPP, wherever the firewall exist,either on your side or on the MNO's side, confirm they are allowing SMPP traffic to and from your server.


2009-11-06 13:31:50 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to
server.

That line shows that traffic to the SMSC is denied or the SMSC's server is not alive.

regards,
Isaac




On 11/06/2009 10:56 AM, digiTonik wrote:
I am unable to telnet to the address.

Here is the full log:

bearerbox
=======
2009-11-06 13:31:05 [18052] [0] INFO: Added logfile
`/var/log/kannel/bearerbox.log' with level `0'.
2009-11-06 13:31:05 [18052] [0] INFO: HTTP: Opening server at port 13002.
2009-11-06 13:31:05 [18052] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps
to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 1
(gwlib/fdset.c:poller)
2009-11-06 13:31:05 [18052] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread)
maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 2
(gwlib/http.c:server_thread)
2009-11-06 13:31:05 [18052] [3] DEBUG: Thread 3 (gw/bb_http.c:httpadmin_run)
maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 3
(gw/bb_http.c:httpadmin_run)
2009-11-06 13:31:05 [18052] [0] DEBUG: starting smsbox connection module
2009-11-06 13:31:05 [18052] [0] INFO: BOXC: 'smsbox-max-pending' not set,
using default (100).
2009-11-06 13:31:05 [18052] [4] DEBUG: Thread 4
(gw/bb_boxc.c:sms_to_smsboxes) maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 4
(gw/bb_boxc.c:sms_to_smsboxes)
2009-11-06 13:31:05 [18052] [5] DEBUG: Thread 5 (gw/bb_boxc.c:smsboxc_run)
maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 5
(gw/bb_boxc.c:smsboxc_run)
2009-11-06 13:31:05 [18052] [0] INFO: Set SMS resend frequency to 60
seconds.
2009-11-06 13:31:05 [18052] [0] INFO: SMS resend retry set to unlimited.
2009-11-06 13:31:05 [18052] [0] DEBUG: smsbox MO concatenated message
handling enabled
2009-11-06 13:31:05 [18052] [0] INFO: DLR rerouting for smsc id<etcSMSC>
disabled.
2009-11-06 13:31:05 [18052] [6] DEBUG: Thread 6
(gw/smsc/smsc_smpp.c:io_thread) maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 6
(gw/smsc/smsc_smpp.c:io_thread)
2009-11-06 13:31:05 [18052] [7] DEBUG: Thread 7
(gw/bb_smscconn.c:sms_router) maps to pid 18052.
2009-11-06 13:31:05 [18052] [0] DEBUG: Started thread 7
(gw/bb_smscconn.c:sms_router)
2009-11-06 13:31:05 [18052] [0] INFO:
----------------------------------------
2009-11-06 13:31:05 [18052] [0] INFO: Kannel bearerbox II version 1.4.3
starting
2009-11-06 13:31:05 [18052] [0] INFO: MAIN: Start-up done, entering mainloop
2009-11-06 13:31:50 [18052] [6] ERROR: connect failed
2009-11-06 13:31:50 [18052] [6] ERROR: System error 113: No route to host
2009-11-06 13:31:50 [18052] [6] ERROR: error connecting to server
`***.***.**.***' at port `****'
2009-11-06 13:31:50 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to
server.
2009-11-06 13:31:50 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to
SMS center (retrying in 10 seconds).
2009-11-06 13:32:43 [18052] [5] INFO: Client connected from<127.0.0.1>
2009-11-06 13:32:43 [18052] [8] DEBUG: Thread 8 (gw/bb_boxc.c:function) maps
to pid 18052.
2009-11-06 13:32:43 [18052] [9] DEBUG: Thread 9 (gw/bb_boxc.c:boxc_sender)
maps to pid 18052.
2009-11-06 13:32:43 [18052] [8] DEBUG: Started thread 9
(gw/bb_boxc.c:boxc_sender)
2009-11-06 13:32:43 [18052] [5] DEBUG: Started thread 8
(gw/bb_boxc.c:function)
2009-11-06 13:33:14 [18052] [8] DEBUG: boxc_receiver: sms received
2009-11-06 13:33:14 [18052] [8] DEBUG: send_msg: sending msg to box:
<127.0.0.1>
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message
(0x8545850 vs 0x8545850)
2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message
(0x8545850 vs 0x8545850)
2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: gwlist_len = 1
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message
(0x8545850 vs 0x8545850)
2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: handling message
(0x8545850 vs 0x8545850)
2009-11-06 13:33:14 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:14 [18052] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: gwlist_len = 1
2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: handling message
(0x8545850 vs 0x8545850)
2009-11-06 13:33:44 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: handling message
(0x8545850 vs 0x8545850)
2009-11-06 13:33:44 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:33:44 [18052] [7] DEBUG: sms_router: time to sleep 30.00 secs.
2009-11-06 13:33:56 [18052] [0] WARNING: Killing signal or HTTP admin
command received, shutting down...
2009-11-06 13:33:56 [18052] [0] DEBUG: Shutting down Kannel...
2009-11-06 13:33:56 [18052] [0] DEBUG: shutting down smsc
2009-11-06 13:33:56 [18052] [0] DEBUG: Shutting down SMSCConn
SMPP:***.***.**.***:****/****:user:VMA (slow)
2009-11-06 13:34:14 [18052] [7] DEBUG: sms_router: gwlist_len = 1
2009-11-06 13:34:14 [18052] [7] DEBUG: sms_router: handling message
(0x8545850 vs 0x8545850)
2009-11-06 13:34:14 [18052] [7] DEBUG: Routing failed, re-queued.
2009-11-06 13:34:14 [18052] [7] DEBUG: Thread 7
(gw/bb_smscconn.c:sms_router) terminates.
2009-11-06 13:35:09 [18052] [6] ERROR: connect failed
2009-11-06 13:35:09 [18052] [6] ERROR: System error 110: Connection timed
out
2009-11-06 13:35:09 [18052] [6] ERROR: error connecting to server
`***.***.**.***' at port `****'
2009-11-06 13:35:09 [18052] [6] ERROR: SMPP[etcSMSC]: Couldn't connect to
server.
2009-11-06 13:35:09 [18052] [6] DEBUG: Thread 6
(gw/smsc/smsc_smpp.c:io_thread) terminates.
2009-11-06 13:35:09 [18052] [0] DEBUG: SMSCConn
SMPP:***.***.**.***:****/****:user:VMA shut down.
2009-11-06 13:35:09 [18052] [0] DEBUG: shutting down udp
2009-11-06 13:35:09 [18052] [4] DEBUG: Thread 4
(gw/bb_boxc.c:sms_to_smsboxes) terminates.
2009-11-06 13:35:09 [18052] [9] DEBUG: send_msg: sending msg to box:
<127.0.0.1>
2009-11-06 13:35:09 [18052] [9] DEBUG: Thread 9 (gw/bb_boxc.c:boxc_sender)
terminates.
2009-11-06 13:35:10 [18052] [8] INFO: Connection closed by the box
<127.0.0.1>
2009-11-06 13:35:10 [18052] [8] DEBUG: Thread 8 (gw/bb_boxc.c:function)
terminates.
2009-11-06 13:35:10 [18052] [5] DEBUG: Thread 5 (gw/bb_boxc.c:smsboxc_run)
terminates.
2009-11-06 13:35:10 [18052] [0] INFO: All flow threads have died, killing
core
2009-11-06 13:35:10 [18052] [0] DEBUG: Waiting for 2
(gwlib/http.c:server_thread) to terminate
2009-11-06 13:35:10 [18052] [3] DEBUG: HTTP: No clients with requests,
quitting.
2009-11-06 13:35:10 [18052] [3] DEBUG: Thread 3 (gw/bb_http.c:httpadmin_run)
terminates.
2009-11-06 13:35:11 [18052] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread)
terminates.
2009-11-06 13:35:11 [18052] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
terminates.
2009-11-06 13:35:11 [18052] [0] DEBUG: final clean-up for SMSCConn
2009-11-06 13:35:11 [18052] [0] DEBUG: smsbox MO concatenated message
handling cleaned up
2009-11-06 13:35:11 [18052] [0] INFO: Total WDP messages: received 0, sent 0
2009-11-06 13:35:11 [18052] [0] INFO: Total SMS messages: received 0, sent 0
2009-11-06 13:35:11 [18052] [0] DEBUG: Immutable octet strings: 181.

smsbox
=====
2009-11-06 13:32:43 [18083] [0] INFO: Added logfile
`/var/log/kannel/smsbox.log' with level `0'.
2009-11-06 13:32:43 [18083] [0] INFO: Service global sender set as '13013'
2009-11-06 13:32:43 [18083] [0] INFO: HTTP: Opening server at port 13013.
2009-11-06 13:32:43 [18083] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps
to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 1
(gwlib/fdset.c:poller)
2009-11-06 13:32:43 [18083] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread)
maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 2
(gwlib/http.c:server_thread)
2009-11-06 13:32:43 [18083] [0] INFO: Set up send sms service at port 13013
2009-11-06 13:32:43 [18083] [3] DEBUG: Thread 3 (gw/smsbox.c:sendsms_thread)
maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 3
(gw/smsbox.c:sendsms_thread)
2009-11-06 13:32:43 [18083] [0] DEBUG:
----------------------------------------------
2009-11-06 13:32:43 [18083] [0] DEBUG: Kannel smsbox version 1.4.3 starting
2009-11-06 13:32:43 [18083] [0] DEBUG:   dumping group (sendsms-user):
2009-11-06 13:32:43 [18083] [0] DEBUG:<group>  =<sendsms-user>
2009-11-06 13:32:43 [18083] [0] DEBUG:<username>  =<test>
2009-11-06 13:32:43 [18083] [0] DEBUG:<password>  =<test123>
2009-11-06 13:32:43 [18083] [4] DEBUG: Thread 4
(gw/smsbox.c:obey_request_thread) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 4
(gw/smsbox.c:obey_request_thread)
2009-11-06 13:32:43 [18083] [5] DEBUG: Thread 5
(gw/smsbox.c:url_result_thread) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 5
(gw/smsbox.c:url_result_thread)
2009-11-06 13:32:43 [18083] [6] DEBUG: Thread 6
(gw/smsbox.c:http_queue_thread) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 6
(gw/smsbox.c:http_queue_thread)
2009-11-06 13:32:43 [18083] [0] INFO: Connected to bearerbox at 127.0.0.1
port 13003.
2009-11-06 13:32:43 [18083] [7] DEBUG: Thread 7
(gw/heartbeat.c:heartbeat_thread) maps to pid 18083.
2009-11-06 13:32:43 [18083] [0] DEBUG: Started thread 7
(gw/heartbeat.c:heartbeat_thread)
2009-11-06 13:33:14 [18083] [2] DEBUG: HTTP: Creating HTTPClient for
`127.0.0.1'.
2009-11-06 13:33:14 [18083] [2] DEBUG: HTTP: Created HTTPClient area
0x823e4d8.
2009-11-06 13:33:14 [18083] [3] INFO: smsbox: Got HTTP request
</cgi-bin/sendsms>  from<127.0.0.1>
2009-11-06 13:33:14 [18083] [3] INFO: sendsms used by<test>
2009-11-06 13:33:14 [18083] [3] INFO: sendsms sender:<test:13013>
(127.0.0.1) to:<251911127145>  msg:<whyisthisnotworking>
2009-11-06 13:33:14 [18083] [3] DEBUG: Stored UUID
7f51e7db-3020-493f-a301-0b6993878b11
2009-11-06 13:33:14 [18083] [3] DEBUG: message length 19, sending 1 messages
2009-11-06 13:33:14 [18083] [3] DEBUG: Status: 202 Answer:<Sent.>
2009-11-06 13:33:14 [18083] [3] DEBUG: Delayed reply - wait for bearerbox
2009-11-06 13:33:14 [18083] [0] DEBUG: Got ACK (3) of
7f51e7db-3020-493f-a301-0b6993878b11
2009-11-06 13:33:14 [18083] [0] DEBUG: HTTP: Resetting HTTPClient for
`127.0.0.1'.
2009-11-06 13:34:14 [18083] [1] DEBUG: Timeout for fd:23 appeares.
2009-11-06 13:34:14 [18083] [1] DEBUG: HTTP: Destroying HTTPClient area
0x823e4d8.
2009-11-06 13:34:14 [18083] [1] DEBUG: HTTP: Destroying HTTPClient for
`127.0.0.1'.
2009-11-06 13:35:09 [18083] [0] INFO: Bearerbox told us to die
2009-11-06 13:35:09 [18083] [0] INFO: Received (and handled?) 0 requests in
146 seconds (0.00 per second)
2009-11-06 13:35:09 [18083] [0] INFO: Kannel smsbox terminating.
2009-11-06 13:35:09 [18083] [7] DEBUG: Thread 7
(gw/heartbeat.c:heartbeat_thread) terminates.
2009-11-06 13:35:09 [18083] [0] DEBUG: Waiting for 2
(gwlib/http.c:server_thread) to terminate
2009-11-06 13:35:09 [18083] [3] DEBUG: HTTP: No clients with requests,
quitting.
2009-11-06 13:35:09 [18083] [3] DEBUG: Thread 3 (gw/smsbox.c:sendsms_thread)
terminates.
2009-11-06 13:35:10 [18083] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread)
terminates.
2009-11-06 13:35:10 [18083] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
terminates.
2009-11-06 13:35:10 [18083] [0] DEBUG: Waiting for 4
(gw/smsbox.c:obey_request_thread) to terminate
2009-11-06 13:35:10 [18083] [6] DEBUG: Thread 6
(gw/smsbox.c:http_queue_thread) terminates.
2009-11-06 13:35:10 [18083] [4] DEBUG: Thread 4
(gw/smsbox.c:obey_request_thread) terminates.
2009-11-06 13:35:10 [18083] [0] DEBUG: Waiting for 5
(gw/smsbox.c:url_result_thread) to terminate
2009-11-06 13:35:10 [18083] [5] DEBUG: Thread 5
(gw/smsbox.c:url_result_thread) terminates.
2009-11-06 13:35:10 [18083] [0] DEBUG: Immutable octet strings: 147.



Nikos Balkanas wrote:
 
Hi,

Don't try pinging. There may be a firewall in between. Instead try:

telnet<IP>  <port>

And see if that reponds. If not, call them.

But the question is not even that. Bb doesn't even attempt to start it.
Seems it doesn't read the right configuration. Please post the whole
startup
logs.

PS: Your posted SMSc configuration seems fine.

BR,
Nikos