Hi,
I believe you have solved your DLR matching. If not, comment out your smsc
logfile and repost your detailed bb logs.
BR,
Nikos
----- Original Message -----
From: Anshu Sah
To: Nikos Balkanas ; users
Sent: Sunday, October 24, 2010 10:30 PM
Subject: WARNING: smsbox_list empty! && Unable to Translate DLR with
SMPPv3.3 | UPDATED
Hello Nikos,
I am sending my full investigation below. I am getting 2 errors with Kannel
& SMPP v3.3. I think both problems has some link.
1. WARNING: smsbox_list empty!
2. Unable to Translate DLR
This error comes while receiving DLR, While sending SMS It has no problem.
I am using 2 SMSC with 2 Session Each, & 2 SMSBOXs. 1 SMSBOX for 2 SMSC of
Same Login ID. , I am pushing & Receiving DLR using SqlBox.
I confirmed to my SMSC they send "DECIMAL MessageID" while acknowledgement &
delivery report. I get the acknowledge saved in sent_sms table, But DLR get
saved in Kannel store.
I am also sending you the Config parameters
Regards
Anshu Sah
SMPP Group
group=smsc
smsc=smpp
smsc-id=SMStata
allowed-smsc-id = SMStata2;SMStata
#preferred-smsc-id = SMStata
host = xxxxxxxxxxx
port = xxxx
receive-port = 12006
log-file = "/projects/kannel/logs/live/SMStata.log"
log-level = 0
smsc-username = "xxxxxxx"
smsc-password = "xxxxx"
transceiver-mode=1
interface-version = 33
source-addr-ton = 1
system-type = "null" # AS DIRECTED BY SMSC
msg-id-type = 0x00
alt-charset = "ISO-8859-1"
#SMSBOX
group = smsbox
smsbox-id = BOX-SMStata
bearerbox-host = localhost
sendsms-port = 11117
log-file = "/projects/kannel/logs/live/BOX-SMSCountry.log"
log-level = 0
#SMS BOX ROUTE SMStata
group = smsbox-route
smsbox-id = BOX-SMStata
smsc-id = SMStata;SMStata2
# Sqlbox configuration
group = sqlbox
id = sqlbox-db
smsbox-id = sqlbox
#global-sender = ""
bearerbox-host = localhost
bearerbox-port = 11010
smsbox-port = 11020
smsbox-port-ssl = false
sql-log-table = sent_sms
sql-insert-table = send_sms
log-file = "/projects/kannel/logs/live/sqlbox.log"
log-level = 0
STORE STATUS
SMS IDTypeTimeSenderReceiverSMSC IDBOX IDUDHMessage
42039504-84a3-4c8c-b0df-3dae227890b1MO2010-10-24
18:44:32+xxxxxxxxxxxxxxSMStata
id:0000452288 sub:001 dlvrd:000 submit date:1010242143 done date:1010250014
stat:EXPIRED err:027 text:xxxxxxx
WHILE AFTER RESTATING KANNEL & SMSBOX
2010-10-24 18:54:48 [3147] [4] WARNING: No translation found for
<id:0000452420 sub:001 dlvrd:000 submit date:1010242144 done date:1010250015
stat:EXPIRED err:027 text:xxxxxx> from <+xxxxxx> to <+xxxxxxxx>
2010-10-24 18:54:48 [3147] [4] ERROR: request failed
2010-10-24 18:54:48 [3147] [4] WARNING: No translation found for
<id:0000452340 sub:001 dlvrd:000 submit date:1010242143 done date:1010250015
stat:EXPIRED err:194 text:xxxxxxx> from <+xxxxxxx> to <+xxxxxxxxxx>
2010-10-24 18:54:48 [3147] [4] ERROR: request failed
SMSBOX
2010-10-24 18:04:20 [2771] [6] DEBUG: SMPP[SMSCountry]: throughput
(4.00,0.00)
2010-10-24 18:04:20 [2771] [6] DEBUG: SMPP[SMSCountry]: Got PDU:
2010-10-24 18:04:20 [2771] [6] DEBUG: SMPP PDU 0x7fc6b8013b00 dump:
2010-10-24 18:04:20 [2771] [6] DEBUG: type_name: submit_sm_resp
2010-10-24 18:04:20 [2771] [6] DEBUG: command_id: 2147483652 = 0x80000004
2010-10-24 18:04:20 [2771] [6] DEBUG: command_status: 0 = 0x00000000
2010-10-24 18:04:20 [2771] [6] DEBUG: sequence_number: 16 = 0x00000010
2010-10-24 18:04:20 [2771] [6] DEBUG: message_id: "0000453332"
2010-10-24 18:04:20 [2771] [6] DEBUG: SMPP PDU dump ends.
2010-10-24 18:04:20 [2771] [6] DEBUG: DLR[mysql]: Adding DLR smsc=SMSC1,
ts=453332, src=xxxxxxx, dst=+91xxxxxxxxx, mask=31, boxc=sqlbox
2010-10-24 18:04:20 [2771] [6] DEBUG: adding DLR entry into database
2010-10-24 18:04:20 [2771] [6] DEBUG: sql: INSERT INTO `kannel_dlr` (`smsc`,
`timestamp`, `source`, `destination`, `service`, `url`, `mask`, `boxc_id`,
`status`) VALUES (?, ?, ?, ?, ?, ?, ?, ?, 0)
2010-10-24 18:04:20 [2771] [6] DEBUG: SMSC[SMSCountry]: creating DLR message
2010-10-24 18:04:20 [2771] [6] DEBUG: SMSC[SMSCountry]: DLR =
xxxxxxxxxxxxxxxxxxxxx
2010-10-24 18:04:20 [2771] [6] DEBUG: SMPP[SMSCountry]: throughput
(4.00,0.00)
2010-10-24 18:04:27 [2771] [6] DEBUG: SMPP[SMSCountry]: throughput
(0.00,0.00)
2010-10-24 18:04:27 [2771] [6] DEBUG: SMPP[SMSCountry]: Got PDU:
2010-10-24 18:04:27 [2771] [6] DEBUG: SMPP PDU 0x7fc6b8013ed0 dump:
2010-10-24 18:04:27 [2771] [6] DEBUG: type_name: deliver_sm
2010-10-24 18:04:27 [2771] [6] DEBUG: command_id: 5 = 0x00000005
2010-10-24 18:04:27 [2771] [6] DEBUG: command_status: 0 = 0x00000000
2010-10-24 18:04:27 [2771] [6] DEBUG: sequence_number: 8 = 0x00000008
2010-10-24 18:04:27 [2771] [6] DEBUG: service_type: NULL
2010-10-24 18:04:27 [2771] [6] DEBUG: source_addr_ton: 1 = 0x00000001
2010-10-24 18:04:27 [2771] [6] DEBUG: source_addr_npi: 1 = 0x00000001
2010-10-24 18:04:27 [2771] [6] DEBUG: source_addr: "xxxxxxxxx"
2010-10-24 18:04:27 [2771] [6] DEBUG: dest_addr_ton: 1 = 0x00000001
2010-10-24 18:04:27 [2771] [6] DEBUG: dest_addr_npi: 1 = 0x00000001
2010-10-24 18:04:27 [2771] [6] DEBUG: destination_addr: "xxxxxxxxxxxxxxx"
2010-10-24 18:04:27 [2771] [6] DEBUG: esm_class: 0 = 0x00000000
2010-10-24 18:04:27 [2771] [6] DEBUG: protocol_id: 1 = 0x00000001
2010-10-24 18:04:27 [2771] [6] DEBUG: priority_flag: 3 = 0x00000003
2010-10-24 18:04:27 [2771] [6] DEBUG: schedule_delivery_time: NULL
2010-10-24 18:04:27 [2771] [6] DEBUG: validity_period: NULL
2010-10-24 18:04:27 [2771] [6] DEBUG: registered_delivery: 1 = 0x00000001
2010-10-24 18:04:27 [2771] [6] DEBUG: replace_if_present_flag: 0 =
0x00000000
2010-10-24 18:04:27 [2771] [6] DEBUG: data_coding: 0 = 0x00000000
2010-10-24 18:04:27 [2771] [6] DEBUG: sm_default_msg_id: 0 = 0x00000000
2010-10-24 18:04:27 [2771] [6] DEBUG: sm_length: 107 = 0x0000006b
2010-10-24 18:04:27 [2771] [6] DEBUG: short_message:
2010-10-24 18:04:27 [2771] [6] DEBUG: Octet string at 0x7fc6b8017ab0:
2010-10-24 18:04:27 [2771] [6] DEBUG: len: 107
2010-10-24 18:04:27 [2771] [6] DEBUG: size: 108
2010-10-24 18:04:27 [2771] [6] DEBUG: immutable: 0
2010-10-24 18:04:27 [2771] [6] DEBUG: data: 69 64 3a 30 30 30 30 34 35
33 33 33 30 20 73 75 id:0000453330 su
2010-10-24 18:04:27 [2771] [6] DEBUG: data: 62 3a 30 30 31 20 64 6c 76
72 64 3a 30 30 31 20 b:001 dlvrd:001
2010-10-24 18:04:27 [2771] [6] DEBUG: data: 73 75 62 6d 69 74 20 64 61
74 65 3a 31 30 31 30 submit date:1010
2010-10-24 18:04:27 [2771] [6] DEBUG: data: 32 34 32 33 33 34 20 64 6f
6e 65 20 64 61 74 65 242334 done date
2010-10-24 18:04:27 [2771] [6] DEBUG: data: 3a 31 30 31 30 32 34 32 33
33 34 20 73 74 61 74 :1010242334 stat
2010-10-24 18:04:27 [2771] [6] DEBUG: data: 3a 44 45 4c 49 56 52 44 20
65 72 72 3a 30 30 30 :DELIVRD err:000
2010-10-24 18:04:27 [2771] [6] DEBUG: data: 20 74 65 78 74 3a 48 65 6c
6c 6f text:Hello
2010-10-24 18:04:27 [2771] [6] DEBUG: Octet string dump ends.
2010-10-24 18:04:27 [2771] [6] DEBUG: SMPP PDU dump ends.
2010-10-24 18:04:27 [2771] [6] ERROR: SMPP[SMSCountry]: Mallformed addr
`xxxxxxx', expected all digits.
2010-10-24 18:04:27 [2771] [6] DEBUG: SMPP[SMSCountry]: Sending PDU:
2010-10-24 18:04:27 [2771] [6] DEBUG: SMPP PDU 0x7fc6b8013b00 dump:
2010-10-24 18:04:27 [2771] [6] DEBUG: type_name: deliver_sm_resp
2010-10-24 18:04:27 [2771] [6] DEBUG: command_id: 2147483653 = 0x80000005
2010-10-24 18:04:27 [2771] [6] DEBUG: command_status: 10 = 0x0000000a
2010-10-24 18:04:27 [2771] [6] DEBUG: sequence_number: 8 = 0x00000008
2010-10-24 18:04:27 [2771] [6] DEBUG: message_id: NULL
2010-10-24 18:04:27 [2771] [6] DEBUG: SMPP PDU dump ends.
BEARERBOX
2010-10-24 17:58:41 [2771] [0] INFO: Added logfile
`/projects/kannel/logs/live/core.log' with level `0'.
2010-10-24 17:58:41 [2771] [0] INFO: Started access logfile
`/projects/kannel/logs/live/access.log'.
2010-10-24 17:58:41 [2771] [0] INFO: SSL not supported, no SSL
initialization done.
2010-10-24 17:58:41 [2771] [0] INFO: HTTP: Opening server at port 11000.
2010-10-24 17:58:41 [2771] [0] DEBUG: Started thread 1
(gwlib/fdset.c:poller)
2010-10-24 17:58:41 [2771] [1] DEBUG: Thread 1 (gwlib/fdset.c:poller) maps
to pid 2771.
2010-10-24 17:58:41 [2771] [0] DEBUG: Started thread 2
(gwlib/http.c:server_thread)
2010-10-24 17:58:41 [2771] [2] DEBUG: Thread 2 (gwlib/http.c:server_thread)
maps to pid 2771.
2010-10-24 17:58:41 [2771] [2] DEBUG: HTTP: Including port 11000, fd 10 for
polling in server thread
2010-10-24 17:58:41 [2771] [0] DEBUG: Started thread 3
(gw/bb_http.c:httpadmin_run)
2010-10-24 17:58:41 [2771] [3] DEBUG: Thread 3 (gw/bb_http.c:httpadmin_run)
maps to pid 2771.
2010-10-24 17:58:41 [2771] [0] DEBUG: starting smsbox connection module
2010-10-24 17:58:41 [2771] [0] INFO: BOXC: 'smsbox-max-pending' not set,
using default (100).
2010-10-24 17:58:41 [2771] [0] DEBUG: Adding smsbox routing to id
<BOX-SMSC1> for smsc id <SMSC1>
2010-10-24 17:58:41 [2771] [0] DEBUG: Adding smsbox routing to id
<BOX-SMSC1> for smsc id <SMSC12>
2010-10-24 17:58:41 [2771] [0] DEBUG: Started thread 4
(gw/bb_boxc.c:sms_to_smsboxes)
2010-10-24 17:58:41 [2771] [4] DEBUG: Thread 4
(gw/bb_boxc.c:sms_to_smsboxes) maps to pid 2771.
2010-10-24 17:58:41 [2771] [0] DEBUG: Started thread 5
(gw/bb_boxc.c:smsboxc_run)
2010-10-24 17:58:41 [2771] [5] DEBUG: Thread 5 (gw/bb_boxc.c:smsboxc_run)
maps to pid 2771.
2010-10-24 17:58:41 [2771] [0] INFO: Set SMS resend frequency to 60 seconds.
2010-10-24 17:58:41 [2771] [0] INFO: SMS resend retry set to unlimited.
2010-10-24 17:58:41 [2771] [0] DEBUG: MO concatenated message handling
enabled
2010-10-24 17:58:41 [2771] [0] INFO: DLR rerouting for smsc id <SMSC1>
disabled.
2010-10-24 17:58:41 [2771] [0] INFO: Added logfile
`/projects/kannel/logs/live/SMSC1.log' with level `0'.
2010-10-24 17:58:41 [2771] [0] WARNING: SMPP: receive-port for transceiver
mode defined, ignoring.
2010-10-24 17:58:41 [2771] [0] DEBUG: Started thread 6
(gw/smsc/smsc_smpp.c:io_thread)
2010-10-24 17:58:41 [2771] [6] DEBUG: Thread 6
(gw/smsc/smsc_smpp.c:io_thread) maps to pid 2771.
2010-10-24 17:58:41 [2771] [6] INFO: Logging thread `6' to logfile
`/projects/kannel/logs/live/SMSC1.log' with level `0'.
2010-10-24 17:58:41 [2771] [0] INFO: DLR rerouting for smsc id <SMSC12>
disabled.
2010-10-24 17:58:41 [2771] [0] INFO: Added logfile
`/projects/kannel/logs/live/SMSC12.log' with level `0'.
2010-10-24 17:58:41 [2771] [0] WARNING: SMPP: receive-port for transceiver
mode defined, ignoring.
2010-10-24 17:58:41 [2771] [0] DEBUG: Started thread 7
(gw/smsc/smsc_smpp.c:io_thread)
2010-10-24 17:58:41 [2771] [7] DEBUG: Thread 7
(gw/smsc/smsc_smpp.c:io_thread) maps to pid 2771.
2010-10-24 17:58:41 [2771] [7] INFO: Logging thread `7' to logfile
`/projects/kannel/logs/live/SMSC12.log' with level `0'.
2010-10-24 17:58:41 [2771] [0] INFO: DLR rerouting for smsc id <SMStata>
disabled.
2010-10-24 17:58:41 [2771] [0] INFO: Added logfile
`/projects/kannel/logs/live/SMStata.log' with level `0'.
2010-10-24 17:58:41 [2771] [0] WARNING: SMPP: receive-port for transceiver
mode defined, ignoring.
2010-10-24 17:58:41 [2771] [0] DEBUG: Started thread 8
(gw/smsc/smsc_smpp.c:io_thread)
2010-10-24 17:58:41 [2771] [8] DEBUG: Thread 8
(gw/smsc/smsc_smpp.c:io_thread) maps to pid 2771.
2010-10-24 17:58:41 [2771] [8] INFO: Logging thread `8' to logfile
`/projects/kannel/logs/live/SMStata.log' with level `0'.
2010-10-24 17:58:41 [2771] [0] INFO: DLR rerouting for smsc id <SMStata2>
disabled.
2010-10-24 17:58:41 [2771] [0] INFO: Added logfile
`/projects/kannel/logs/live/SMStata2.log' with level `0'.
2010-10-24 17:58:41 [2771] [0] WARNING: SMPP: receive-port for transceiver
mode defined, ignoring.
2010-10-24 17:58:41 [2771] [0] DEBUG: Started thread 9
(gw/smsc/smsc_smpp.c:io_thread)
2010-10-24 17:58:41 [2771] [9] DEBUG: Thread 9
(gw/smsc/smsc_smpp.c:io_thread) maps to pid 2771.
2010-10-24 17:58:41 [2771] [9] INFO: Logging thread `9' to logfile
`/projects/kannel/logs/live/SMStata2.log' with level `0'.
2010-10-24 17:58:41 [2771] [10] DEBUG: Thread 10
(gw/bb_smscconn.c:sms_router) maps to pid 2771.
2010-10-24 17:58:41 [2771] [0] DEBUG: Started thread 10
(gw/bb_smscconn.c:sms_router)
2010-10-24 17:58:41 [2771] [0]
INFO: ----------------------------------------
2010-10-24 17:58:41 [2771] [0] INFO: Kannel bearerbox II version svn-r4865
starting
2010-10-24 17:58:41 [2771] [0] INFO: Loaded 0 messages from store.
2010-10-24 17:58:41 [2771] [0] INFO: MAIN: Start-up done, entering mainloop
2010-10-24 17:58:42 [2771] [2] DEBUG: HTTP: Creating HTTPClient for
`122.173.123.171'.
2010-10-24 17:58:42 [2771] [2] DEBUG: HTTP: Created HTTPClient area
0x26430e0.
2010-10-24 17:58:42 [2771] [3] DEBUG: sql: SELECT count(*) FROM `kannel_dlr`
2010-10-24 17:58:42 [2771] [3] DEBUG: column=count(*) buffer_type=8
max_length=0 length=21
2010-10-24 17:58:42 [2771] [3] DEBUG: HTTP: Resetting HTTPClient for
`122.173.123.171'.
2010-10-24 17:58:44 [2771] [5] INFO: Client connected from <127.0.0.1>
2010-10-24 17:58:44 [2771] [5] DEBUG: Started thread 11
(gw/bb_boxc.c:function)
2010-10-24 17:58:44 [2771] [11] DEBUG: Thread 11 (gw/bb_boxc.c:function)
maps to pid 2771.
2010-10-24 17:58:44 [2771] [11] DEBUG: Started thread 12
(gw/bb_boxc.c:boxc_sender)
2010-10-24 17:58:44 [2771] [11] DEBUG: boxc_receiver: got boxc_id
<BOX-SMSC1> from <127.0.0.1>
2010-10-24 17:58:44 [2771] [12] DEBUG: Thread 12 (gw/bb_boxc.c:boxc_sender)
maps to pid 2771.
2010-10-24 17:58:47 [2771] [3] DEBUG: sql: SELECT count(*) FROM `kannel_dlr`
2010-10-24 17:58:47 [2771] [3] DEBUG: column=count(*) buffer_type=8
max_length=0 length=21
2010-10-24 17:58:47 [2771] [3] DEBUG: HTTP: Resetting HTTPClient for
`122.173.123.171'.
2010-10-24 17:58:52 [2771] [3] DEBUG: sql: SELECT count(*) FROM `kannel_dlr`
2010-10-24 17:58:52 [2771] [3] DEBUG: column=count(*) buffer_type=8
max_length=0 length=21
2010-10-24 17:58:52 [2771] [3] DEBUG: HTTP: Resetting HTTPClient for
`122.173.123.171'.
2010-10-24 17:58:57 [2771] [3] DEBUG: sql: SELECT count(*) FROM `kannel_dlr`
2010-10-24 17:58:57 [2771] [3] DEBUG: column=count(*) buffer_type=8
max_length=0 length=21
2010-10-24 17:58:57 [2771] [3] DEBUG: HTTP: Resetting HTTPClient for
`122.173.123.171'.
2010-10-24 17:59:00 [2771] [5] INFO: Client connected from <127.0.0.1>
2010-10-24 17:59:00 [2771] [5] DEBUG: Started thread 13
(gw/bb_boxc.c:function)
2010-10-24 17:59:00 [2771] [13] DEBUG: Thread 13 (gw/bb_boxc.c:function)
maps to pid 2771.
2010-10-24 17:59:00 [2771] [13] DEBUG: Started thread 14
(gw/bb_boxc.c:boxc_sender)
2010-10-24 17:59:00 [2771] [14] DEBUG: Thread 14 (gw/bb_boxc.c:boxc_sender)
maps to pid 2771.
2010-10-24 17:59:00 [2771] [13] DEBUG: boxc_receiver: got boxc_id
<BOX-SMStata> from <127.0.0.1>
2010-10-24 18:00:41 [2771] [4] WARNING: smsbox_list empty!
2010-10-24 18:00:41 [2771] [4] WARNING: smsbox_list empty!
