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" <ts...@gmx.de> > To: "Nikos Balkanas" <nbalka...@gmail.com> > Cc: <users@kannel.org> > 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..]
sms_errors_masked.ods
Description: application/vnd.oasis.opendocument.spreadsheet
