Re: WAP-Push with Nokia 6210

Tue, 15 Jun 2010 20:12:56 -0700 

Hi,
Actually you are more or less right. result:0 means SMSC_SUCCESS, not queued or failed or anything else. So I assumed that when it got that at 22:04:10 modem already returned OK. Checking at the sources, however, shows that this is returned only when the SMS is put in bb's at2 queue for sending, and that the at2 driver always returns SMSC_SUCCESS (0). 


Therefore everything works as expected from kannel's side. Bb correctly 
requeues the SMS as long as it gets an ERROR from your modem. No need to 
check the modem cable, it is fine. Could be a problem with the SIM (network 
congested - change operator?) or the modem is broken - change modem.


BR,
Nikos


----- Original Message ----- 
From: "Thomas Sieh" <[email protected]>

To: "Nikos Balkanas" <[email protected]>
Cc: "Thomas Sieh" <[email protected]>; <[email protected]>
Sent: Wednesday, June 16, 2010 1:26 AM
Subject: Re: WAP-Push with Nokia 6210



Hi,

when i interpret the logs correct the smsc ist opened at 15:19:30.
At 15:21:01 i pushed a sms from webbrowser and the smsbox gave it
directly to the bearerbox.
at 15:21:02 the smsc try to send the data, but raises an error.
from 15:21:03 to 15:22:03 the bearerbox tried to route the message.
The smsc tries again to send the message, successless.
At 15:22:05 the smsc tries again to send the data, but raises an error.
Then the bearerbox routes the message again until 15:23:05. this time
the smsc sends the message successfully.
is this correct?

then i don't understand what the smsbox should do after the initial
sending to the bearerbox. and why should be there an other message in
the queue? isn't it the same message that kannel tries to send multiple
times and only at 15:23:05 with success?

I don't have removed any debug-infos! i never thouched the source apart
from the patch you sent me. and this i have installed into a fresh
downloaded cvs-kannel.

I have done another test and created a chronologic dependend chart of
interaction between the boxes in openoffice format. i attached it. it's
really schort. maybe you see something between the lines or have one
more idea. possibly the routing of the bearerbox?

i think you are right with the guess that there could be a problem with
the phone. tomorrow i will try another cable. possibly i have luck :)

thanks,
thomas


On Tue, Jun 15, 2010 at 06:24:51PM +0300, Nikos Balkanas wrote:

Hi,

This is very confusing. In bb logs I see a successful transmission at
15:21:01. Yet I see no evidence of it in smsc logs. What's going on?
Besides I see in bb logs requeuing SMS at 15:21:03, without sending it
from smsbox, which means that when you started queue was not empty. Also
you removed debugs from gw/bb_smscconn.c.

Please explain,
Nikos
----- Original Message ----- From: "Thomas Sieh" <[email protected]>
To: "Nikos Balkanas" <[email protected]>
Cc: <[email protected]>
Sent: Tuesday, June 15, 2010 6:00 PM
Subject: Re: WAP-Push with Nokia 6210



Hi Nikos,

I have read you E-Mail again and have seen, that you wrote single sms,
not simple sms, sorry.



The Problem occurs at every time. Not only after start or the first 
bulk.

i have already tested -- and did it again after your tip -- to set up
need-sleep. I tried all variations of need-sleep and sendline-sleep.
this options don't make a difference. so I have set it up like at the
compatibility site described for the nokia 6210: need-sleep = true.

I have done it again with your new patch. this time the first message
was sent after only 2 errors. so i will attach the other logs, too.

here are the logfiles:



access ::
:::::::::

2010-06-15 15:19:28 Log begins
2010-06-15 15:19:29 Log begins
2010-06-15 15:19:29 Log begins
2010-06-15 15:21:01 send-SMS request added -
sender:tester:+4915779****** 127.0.0.1 target:+4917649****** request:
'das ist eine sms'
2010-06-15 15:23:10 Sent SMS [SMSC:SMSC_01] [SVC:tester] [ACT:] [BINF:]
[FID:] [META:] [from:+4915779******] [to:+4917649******]
[flags:-1:0:-1:-1:-1] [msg:16:das ist eine sms] [udh:0:]


smsc::
::::::


