Dear All,
I hope you can help me out with one problem that I have with Kannel. Let me explain what kind of system I set up: I have two machines, where two GSM modems are installed and where Kannel works with my applications, that interact with Kannel by means of two services (each service triggers one application). The first service is the default one, and the other one is triggered whenever Kannel receives a SMS with one specific word. The reason for having two machines is to provide my system with some redundancy, so that the two of them are programmed to send the other one a SMS twice per day, at certain hours. (I have done it with the crontab tool that Linux provides). This SMS will say one machine that the other is alive. For sending them I have written one script in PERL, which is the task programmed in the crontab file and that uses the HTTP Kannel interface to send the message. I have tested all of this separately and everything works fine. The problem I am finding is that the whole system works pretty well for two or three days, but after that, it apparently hangs. By checking Kannel logs, I have seen the following: - Bearerbox Access log: it stops when the last redundancy message is received. - Bearerbox log: After the last redundancy message is received it continues, but when the time of sending more redundancy messages comes, I see "Dumping x messages to store", where x increases with every send attempt. (I guess messages are queued). - Smsbox Access log: After the system hangs, I can see that the HTTP requests for sending red. Messages are added. - Smsbox log: I have not seen something special there, but it stops after the system hangs. - And the last thing; my services do not work anymore after the system hangs, obviously, but I have checked the Kannel status by using the http interface and I can see it is still connected to the smsbox , but some messages have been indeed queued. And it appears strange to me that both machines hang at the very same moment. Hereby I attach the portion of the logs I was speaking about above and the configuration file I am using. Thank you very much in advance for your help, Carlos. ------------------------------ Carlos Ghabrous Larrea CERN - European Organization for Nuclear Research 1211 Geneva - Switzerland Department/Group/Section: IT/CS/TC Office: Building 31-S025 Office Phone: +41 22 76 71322 Mobile: +41 76 487 0002 ------------------------------
bearerboxAccess.log 2008-03-07 20:30:05 Sent SMS [SMSC:AT2[/dev/ttyS2]] [SVC:ghabsms] [ACT:] [BINF:] [from:+41764875825] [to:41764872712] [flags:-1:0:-1:-1:-1] [msg:10:redundancy] [udh:0:] 2008-03-07 20:30:14 Receive SMS [SMSC:AT2[/dev/ttyS2]] [SVC:] [ACT:] [BINF:] [from:+41764872712] [to:+41764875825] [flags:-1:0:-1:0:-1] [msg:10:redundancy] [udh:0:] bearerbox.log 2008-03-07 20:30:02 [6630] [9] DEBUG: boxc_receiver: sms received 2008-03-07 20:30:02 [6630] [9] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2008-03-07 20:30:14 [6630] [10] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2008-03-07 20:30:14 [6630] [10] DEBUG: boxc_sender: sent message to <127.0.0.1> 2008-03-07 20:30:14 [6630] [9] DEBUG: boxc_receiver: got ack 2008-03-07 20:30:16 [6630] [8] DEBUG: Dumping 0 messages to store 2008-03-07 20:30:30 [6630] [9] DEBUG: boxc_receiver: heartbeat with load value 0 received 2008-03-08 08:30:01 [6630] [9] DEBUG: boxc_receiver: sms received 2008-03-08 08:30:01 [6630] [9] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2008-03-08 08:30:13 [6630] [8] DEBUG: Dumping 1 messages to store 2008-03-08 20:30:01 [6630] [9] DEBUG: boxc_receiver: sms received 2008-03-08 20:30:01 [6630] [9] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2008-03-08 20:30:20 [6630] [8] DEBUG: Dumping 2 messages to store 2008-03-09 08:30:01 [6630] [9] DEBUG: boxc_receiver: sms received 2008-03-09 08:30:01 [6630] [9] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2008-03-09 08:30:18 [6630] [8] DEBUG: Dumping 3 messages to store 2008-03-09 20:30:02 [6630] [9] DEBUG: boxc_receiver: sms received 2008-03-09 20:30:02 [6630] [9] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2008-03-09 20:30:15 [6630] [8] DEBUG: Dumping 4 messages to store 2008-03-10 08:30:02 [6630] [9] DEBUG: boxc_receiver: sms received 2008-03-10 08:30:02 [6630] [9] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2008-03-10 08:30:22 [6630] [8] DEBUG: Dumping 5 messages to store smsboxAccess.log 2008-03-07 08:30:01 send-SMS request added - sender:ghabsms:+41764875825 127.0.0.1 target:41764872712 request: 'redundancy' 2008-03-07 08:30:14 SMS HTTP-request sender:+41764872712 request: '' url: 'http://localhost/redundancy/test_red.php?phone=%2B41764872712&text=redundancy&sent_time=2008-03-07+07:30:01' reply: 200 '<< successful >>' 2008-03-07 20:30:02 send-SMS request added - sender:ghabsms:+41764875825 127.0.0.1 target:41764872712 request: 'redundancy' 2008-03-07 20:30:15 SMS HTTP-request sender:+41764872712 request: '' url: 'http://localhost/redundancy/test_red.php?phone=%2B41764872712&text=redundancy&sent_time=2008-03-07+19:30:01' reply: 200 '<< successful >>' 2008-03-08 08:30:01 send-SMS request added - sender:ghabsms:+41764875825 127.0.0.1 target:41764872712 request: 'redundancy' 2008-03-08 20:30:01 send-SMS request added - sender:ghabsms:+41764875825 127.0.0.1 target:41764872712 request: 'redundancy' 2008-03-09 08:30:01 send-SMS request added - sender:ghabsms:+41764875825 127.0.0.1 target:41764872712 request: 'redundancy' 2008-03-09 20:30:02 send-SMS request added - sender:ghabsms:+41764875825 127.0.0.1 target:41764872712 request: 'redundancy' 2008-03-10 08:30:02 send-SMS request added - sender:ghabsms:+41764875825 127.0.0.1 target:41764872712 request: 'redundancy' smsbox.log 2008-03-07 20:30:01 [6639] [2] DEBUG: HTTP: Creating HTTPClient for `127.0.0.1'. 2008-03-07 20:30:01 [6639] [2] DEBUG: HTTP: Created HTTPClient area 0x9384bf8. 2008-03-07 20:30:02 [6639] [3] INFO: smsbox: Got HTTP request </cgi-bin/sendsms> from <127.0.0.1> 2008-03-07 20:30:02 [6639] [3] INFO: sendsms used by <ghabsms> 2008-03-07 20:30:02 [6639] [3] INFO: sendsms sender:<ghabsms:+41764875825> (127.0.0.1) to:< 41764872712> msg:<redundancy> 2008-03-07 20:30:02 [6639] [3] DEBUG: Stored UUID 87691e43-abc3-4816-8268-77dc0d779307 2008-03-07 20:30:02 [6639] [3] DEBUG: message length 10, sending 1 messages 2008-03-07 20:30:02 [6639] [3] DEBUG: Status: 202 Answer: <Sent.> 2008-03-07 20:30:02 [6639] [3] DEBUG: Delayed reply - wait for bearerbox 2008-03-07 20:30:02 [6639] [0] DEBUG: Got ACK (0) of 87691e43-abc3-4816-8268-77dc0d779307 2008-03-07 20:30:02 [6639] [0] DEBUG: HTTP: Destroying HTTPClient area 0x9384bf8. 2008-03-07 20:30:02 [6639] [0] DEBUG: HTTP: Destroying HTTPClient for `127.0.0.1'. 2008-03-07 20:30:14 [6639] [4] INFO: Starting to service <redundancy> from <+41764872712> to <+41764875825> 2008-03-07 20:30:14 [6639] [9] DEBUG: Parsing URL `http://localhost/redundancy/test_red.php?phone=%2B41764872712&text=redundancy&sent_time=2008-03-07+19:30:01': 2008-03-07 20:30:14 [6639] [9] DEBUG: Scheme: http:// 2008-03-07 20:30:14 [6639] [9] DEBUG: Host: localhost 2008-03-07 20:30:14 [6639] [9] DEBUG: Port: 80 2008-03-07 20:30:14 [6639] [9] DEBUG: Username: (null) 2008-03-07 20:30:14 [6639] [9] DEBUG: Password: (null) 2008-03-07 20:30:14 [6639] [9] DEBUG: Path: /redundancy/test_red.php 2008-03-07 20:30:14 [6639] [9] DEBUG: Query: phone=%2B41764872712&text=redundancy&sent_time=2008-03-07+19:30:01 2008-03-07 20:30:14 [6639] [9] DEBUG: Fragment: (null) 2008-03-07 20:30:14 [6639] [9] DEBUG: HTTP: Opening connection to `localhost:80' (fd=28). 2008-03-07 20:30:14 [6639] [9] DEBUG: Socket connecting 2008-03-07 20:30:14 [6639] [8] DEBUG: Get info about connecting socket 2008-03-07 20:30:14 [6639] [8] DEBUG: HTTP: Sending request: 2008-03-07 20:30:14 [6639] [8] DEBUG: Octet string at 0x93819a8: 2008-03-07 20:30:14 [6639] [8] DEBUG: len: 151 2008-03-07 20:30:14 [6639] [8] DEBUG: size: 1024 2008-03-07 20:30:14 [6639] [8] DEBUG: immutable: 0 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 47 45 54 20 2f 72 65 64 75 6e 64 61 6e 63 79 2f GET /redundancy/ 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 74 65 73 74 5f 72 65 64 2e 70 68 70 3f 70 68 6f test_red.php?pho 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 6e 65 3d 25 32 42 34 31 37 36 34 38 37 32 37 31 ne=%2B4176487271 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 32 26 74 65 78 74 3d 72 65 64 75 6e 64 61 6e 63 2&text=redundanc 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 79 26 73 65 6e 74 5f 74 69 6d 65 3d 32 30 30 38 y&sent_time=2008 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 2d 30 33 2d 30 37 2b 31 39 3a 33 30 3a 30 31 20 -03-07+19:30:01 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 48 54 54 50 2f 31 2e 31 0d 0a 48 6f 73 74 3a 20 HTTP/1.1..Host: 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 6c 6f 63 61 6c 68 6f 73 74 0d 0a 55 73 65 72 2d localhost..User- 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 41 67 65 6e 74 3a 20 4b 61 6e 6e 65 6c 2f 31 2e Agent: Kannel/1. 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 34 2e 31 0d 0a 0d 0a 4.1.... 2008-03-07 20:30:14 [6639] [8] DEBUG: Octet string dump ends. 2008-03-07 20:30:14 [6639] [8] DEBUG: HTTP: Status line: <HTTP/1.1 200 OK> 2008-03-07 20:30:14 [6639] [8] DEBUG: HTTP: Received response: 2008-03-07 20:30:14 [6639] [8] DEBUG: Octet string at 0x9380fb8: 2008-03-07 20:30:14 [6639] [8] DEBUG: len: 175 2008-03-07 20:30:14 [6639] [8] DEBUG: size: 1024 2008-03-07 20:30:14 [6639] [8] DEBUG: immutable: 0 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 44 61 74 65 3a 20 46 72 69 2c 20 30 37 20 4d 61 Date: Fri, 07 Ma 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 72 20 32 30 30 38 20 31 39 3a 33 30 3a 31 34 20 r 2008 19:30:14 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 47 4d 54 0d 0a 53 65 72 76 65 72 3a 20 41 70 61 GMT..Server: Apa 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 63 68 65 2f 32 2e 30 2e 35 32 20 28 52 65 64 20 che/2.0.52 (Red 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 48 61 74 29 0d 0a 58 2d 50 6f 77 65 72 65 64 2d Hat)..X-Powered- 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 42 79 3a 20 50 48 50 2f 34 2e 33 2e 39 0d 0a 43 By: PHP/4.3.9..C 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 6f 6e 74 65 6e 74 2d 4c 65 6e 67 74 68 3a 20 30 ontent-Length: 0 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 0d 0a 43 6f 6e 6e 65 63 74 69 6f 6e 3a 20 63 6c ..Connection: cl 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 6f 73 65 0d 0a 43 6f 6e 74 65 6e 74 2d 54 79 70 ose..Content-Typ 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 65 3a 20 74 65 78 74 2f 68 74 6d 6c 3b 20 63 68 e: text/html; ch 2008-03-07 20:30:14 [6639] [8] DEBUG: data: 61 72 73 65 74 3d 55 54 46 2d 38 0d 0a 0d 0a arset=UTF-8.... 2008-03-07 20:30:14 [6639] [8] DEBUG: Octet string dump ends. 2008-03-07 20:30:15 [6639] [5] INFO: No reply sent, denied. 2008-03-08 08:30:01 [6639] [2] DEBUG: HTTP: Creating HTTPClient for `127.0.0.1'. 2008-03-08 08:30:01 [6639] [2] DEBUG: HTTP: Created HTTPClient area 0x93819a8. 2008-03-08 08:30:01 [6639] [3] INFO: smsbox: Got HTTP request </cgi-bin/sendsms> from <127.0.0.1> 2008-03-08 08:30:01 [6639] [3] INFO: sendsms used by <ghabsms> 2008-03-08 08:30:01 [6639] [3] INFO: sendsms sender:<ghabsms:+41764875825> (127.0.0.1) to:< 41764872712> msg:<redundancy> 2008-03-08 08:30:01 [6639] [3] DEBUG: Stored UUID 953de4ff-22a7-41c3-a785-bf12df1efa09 2008-03-08 08:30:01 [6639] [3] DEBUG: message length 10, sending 1 messages 2008-03-08 08:30:01 [6639] [3] DEBUG: Status: 202 Answer: <Sent.> 2008-03-08 08:30:01 [6639] [3] DEBUG: Delayed reply - wait for bearerbox 2008-03-08 08:30:01 [6639] [0] DEBUG: Got ACK (0) of 953de4ff-22a7-41c3-a785-bf12df1efa09 2008-03-08 08:30:01 [6639] [0] DEBUG: HTTP: Destroying HTTPClient area 0x93819a8. 2008-03-08 08:30:01 [6639] [0] DEBUG: HTTP: Destroying HTTPClient for `127.0.0.1'. kannel status Kannel bearerbox version `1.4.1'. Build `Feb 8 2008 15:16:16', compiler `3.4.6 20060404 (Red Hat 3.4.6-9)'. System Linux, release 2.6.9-67.0.4.EL.cern, version #1 Mon Feb 4 09:56:43 CET 2008, machine i686. Hostname pcittel024.cern.ch, IP 137.138.35.60. Libxml version 2.6.16. Using OpenSSL 0.9.7a Feb 19 2003. Using native malloc. Status: running, uptime 3d 18h 38m 10s WDP: received 0 (0 queued), sent 0 (0 queued) SMS: received 3 (0 queued), sent 8 (0 queued), store size 5 SMS: inbound 0.00 msg/sec, outbound 0.00 msg/sec DLR: 0 queued, using internal storage Box connections: smsbox:(none), IP 127.0.0.1 (0 queued), (on-line 3d 18h 37m 58s) SMSC connections: unknown AT2[/dev/ttyS2] (online 326286s, rcvd 3, sent 8, failed 0, queued 5 msgs)
smskannel.conf
Description: smskannel.conf
