Emmanuel,
You are running FAKE smsc in a non-standard way. It will only work when the
fakesmsc client is connected, and when sending from the client, it is meant
to send them back to the client. Instead you are redirecting to smppbox. I
don't know if that will work. I think that the client doesn't send normal
packets to FAKE smsc. It would stand to reason that fakesmsc doesn't respond
with normal packages to the client, or in this case smppbox. Poor smppbox
could get fits over it ;-). Check your packets and smppbox logs.
BR,
Nikos
----- Original Message -----
From: Emmanuel CHANSON
To: Nikos Balkanas ; users
Sent: Thursday, October 14, 2010 12:32 AM
Subject: Re: Strange behavior on kannel (fakesmsc + bearerbox + opensmppbox
+ smppclient), more message than ordered sent from smppbox
I am still trying to understand the issue.
Running a test where fakesmsc sent 20+50+500 SMS I got a freeze on smppbox
after lets's 5 minutes:
smppbox only sent 394 SMS to smppclient
# ./stat.sh
Bearerbox - Received SMS: 570
Bearerbox - Sent SMS: 294
Access - Receive SMS: 570
Access - Sent SMS: 294
Smppbox - Deliver_sm: 394
Smppbox - Deliver_sm_resp: 394
Smppbox - Submit_sm: 294
Smppbox - Submit_sm_resp: 294
#
Suddenly I saw all the remaining SMS sent from smppbox to smppclient in one
two seconds, and 100 SMS more than what I have ordered (with message from
the first (20 SMS) and second (50 SMS) test.
# ./stat.sh
Bearerbox - Received SMS: 570
Bearerbox - Sent SMS: 394
Access - Receive SMS: 570
Access - Sent SMS: 394
Smppbox - Deliver_sm: 670
Smppbox - Deliver_sm_resp: 670
Smppbox - Submit_sm: 394
Smppbox - Submit_sm_resp: 394
#
At the end I got 100 unordered SMS sent arrived on fakesmsc (because smppbox
sent these new 100 SMS to my smppclient and answer back for each SMS
received):
Bearerbox - Received SMS: 570
Bearerbox - Sent SMS: 670
Access - Receive SMS: 570
Access - Sent SMS: 670
Smppbox - Deliver_sm: 670
Smppbox - Deliver_sm_resp: 670
Smppbox - Submit_sm: 670
Smppbox - Submit_sm_resp: 670
Please can someone confirm this behavior ?
It is an issue and load testing can not be done using SMPPbox
Or something is wrong in my Kannel architecture.
Can you give me some pointers in order to investiguate more if no one can
reproduce this ?
Regards,
Emmanuel
2010/10/13 Emmanuel CHANSON <emmanuelchan...@gmail.com>
Yes Nikos,
I downloaded and installed kannel & opensmppbox this week from SVN site.
Emmanuel
2010/10/13 Nikos Balkanas <nbalka...@gmail.com>
Hi,
Do you use latest smppbox svn? I remember that sometime back it had some ACK
issues and bb would resend SMS. But it has been fixed since.
BR,
Nikos
----- Original Message ----- From: Emmanuel CHANSON
To: users
Sent: Wednesday, October 13, 2010 7:21 AM
Subject: Strange behavior on kannel (fakesmsc + bearerbox + opensmppbox +
smppclient), more message than ordered sent from smppbox
Using Fakesmsc and Opensmppbox for load testing of an SMPP application I
notice strange behavior of Kannel (surrely bad configuration, I hope)
Fakesmsc <--> Bearerbox <--> Opensmppbox <--> Smpp client
Config file at the end:
Test: When I send 1 SMS from Fakesmsc (5656) to my SMPP application (1065)
this application answer back with a SMS to the original sender. ie for 1 SMS
sent from fakesmsc 1 should be received.
From fakesmsc I send 50 SMS with this command:
# /usr/bin/fakesmsc -r 10001 -H 127.0.0.1 -i 0.2 -m 50 "5656 1065 text 50
SMS"
Below output from a script that parse the log file under /var/log:
So I see exactly the 50 SMS sent to my smpp application (50 SMS also on its
side)
# /usr/bin/fakesmsc -r 10001 -H 127.0.0.1 -i 0.2 -m 50 "5656 1065 text 50
SMS"
2010-10-13 14:31:08 [29344] [0] INFO: Debug_lvl = -1, log_file = <none>,
log_lvl = 0
2010-10-13 14:31:08 [29344] [0] INFO: Host 127.0.0.1 Port 10001 interval
0.200 max-messages 50
2010-10-13 14:31:08 [29344] [0] INFO: fakesmsc starting
2010-10-13 14:31:08 [29344] [0] DEBUG: Connecting to <127.0.0.1>
2010-10-13 14:31:08 [29344] [0] DEBUG: fakesmsc: sent message 1
2010-10-13 14:31:08 [29344] [0] DEBUG: fakesmsc: sent message 2
...
2010-10-13 14:31:18 [29344] [0] DEBUG: fakesmsc: sent message 49
2010-10-13 14:31:18 [29344] [0] INFO: fakesmsc: sent message 50
2010-10-13 14:31:19 [29344] [0] DEBUG: Got message 1: <1065 5656 text 50
SMS>
2010-10-13 14:31:20 [29344] [0] DEBUG: Got message 2: <1065 5656 text 50
SMS>
...
2010-10-13 14:32:07 [29344] [0] DEBUG: Got message 49: <1065 5656 text 50
SMS>
2010-10-13 14:32:08 [29344] [0] INFO: Got message 50: <1065 5656 text 50
SMS>
# ./stat.sh
Bearerbox - Received SMS: 50 (cat bearerbox.log | grep "new
message" | wc -l)
Bearerbox - Sent SMS: 50 (cat bearerbox.log | grep
"sending message" | wc -l)
Access - Receive SMS: 50 (cat access.log | grep "Receive
SMS" | wc -l)
Access - Sent SMS: 50 (cat access.log | grep "Sent
SMS" | wc -l)
Smppbox - Deliver_sm: 50 (cat opensmppbox.log | grep
"deliver_sm" | wc -l)
Smppbox - Submit_sm: 50 (cat opensmppbox.log | grep
"submit_sm" | wc -l)
But some minutes after I see new messages sent from kannel (not from
fakesmsc), 31 more !!
...
2010-10-13 14:37:31 [29344] [0] DEBUG: Got message 51: <1065 5656 text 50
SMS>
2010-10-13 14:37:32 [29344] [0] DEBUG: Got message 52: <1065 5656 text 50
SMS>
...
2010-10-13 14:38:00 [29344] [0] DEBUG: Got message 80: <1065 5656 text 50
SMS>
2010-10-13 14:38:01 [29344] [0] DEBUG: Got message 81: <1065 5656 text 50
SMS>
I close fakesmsc:
^C2010-10-13 14:55:59 [29344] [0] INFO: fakesmsc: 50 messages sent and 81
received
2010-10-13 14:55:59 [29344] [0] INFO: fakesmsc: total running time 1490.5
seconds
2010-10-13 14:55:59 [29344] [0] INFO: fakesmsc: from first to last sent
message 9.8 s, 5.0 msgs/s
2010-10-13 14:55:59 [29344] [0] INFO: fakesmsc: from first to last received
message 402.0 s, 0.2 msgs/s
2010-10-13 14:55:59 [29344] [0] INFO: fakesmsc: terminating
#
logs parsed by the script stat.sh
# ./stat.sh
Bearerbox - Received SMS: 50
Bearerbox - Sent SMS: 81 --> bearerbox sent 31 new messages to
fakesmsc !!!!
Access - Receive SMS: 50
Access - Sent SMS: 81 --> 31 new messages logged in access.log
Smppbox - Deliver_sm: 81 --> smppbox received these 31 new
messages forward them to smpp client
Smppbox - Submit_sm: 81 --> and smppclient answer back with 31
new message going to fakesmsc
From where comes theses messages ?
It seems it is smppbox that seems to receive 31 new messages but from where
????!
I check in detail opensmppbox.log: Ifound that smppbox shutdown and
restarted after the 50 messages have been sent at 14:32, 4 minutes later it
restarted:
2010-10-13 14:32:08 [25805] [267] DEBUG: message_id: "b91ac635"
2010-10-13 14:32:08 [25805] [267] DEBUG: SMPP PDU dump ends.
2010-10-13 14:36:21 [25805] [266] DEBUG: Thread 266
(opensmppbox.c:smpp_to_bearerbox) terminates.
2010-10-13 14:36:21 [25805] [265] DEBUG: Thread 265 (opensmppbox.c:function)
terminates.
2010-10-13 14:36:21 [25805] [0] DEBUG: Started thread 269
(opensmppbox.c:function)
2010-10-13 14:36:21 [25805] [269] DEBUG: Thread 269 (opensmppbox.c:function)
maps to pid 25805.
2010-10-13 14:36:21 [25805] [269] INFO: Client connected from
<163.176.17.238>
2010-10-13 14:36:21 [25805] [269] DEBUG: Connecting to <127.0.0.1>
2010-10-13 14:36:21 [25805] [269] INFO: Connected to bearerbox at localhost
port 13001.
2010-10-13 14:36:21 [25805] [269] DEBUG: Started thread 270
(opensmppbox.c:smpp_to_bearerbox)
2010-10-13 14:36:21 [25805] [270] DEBUG: Thread 270
(opensmppbox.c:smpp_to_bearerbox) maps to pid 25805.
2010-10-13 14:36:26 [25805] [270] DEBUG: SMPP[smppbox]: Got PDU:
2010-10-13 14:36:26 [25805] [270] DEBUG: SMPP PDU 0xb2f814b0 dump:
2010-10-13 14:36:26 [25805] [270] DEBUG: type_name: bind_receiver
2010-10-13 14:36:26 [25805] [270] DEBUG: command_id: 1 = 0x00000001
2010-10-13 14:36:26 [25805] [270] DEBUG: command_status: 0 = 0x00000000
2010-10-13 14:36:26 [25805] [270] DEBUG: sequence_number: 1 = 0x00000001
2010-10-13 14:36:26 [25805] [270] DEBUG: system_id: "mmg"
2010-10-13 14:36:26 [25805] [270] DEBUG: password: "mmg"
2010-10-13 14:36:26 [25805] [270] DEBUG: system_type: "mmg"
2010-10-13 14:36:26 [25805] [270] DEBUG: interface_version: 52 =
0x00000034
2010-10-13 14:36:26 [25805] [270] DEBUG: addr_ton: 0 = 0x00000000
2010-10-13 14:36:26 [25805] [270] DEBUG: addr_npi: 0 = 0x00000000
2010-10-13 14:36:26 [25805] [270] DEBUG: address_range: NULL
2010-10-13 14:36:26 [25805] [270] DEBUG: SMPP PDU dump ends.
2010-10-13 14:36:26 [25805] [270] DEBUG: SMPP[mmg]: Sending PDU:
2010-10-13 14:36:26 [25805] [270] DEBUG: SMPP PDU 0xb2f82258 dump:
2010-10-13 14:36:26 [25805] [270] DEBUG: type_name: bind_receiver_resp
2010-10-13 14:36:26 [25805] [270] DEBUG: command_id: 2147483649 =
0x80000001
2010-10-13 14:36:26 [25805] [270] DEBUG: command_status: 0 = 0x00000000
2010-10-13 14:36:26 [25805] [270] DEBUG: sequence_number: 1 = 0x00000001
2010-10-13 14:36:26 [25805] [270] DEBUG: system_id: "opensmppbox"
2010-10-13 14:36:26 [25805] [270] DEBUG: SMPP PDU dump ends.
2010-10-13 14:37:10 [25805] [268] DEBUG: Thread 268
(opensmppbox.c:smpp_to_bearerbox) terminates.
2010-10-13 14:37:10 [25805] [267] DEBUG: Thread 267 (opensmppbox.c:function)
terminates.
2010-10-13 14:37:10 [25805] [269] INFO: We received an SMS message.
2010-10-13 14:37:10 [25805] [269] DEBUG: message length 6, sending 1 message
2010-10-13 14:37:10 [25805] [269] DEBUG: SMPP[mmg]: Sending PDU:
2010-10-13 14:37:10 [25805] [269] DEBUG: SMPP PDU 0xb2f855d0 dump:
2010-10-13 14:37:10 [25805] [269] DEBUG: type_name: deliver_sm
2010-10-13 14:37:10 [25805] [269] DEBUG: command_id: 5 = 0x00000005
2010-10-13 14:37:10 [25805] [269] DEBUG: command_status: 0 = 0x00000000
2010-10-13 14:37:10 [25805] [269] DEBUG: sequence_number: 0 = 0x00000000
2010-10-13 14:37:10 [25805] [269] DEBUG: service_type: NULL
2010-10-13 14:37:10 [25805] [269] DEBUG: source_addr_ton: 2 = 0x00000002
2010-10-13 14:37:10 [25805] [269] DEBUG: source_addr_npi: 1 = 0x00000001
2010-10-13 14:37:10 [25805] [269] DEBUG: source_addr: "5656"
2010-10-13 14:37:10 [25805] [269] DEBUG: dest_addr_ton: 2 = 0x00000002
2010-10-13 14:37:10 [25805] [269] DEBUG: dest_addr_npi: 1 = 0x00000001
2010-10-13 14:37:10 [25805] [269] DEBUG: destination_addr: "1065"
Config file:
kannel;conf:
...
# SMSC Fake
group = smsc
smsc = fake
smsc-id = "FakeSMSC-1"
allowed-smsc-id = "FakeSMSC-1"
host = localhost
port = 10001
group = smsbox-route
smsbox-id = mmg
smsc-id = FakeSMSC-1
opensmppbox.conf:
...
group = core
dlr-storage = mysql
# this group defines your opensmppbox
group = opensmppbox
# our boxc type
opensmppbox-id = smppbox
# the port to listen on for smpp connections
opensmppbox-port = 6201
# we connect to the following host as a box
bearerbox-host = localhost
bearerbox-port = 13001
log-level = 0
log-file = /var/log/kannel/opensmppbox.log
our-system-id = opensmppbox
route-to-smsc = FakeSMSC-1
# see sample smpplogins.txt
smpp-logins = "/etc/kannel/smpplogins.txt"
smpplogins.txt
mmg mmg mmg
Regards,
Emmanuel