2010-06-15 15:19:28 [17069] [6] INFO: AT2[SMSC_01]: opening device
2010-06-15 15:19:28 [17069] [6] DEBUG: AT2[SMSC_01]: device opened.
Telnet mode = 0
2010-06-15 15:19:29 [17069] [6] DEBUG: AT2[SMSC_01]: device opened
2010-06-15 15:19:29 [17069] [6] INFO: AT2[SMSC_01]: Logging in
2010-06-15 15:19:29 [17069] [6] INFO: AT2[SMSC_01]: init device
2010-06-15 15:19:29 [17069] [6] INFO: AT2[SMSC_01]: speed set to 9600
2010-06-15 15:19:29 [17069] [6] DEBUG: AT2[SMSC_01]: --> ATZ^M
2010-06-15 15:19:29 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:19:29 [17069] [6] DEBUG: AT2[SMSC_01]: --> AT^M
2010-06-15 15:19:29 [17069] [6] DEBUG: AT2[SMSC_01]: <-- AT
2010-06-15 15:19:29 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:19:29 [17069] [6] DEBUG: AT2[SMSC_01]: --> AT&F^M
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- AT&F
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: --> ATE0^M
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- ATE0
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: --> AT+IFC=2,2^M
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: --> AT+CPIN?^M
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- +CPIN: READY
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: -->
AT+CSCA="+491770610000"^M
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: --> AT+CMGF=0^M
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: --> AT+CSMS=?^M
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: -->
AT+CNMI=1,2,0,1,0^M
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- +CSMS: (0,1)
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:19:30 [17069] [6] INFO: AT2[SMSC_01]: AT SMSC
successfully opened.
2010-06-15 15:19:30 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:21:02 [17069] [6] DEBUG: AT2[SMSC_01]:
TP-Validity-Period: 24.0 hours
2010-06-15 15:21:02 [17069] [6] DEBUG: AT2[SMSC_01]: --> AT+CMGS=29^M
2010-06-15 15:21:02 [17069] [6] DEBUG: AT2[SMSC_01]: <--
2010-06-15 15:21:02 [17069] [6] DEBUG: AT2[SMSC_01]: <-- >
2010-06-15 15:21:02 [17069] [6] DEBUG: AT2[SMSC_01]: send command
status: 1
2010-06-15 15:21:02 [17069] [6] DEBUG: AT2[SMSC_01]: -->
0011000D9194714669
2010-06-15 15:21:02 [17069] [6] DEBUG: AT2[SMSC_01]: -->
******0000A710E4F0
2010-06-15 15:21:03 [17069] [6] DEBUG: AT2[SMSC_01]: -->
1C949ED341E5B4BB0C
2010-06-15 15:21:03 [17069] [6] DEBUG: AT2[SMSC_01]: --> 9AB7E7^Z
2010-06-15 15:21:03 [17069] [6] DEBUG: AT2[SMSC_01]: <-- ERROR

2010-06-15 15:21:03 [17069] [6] ERROR: AT2[SMSC_01]: Generic error: 
ERROR

2010-06-15 15:21:03 [17069] [6] DEBUG: AT2[SMSC_01]: send command
status: -1
2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]:
TP-Validity-Period: 24.0 hours
2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]: --> AT+CMGS=29^M
2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]: <-- >
2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]: send command
status: 1
2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]: -->
0011000D9194714669
2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]: -->
******0000A710E4F0
2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]: -->
1C949ED341E5B4BB0C
2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]: --> 9AB7E7^Z
2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]: <-- >
2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]: <-- ERROR

2010-06-15 15:22:05 [17069] [6] ERROR: AT2[SMSC_01]: Generic error: 
ERROR

2010-06-15 15:22:05 [17069] [6] DEBUG: AT2[SMSC_01]: send command
status: -1
2010-06-15 15:23:05 [17069] [6] DEBUG: AT2[SMSC_01]:
TP-Validity-Period: 24.0 hours
2010-06-15 15:23:05 [17069] [6] DEBUG: AT2[SMSC_01]: --> AT+CMGS=29^M
2010-06-15 15:23:05 [17069] [6] DEBUG: AT2[SMSC_01]: <--
2010-06-15 15:23:05 [17069] [6] DEBUG: AT2[SMSC_01]: <-- >
2010-06-15 15:23:05 [17069] [6] DEBUG: AT2[SMSC_01]: send command
status: 1
2010-06-15 15:23:05 [17069] [6] DEBUG: AT2[SMSC_01]: -->
0011000D9194714669
2010-06-15 15:23:06 [17069] [6] DEBUG: AT2[SMSC_01]: -->
******0000A710E4F0
2010-06-15 15:23:06 [17069] [6] DEBUG: AT2[SMSC_01]: -->
1C949ED341E5B4BB0C
2010-06-15 15:23:06 [17069] [6] DEBUG: AT2[SMSC_01]: --> 9AB7E7^Z
2010-06-15 15:23:10 [17069] [6] DEBUG: AT2[SMSC_01]: <-- +CMGS: 103
2010-06-15 15:23:10 [17069] [6] DEBUG: AT2[SMSC_01]: <-- OK
2010-06-15 15:23:10 [17069] [6] DEBUG: AT2[SMSC_01]: send command
status: 0


