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+xxxxxxxxxxxxxx SMStata 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!
