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'.



thanks,
thomas





Nikos wrote:


Hi,

Broken sender and destination are fine. Just ignore. My mistake. SMS is
deleted at that point.

If this happens only during startup, and just the first couple of SMS are
requeued, it is no biggie. To avoid it check user's guide about need-sleep.

If needed, just send modem logs. No other logs are necessary.

BR,
Nikos

[..snip..]

Reply via email to