bearerbox ::
::::::::::::

2010-06-15 15:19:28 [17069] [0] INFO: Added logfile
`/var/log/kannel/bearerbox.log' with level `0'.
2010-06-15 15:19:28 [17069] [0] INFO: Started access logfile
`/var/log/kannel/access.log'.
2010-06-15 15:19:28 [17069] [0] INFO: SSL not supported, no SSL
initialization done.

2010-06-15 15:19:28 [17069] [0] INFO: HTTP: Opening server at port 
13000.

2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 1
(gwlib/fdset.c:poller)
2010-06-15 15:19:28 [17069] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller)
maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 2
(gwlib/http.c:server_thread)
2010-06-15 15:19:28 [17069] [2] DEBUG: Thread 2
(gwlib/http.c:server_thread) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 3
(gw/bb_http.c:httpadmin_run)
2010-06-15 15:19:28 [17069] [3] DEBUG: Thread 3
(gw/bb_http.c:httpadmin_run) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] DEBUG: starting smsbox connection module
2010-06-15 15:19:28 [17069] [0] INFO: BOXC: 'smsbox-max-pending' not
set, using default (100).
2010-06-15 15:19:28 [17069] [0] DEBUG: Adding smsbox routing to id
<sms_box> for smsc id <SMSC_01>
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 4
(gw/bb_boxc.c:sms_to_smsboxes)
2010-06-15 15:19:28 [17069] [4] DEBUG: Thread 4
(gw/bb_boxc.c:sms_to_smsboxes) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 5
(gw/bb_boxc.c:smsboxc_run)
2010-06-15 15:19:28 [17069] [5] DEBUG: Thread 5
(gw/bb_boxc.c:smsboxc_run) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] INFO: Set SMS resend frequency to 60
seconds.
2010-06-15 15:19:28 [17069] [0] INFO: SMS resend retry set to unlimited.
2010-06-15 15:19:28 [17069] [0] DEBUG: MO concatenated message handling
enabled
2010-06-15 15:19:28 [17069] [0] INFO: DLR rerouting for smsc id
<SMSC_01> disabled.
2010-06-15 15:19:28 [17069] [0] INFO: Added logfile
`/var/log/kannel/smsc.log' with level `0'.
2010-06-15 15:19:28 [17069] [0] INFO: AT2[SMSC_01]: configuration shows
modemtype <nokiaphone>
2010-06-15 15:19:28 [17069] [0] DEBUG: AT2[SMSC_01]: Reading modem
definitions from </etc/kannel/kannel.conf>
2010-06-15 15:19:28 [17069] [0] DEBUG: AT2[SMSC_01]: Found <1> modems
in config
2010-06-15 15:19:28 [17069] [0] INFO: AT2[SMSC_01]: read modem
definition for <Nokia Phone>
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 6
(gw/smsc/smsc_at.c:at2_device_thread)
2010-06-15 15:19:28 [17069] [6] DEBUG: Thread 6
(gw/smsc/smsc_at.c:at2_device_thread) maps to pid 17069.
2010-06-15 15:19:28 [17069] [6] INFO: Logging thread `6' to logfile
`/var/log/kannel/smsc.log' with level `0'.
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 7
(gw/bb_smscconn.c:sms_router)
2010-06-15 15:19:28 [17069] [7] DEBUG: Thread 7
(gw/bb_smscconn.c:sms_router) maps to pid 17069.

2010-06-15 15:19:28 [17069] [0] DEBUG: starting UDP sender/receiver 
module

2010-06-15 15:19:28 [17069] [0] INFO: Adding interface *
2010-06-15 15:19:28 [17069] [0] DEBUG: udpc_create: Bound to UDP
<0.0.0.0:9200>
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 8
(gw/bb_udp.c:udp_receiver)
2010-06-15 15:19:28 [17069] [8] DEBUG: Thread 8
(gw/bb_udp.c:udp_receiver) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 9
(gw/bb_udp.c:udp_sender)
2010-06-15 15:19:28 [17069] [9] DEBUG: Thread 9
(gw/bb_udp.c:udp_sender) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] DEBUG: udpc_create: Bound to UDP
<0.0.0.0:9201>
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 10
(gw/bb_udp.c:udp_receiver)
2010-06-15 15:19:28 [17069] [10] DEBUG: Thread 10
(gw/bb_udp.c:udp_receiver) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 11
(gw/bb_udp.c:udp_sender)
2010-06-15 15:19:28 [17069] [11] DEBUG: Thread 11
(gw/bb_udp.c:udp_sender) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] DEBUG: starting wapbox connection module
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 12
(gw/bb_boxc.c:wdp_to_wapboxes)
2010-06-15 15:19:28 [17069] [12] DEBUG: Thread 12
(gw/bb_boxc.c:wdp_to_wapboxes) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 13
(gw/bb_boxc.c:wapboxc_run)
2010-06-15 15:19:28 [17069] [13] DEBUG: Thread 13
(gw/bb_boxc.c:wapboxc_run) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] DEBUG: starting WDP router
2010-06-15 15:19:28 [17069] [0] DEBUG: Started thread 14
(gw/bearerbox.c:wdp_router)
2010-06-15 15:19:28 [17069] [14] DEBUG: Thread 14
(gw/bearerbox.c:wdp_router) maps to pid 17069.
2010-06-15 15:19:28 [17069] [0] INFO:
----------------------------------------
2010-06-15 15:19:28 [17069] [0] INFO: Kannel bearerbox II version
cvs-20100320 starting
2010-06-15 15:19:28 [17069] [0] INFO: Loaded 0 messages from store.
2010-06-15 15:19:28 [17069] [0] INFO: MAIN: Start-up done, entering
mainloop
2010-06-15 15:19:28 [17069] [0] DEBUG: AT2[SMSC_01]: start called
2010-06-15 15:19:29 [17069] [5] INFO: Client connected from <127.0.0.1>
2010-06-15 15:19:29 [17069] [5] DEBUG: Started thread 15
(gw/bb_boxc.c:function)
2010-06-15 15:19:29 [17069] [15] DEBUG: Thread 15
(gw/bb_boxc.c:function) maps to pid 17069.
2010-06-15 15:19:29 [17069] [15] DEBUG: Started thread 16
(gw/bb_boxc.c:boxc_sender)
2010-06-15 15:19:29 [17069] [16] DEBUG: Thread 16
(gw/bb_boxc.c:boxc_sender) maps to pid 17069.
2010-06-15 15:19:29 [17069] [15] DEBUG: boxc_receiver: got boxc_id
<sms_box> from <127.0.0.1>
2010-06-15 15:19:29 [17069] [13] INFO: Client connected from <127.0.0.1>
2010-06-15 15:19:29 [17069] [13] DEBUG: Started thread 17
(gw/bb_boxc.c:function)
2010-06-15 15:19:29 [17069] [17] DEBUG: Thread 17
(gw/bb_boxc.c:function) maps to pid 17069.

2010-06-15 15:19:29 [17069] [17] DEBUG: setting up systems for new 
wapbox

2010-06-15 15:19:29 [17069] [17] DEBUG: Started thread 18
(gw/bb_boxc.c:boxc_sender)
2010-06-15 15:19:29 [17069] [18] DEBUG: Thread 18
(gw/bb_boxc.c:boxc_sender) maps to pid 17069.
2010-06-15 15:21:01 [17069] [15] DEBUG: boxc_receiver: sms received
2010-06-15 15:21:01 [17069] [15] INFO: NIKOS!!! sender: +4915779******,
destination: +4917649******
2010-06-15 15:21:01 [17069] [15] INFO: NIKOS!!! Sent SMS result: 0
2010-06-15 15:21:01 [17069] [15] DEBUG: send_msg: sending msg to boxc:
<sms_box>
2010-06-15 15:21:03 [17069] [7] DEBUG: sms_router: handling message
(0x81b1a78 vs 0x81b1a78)

2010-06-15 15:21:03 [17069] [7] DEBUG: re-queing SMS not-yet-to-be 
resent

2010-06-15 15:21:03 [17069] [7] DEBUG: sms_router: handling message
(0x81b1a78 vs 0x81b1a78)

2010-06-15 15:21:03 [17069] [7] DEBUG: re-queing SMS not-yet-to-be 
resent

2010-06-15 15:21:03 [17069] [7] DEBUG: sms_router: time to sleep 30.00
secs.
2010-06-15 15:21:03 [17069] [7] DEBUG: sms_router: gwlist_len = 1
2010-06-15 15:21:03 [17069] [7] DEBUG: sms_router: handling message
(0x81b1a78 vs 0x81b1a78)

2010-06-15 15:21:03 [17069] [7] DEBUG: re-queing SMS not-yet-to-be 
resent

2010-06-15 15:21:03 [17069] [7] DEBUG: sms_router: handling message
(0x81b1a78 vs 0x81b1a78)

2010-06-15 15:21:03 [17069] [7] DEBUG: re-queing SMS not-yet-to-be 
resent

2010-06-15 15:21:03 [17069] [7] DEBUG: sms_router: time to sleep 30.00
secs.
2010-06-15 15:21:06 [17069] [2] DEBUG: HTTP: Creating HTTPClient for
`127.0.0.1'.
2010-06-15 15:21:06 [17069] [2] DEBUG: HTTP: Created HTTPClient area
0x81ae688.
2010-06-15 15:21:06 [17069] [3] DEBUG: HTTP: Resetting HTTPClient for
`127.0.0.1'.
2010-06-15 15:21:08 [17069] [3] DEBUG: HTTP: Resetting HTTPClient for
`127.0.0.1'.
2010-06-15 15:21:09 [17069] [3] DEBUG: HTTP: Resetting HTTPClient for
`127.0.0.1'.
2010-06-15 15:21:13 [17069] [3] DEBUG: HTTP: Resetting HTTPClient for
`127.0.0.1'.
2010-06-15 15:21:17 [17069] [3] DEBUG: HTTP: Resetting HTTPClient for
`127.0.0.1'.
2010-06-15 15:21:33 [17069] [7] DEBUG: sms_router: gwlist_len = 1
2010-06-15 15:21:33 [17069] [7] DEBUG: sms_router: handling message
(0x81b1a78 vs 0x81b1a78)

2010-06-15 15:21:33 [17069] [7] DEBUG: re-queing SMS not-yet-to-be 
resent

2010-06-15 15:21:33 [17069] [7] DEBUG: sms_router: handling message
(0x81b1a78 vs 0x81b1a78)

2010-06-15 15:21:33 [17069] [7] DEBUG: re-queing SMS not-yet-to-be 
resent

2010-06-15 15:21:33 [17069] [7] DEBUG: sms_router: time to sleep 30.00
secs.
2010-06-15 15:22:03 [17069] [7] DEBUG: sms_router: gwlist_len = 1
2010-06-15 15:22:03 [17069] [7] DEBUG: sms_router: handling message
(0x81b1a78 vs 0x81b1a78)
2010-06-15 15:22:03 [17069] [7] DEBUG: Message routed successfully.
2010-06-15 15:22:05 [17069] [7] DEBUG: sms_router: handling message
(0x81b5208 vs 0x81b5208)

2010-06-15 15:22:05 [17069] [7] DEBUG: re-queing SMS not-yet-to-be 
resent

2010-06-15 15:22:05 [17069] [7] DEBUG: sms_router: handling message
(0x81b5208 vs 0x81b5208)

2010-06-15 15:22:05 [17069] [7] DEBUG: re-queing SMS not-yet-to-be 
resent

2010-06-15 15:22:05 [17069] [7] DEBUG: sms_router: time to sleep 30.00
secs.
2010-06-15 15:22:17 [17069] [1] DEBUG: Timeout for fd:53 appeares.
2010-06-15 15:22:17 [17069] [1] DEBUG: HTTP: Destroying HTTPClient area
0x81ae688.
2010-06-15 15:22:17 [17069] [1] DEBUG: HTTP: Destroying HTTPClient for
`127.0.0.1'.
2010-06-15 15:22:35 [17069] [7] DEBUG: sms_router: gwlist_len = 1
2010-06-15 15:22:35 [17069] [7] DEBUG: sms_router: handling message
(0x81b5208 vs 0x81b5208)

2010-06-15 15:22:35 [17069] [7] DEBUG: re-queing SMS not-yet-to-be 
resent

2010-06-15 15:22:35 [17069] [7] DEBUG: sms_router: handling message
(0x81b5208 vs 0x81b5208)

2010-06-15 15:22:35 [17069] [7] DEBUG: re-queing SMS not-yet-to-be 
resent

2010-06-15 15:22:35 [17069] [7] DEBUG: sms_router: time to sleep 30.00
secs.
2010-06-15 15:23:05 [17069] [7] DEBUG: sms_router: gwlist_len = 1
2010-06-15 15:23:05 [17069] [7] DEBUG: sms_router: handling message
(0x81b5208 vs 0x81b5208)
2010-06-15 15:23:05 [17069] [7] DEBUG: Message routed successfully.

smsbox ::
:::::::::

2010-06-15 15:19:29 [17138] [0] DEBUG: Started thread 4
(gw/smsbox.c:obey_request_thread)
2010-06-15 15:19:29 [17138] [5] DEBUG: Thread 5
(gw/smsbox.c:url_result_thread) maps to pid 17138.
2010-06-15 15:19:29 [17138] [0] DEBUG: Started thread 5
(gw/smsbox.c:url_result_thread)
2010-06-15 15:19:29 [17138] [0] DEBUG: Started thread 6
(gw/smsbox.c:http_queue_thread)
2010-06-15 15:19:29 [17138] [0] DEBUG: Connecting to <127.0.0.1>
2010-06-15 15:19:29 [17138] [6] DEBUG: Thread 6
(gw/smsbox.c:http_queue_thread) maps to pid 17138.
2010-06-15 15:19:29 [17138] [0] INFO: Connected to bearerbox at
localhost port 13001.
2010-06-15 15:19:29 [17138] [0] DEBUG: Started thread 7
(gw/heartbeat.c:heartbeat_thread)
2010-06-15 15:19:29 [17138] [7] DEBUG: Thread 7
(gw/heartbeat.c:heartbeat_thread) maps to pid 17138.
2010-06-15 15:19:29 [17138] [2] DEBUG: Thread 2
(gwlib/http.c:server_thread) maps to pid 17138.
2010-06-15 15:21:01 [17138] [2] DEBUG: HTTP: Creating HTTPClient for
`127.0.0.1'.
2010-06-15 15:21:01 [17138] [2] DEBUG: HTTP: Created HTTPClient area
0x815b080.
2010-06-15 15:21:01 [17138] [3] INFO: smsbox: Got HTTP request
</cgi-bin/sendsms> from <127.0.0.1>
2010-06-15 15:21:01 [17138] [3] INFO: sendsms used by <tester>
2010-06-15 15:21:01 [17138] [3] INFO: sendsms
sender:<tester:+4915779******> (127.0.0.1) to:<+4917649******> msg:<das
ist eine sms>
2010-06-15 15:21:01 [17138] [3] DEBUG: Stored UUID
61bea839-e84d-42c4-a60a-021e16c9bc74
2010-06-15 15:21:01 [17138] [3] DEBUG: message length 16, sending 1
messages
2010-06-15 15:21:01 [17138] [3] DEBUG: Status: 202 Answer: <Sent.>

2010-06-15 15:21:01 [17138] [3] DEBUG: Delayed reply - wait for 
bearerbox

2010-06-15 15:21:01 [17138] [0] DEBUG: Got ACK (0) of
61bea839-e84d-42c4-a60a-021e16c9bc74
2010-06-15 15:21:01 [17138] [0] DEBUG: HTTP: Resetting HTTPClient for
`127.0.0.1'.
2010-06-15 15:21:05 [17138] [3] INFO: smsbox: Got HTTP request
</favicon.ico> from <127.0.0.1>
2010-06-15 15:21:05 [17138] [3] DEBUG: Status: 404 Answer: <Unknown
request.>
2010-06-15 15:21:05 [17138] [3] DEBUG: HTTP: Resetting HTTPClient for
`127.0.0.1'.
2010-06-15 15:21:09 [17138] [3] INFO: smsbox: Got HTTP request
</favicon.ico> from <127.0.0.1>
2010-06-15 15:21:09 [17138] [3] DEBUG: Status: 404 Answer: <Unknown
request.>
2010-06-15 15:21:09 [17138] [3] DEBUG: HTTP: Resetting HTTPClient for
`127.0.0.1'.
2010-06-15 15:21:17 [17138] [1] DEBUG: HTTP: Destroying HTTPClient area
0x815b080.
2010-06-15 15:21:17 [17138] [1] DEBUG: HTTP: Destroying HTTPClient for
`127.0.0.1'.

[..snip..]






























					Reply via email to