You are not getting a delivery report because the message was not sent anywhere.
There was an attempt to deliver to a so-called http smsc. But it didn't point to a valid smsc. Try an svn update on smppbox. Latest version knows how to handle nacks from bearerbox. == Rene From: Imran Aghayev [mailto:imran.agha...@hotmail.co.uk] Sent: Thursday, 12 August, 2010 14:08 To: rene.klu...@chimit.nl; alejandro.guerri...@gmail.com; users@kannel.org Subject: RE: dlr smppbox Here is smppbox.log file output... Please tell me why emse doesn't receive deliver_sm 2010-08-10 22:23:49 [9467] [13] DEBUG: Thread 13 (smppbox.c:function) maps to pid 9467. 2010-08-10 22:23:49 [9467] [0] DEBUG: Started thread 13 (smppbox.c:function) 2010-08-10 22:23:49 [9467] [13] INFO: Client connected from <127.0.0.1> 2010-08-10 22:23:49 [9467] [13] DEBUG: Connecting to <127.0.0.1> 2010-08-10 22:23:49 [9467] [13] INFO: Connected to bearerbox at localhost port 13778. 2010-08-10 22:23:49 [9467] [13] DEBUG: Started thread 14 (smppbox.c:smpp_to_bearerbox) 2010-08-10 22:23:49 [9467] [14] DEBUG: Thread 14 (smppbox.c:smpp_to_bearerbox) maps to pid 9467. 2010-08-10 22:23:50 [9467] [14] DEBUG: SMPP[SMPP Router]: Got PDU: 2010-08-10 22:23:50 [9467] [14] DEBUG: SMPP PDU 0x8186f40 dump: 2010-08-10 22:23:50 [9467] [14] DEBUG: type_name: bind_transmitter 2010-08-10 22:23:50 [9467] [14] DEBUG: command_id: 2 = 0x00000002 2010-08-10 22:23:50 [9467] [14] DEBUG: command_status: 0 = 0x00000000 2010-08-10 22:23:50 [9467] [14] DEBUG: sequence_number: 1 = 0x00000001 2010-08-10 22:23:50 [9467] [14] DEBUG: system_id: "aldo2" 2010-08-10 22:23:50 [9467] [14] DEBUG: password: "aldo2" 2010-08-10 22:23:50 [9467] [14] DEBUG: system_type: "SMPP" 2010-08-10 22:23:50 [9467] [14] DEBUG: interface_version: 52 = 0x00000034 2010-08-10 22:23:50 [9467] [14] DEBUG: addr_ton: 0 = 0x00000000 2010-08-10 22:23:50 [9467] [14] DEBUG: addr_npi: 0 = 0x00000000 2010-08-10 22:23:50 [9467] [14] DEBUG: address_range: NULL 2010-08-10 22:23:50 [9467] [14] DEBUG: SMPP PDU dump ends. 2010-08-10 22:23:50 [9467] [14] DEBUG: smppbox[SMPP]: Multiple login: disconnect. 2010-08-10 22:23:50 [9467] [14] DEBUG: smppbox[SMPP]: Multiple login: disconnect. 2010-08-10 22:23:50 [9467] [14] DEBUG: smppbox[SMPP]: Multiple login: disconnect. 2010-08-10 22:23:50 [9467] [14] DEBUG: smppbox[SMPP]: Multiple login: disconnect. 2010-08-10 22:23:50 [9467] [14] DEBUG: smppbox[SMPP]: Multiple login: disconnect. 2010-08-10 22:23:50 [9467] [14] DEBUG: smppbox[SMPP]: Multiple login: disconnect. 2010-08-10 22:23:50 [9467] [14] DEBUG: SMPP[SMPP]: Sending PDU: 2010-08-10 22:23:50 [9467] [14] DEBUG: SMPP PDU 0x8187198 dump: 2010-08-10 22:23:50 [9467] [14] DEBUG: type_name: bind_transmitter_resp 2010-08-10 22:23:50 [9467] [14] DEBUG: command_id: 2147483650 = 0x80000002 2010-08-10 22:23:50 [9467] [14] DEBUG: command_status: 0 = 0x00000000 2010-08-10 22:23:50 [9467] [14] DEBUG: sequence_number: 1 = 0x00000001 2010-08-10 22:23:50 [9467] [14] DEBUG: system_id: "aldo2" 2010-08-10 22:23:50 [9467] [14] DEBUG: SMPP PDU dump ends. 2010-08-10 22:23:51 [9467] [14] DEBUG: SMPP[SMPP]: Got PDU: 2010-08-10 22:23:51 [9467] [14] DEBUG: SMPP PDU 0x8186f40 dump: 2010-08-10 22:23:51 [9467] [14] DEBUG: type_name: submit_sm 2010-08-10 22:23:51 [9467] [14] DEBUG: command_id: 4 = 0x00000004 2010-08-10 22:23:51 [9467] [14] DEBUG: command_status: 0 = 0x00000000 2010-08-10 22:23:51 [9467] [14] DEBUG: sequence_number: 2 = 0x00000002 2010-08-10 22:23:51 [9467] [14] DEBUG: service_type: NULL 2010-08-10 22:23:51 [9467] [14] DEBUG: source_addr_ton: 1 = 0x00000001 2010-08-10 22:23:51 [9467] [14] DEBUG: source_addr_npi: 0 = 0x00000000 2010-08-10 22:23:51 [9467] [14] DEBUG: source_addr: "447939615261" 2010-08-10 22:23:51 [9467] [14] DEBUG: dest_addr_ton: 1 = 0x00000001 2010-08-10 22:23:51 [9467] [14] DEBUG: dest_addr_npi: 0 = 0x00000000 2010-08-10 22:23:51 [9467] [14] DEBUG: destination_addr: "447939615261" 2010-08-10 22:23:51 [9467] [14] DEBUG: esm_class: 0 = 0x00000000 2010-08-10 22:23:51 [9467] [14] DEBUG: protocol_id: 0 = 0x00000000 2010-08-10 22:23:51 [9467] [14] DEBUG: priority_flag: 0 = 0x00000000 2010-08-10 22:23:51 [9467] [14] DEBUG: schedule_delivery_time: NULL 2010-08-10 22:23:51 [9467] [14] DEBUG: validity_period: NULL 2010-08-10 22:23:51 [9467] [14] DEBUG: registered_delivery: 1 = 0x00000001 2010-08-10 22:23:51 [9467] [14] DEBUG: replace_if_present_flag: 0 = 0x00000000 2010-08-10 22:23:51 [9467] [14] DEBUG: data_coding: 0 = 0x00000000 2010-08-10 22:23:51 [9467] [14] DEBUG: sm_default_msg_id: 0 = 0x00000000 2010-08-10 22:23:51 [9467] [14] DEBUG: sm_length: 5 = 0x00000005 2010-08-10 22:23:51 [9467] [14] DEBUG: short_message: "zibil" 2010-08-10 22:23:51 [9467] [14] DEBUG: SMPP PDU dump ends. 2010-08-10 22:23:51 [9467] [14] DEBUG: Msg object at 0x8187198: 2010-08-10 22:23:51 [9467] [14] DEBUG: type: sms 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.sender: 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string at 0x8186148: 2010-08-10 22:23:51 [9467] [14] DEBUG: len: 13 2010-08-10 22:23:51 [9467] [14] DEBUG: size: 1024 2010-08-10 22:23:51 [9467] [14] DEBUG: immutable: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: data: 2b 34 34 37 39 33 39 36 31 35 32 36 31 +447939615261 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string dump ends. 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.receiver: 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string at 0x8186178: 2010-08-10 22:23:51 [9467] [14] DEBUG: len: 13 2010-08-10 22:23:51 [9467] [14] DEBUG: size: 1024 2010-08-10 22:23:51 [9467] [14] DEBUG: immutable: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: data: 2b 34 34 37 39 33 39 36 31 35 32 36 31 +447939615261 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string dump ends. 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.udhdata: 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.msgdata: 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string at 0x8187038: 2010-08-10 22:23:51 [9467] [14] DEBUG: len: 5 2010-08-10 22:23:51 [9467] [14] DEBUG: size: 6 2010-08-10 22:23:51 [9467] [14] DEBUG: immutable: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: data: 7a 69 62 69 6c zibil 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string dump ends. 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.time: -1 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.smsc_id: 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string at 0x8186160: 2010-08-10 22:23:51 [9467] [14] DEBUG: len: 7 2010-08-10 22:23:51 [9467] [14] DEBUG: size: 8 2010-08-10 22:23:51 [9467] [14] DEBUG: immutable: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: data: 54 4d 4f 42 49 4c 45 EXTERNAL 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string dump ends. 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.smsc_number: 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.foreign_id: 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.service: 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.account: 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.id: c2ec14d5-9cbb-4007-b4e8-f08660b2feae 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.sms_type: -1 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.mclass: -1 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.mwi: -1 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.coding: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.compress: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.validity: -1 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.deferred: -1 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.dlr_mask: 3 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.dlr_url: 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.pid: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.alt_dcs: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.rpi: -1 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.charset: 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.boxc_id: 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string at 0x8186108: 2010-08-10 22:23:51 [9467] [14] DEBUG: len: 4 2010-08-10 22:23:51 [9467] [14] DEBUG: size: 5 2010-08-10 22:23:51 [9467] [14] DEBUG: immutable: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: data: 53 4d 50 50 SMPP 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string dump ends. 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.binfo: 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.msg_left: -1 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.split_parts: (nil) 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.priority: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.resend_try: -1 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.resend_time: -1 2010-08-10 22:23:51 [9467] [14] DEBUG: sms.meta_data: 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string at 0x8186190: 2010-08-10 22:23:51 [9467] [14] DEBUG: len: 6 2010-08-10 22:23:51 [9467] [14] DEBUG: size: 1024 2010-08-10 22:23:51 [9467] [14] DEBUG: immutable: 0 2010-08-10 22:23:51 [9467] [14] DEBUG: data: 3f 73 6d 70 70 3f ?smpp? 2010-08-10 22:23:51 [9467] [14] DEBUG: Octet string dump ends. 2010-08-10 22:23:51 [9467] [14] DEBUG: Msg object ends. 2010-08-10 22:23:51 [9467] [14] DEBUG: DLR[mysql]: Adding DLR smsc=SMPP, ts=c2ec14d5, src=+447939615261, dst=+447939615261, mask=3, boxc=SMPP 2010-08-10 22:23:51 [9467] [14] DEBUG: adding DLR entry into database 2010-08-10 22:23:51 [9467] [14] DEBUG: sql: INSERT INTO `dlr` (`smsc`, `ts`, `source`, `destination`, `service`, `url`, `mask`, `boxcid`, `status`) VALUES (?, ?, ?, ?, ?, ?, ?, ?, 0) 2010-08-10 22:23:51 [9467] [14] DEBUG: SMPP[SMPP]: Sending PDU: 2010-08-10 22:23:51 [9467] [14] DEBUG: SMPP PDU 0x8188dd0 dump: 2010-08-10 22:23:51 [9467] [14] DEBUG: type_name: submit_sm_resp 2010-08-10 22:23:51 [9467] [14] DEBUG: command_id: 2147483652 = 0x80000004 2010-08-10 22:23:51 [9467] [14] DEBUG: command_status: 0 = 0x00000000 2010-08-10 22:23:51 [9467] [14] DEBUG: sequence_number: 2 = 0x00000002 2010-08-10 22:23:51 [9467] [14] DEBUG: message_id: "c2ec14d5" 2010-08-10 22:23:51 [9467] [14] DEBUG: SMPP PDU dump ends. 2010-08-10 22:23:51 [9467] [9] DEBUG: Thread 9 (smppbox.c:function) terminates. 2010-08-10 22:23:52 [9467] [14] DEBUG: SMPP[SMPP]: Got PDU: 2010-08-10 22:23:52 [9467] [14] DEBUG: SMPP PDU 0x8187058 dump: 2010-08-10 22:23:52 [9467] [14] DEBUG: type_name: unbind 2010-08-10 22:23:52 [9467] [14] DEBUG: command_id: 6 = 0x00000006 2010-08-10 22:23:52 [9467] [14] DEBUG: command_status: 0 = 0x00000000 2010-08-10 22:23:52 [9467] [14] DEBUG: sequence_number: 3 = 0x00000003 2010-08-10 22:23:52 [9467] [14] DEBUG: SMPP PDU dump ends. 2010-08-10 22:23:52 [9467] [14] DEBUG: SMPP[SMPP]: Sending PDU: 2010-08-10 22:23:52 [9467] [14] DEBUG: SMPP PDU 0x8187260 dump: 2010-08-10 22:23:52 [9467] [14] DEBUG: type_name: unbind_resp 2010-08-10 22:23:52 [9467] [14] DEBUG: command_id: 2147483654 = 0x80000006 2010-08-10 22:23:52 [9467] [14] DEBUG: command_status: 0 = 0x00000000 2010-08-10 22:23:52 [9467] [14] DEBUG: sequence_number: 3 = 0x00000003 2010-08-10 22:23:52 [9467] [14] DEBUG: SMPP PDU dump ends. 2010-08-10 22:23:52 [9467] [14] DEBUG: Thread 14 (smppbox.c:smpp_to_bearerbox) terminates. _____ From: rene.klu...@chimit.nl To: imran.agha...@hotmail.co.uk; alejandro.guerri...@gmail.com; users@kannel.org Subject: RE: dlr smppbox Date: Wed, 11 Aug 2010 12:55:47 +0200 Could you also post your smppbox.log? From: users-boun...@kannel.org [mailto:users-boun...@kannel.org] On Behalf Of Imran Aghayev Sent: Wednesday, 11 August, 2010 12:14 To: alejandro.guerri...@gmail.com; users@kannel.org Subject: RE: dlr smppbox I read it, it does not matter what is in log file, Not found, ok or failure. My question was about Delivery report not http smsc response. At the bottom of log file you can see SMSC[EXTERNAL]: creating DLR message So this DLR messages inserted to dlr table but esme doesn't receive it. I need to find why esme didn't got deliver_sm ? Thanks _____ Date: Wed, 11 Aug 2010 11:34:55 +0200 Subject: Re: dlr smppbox From: alejandro.guerri...@gmail.com To: imran.agha...@hotmail.co.uk CC: rene.klu...@chimit.nl; users@kannel.org Did you _really_ read the log entry? It shows "404 Not Found" and a reference to "www.azeriguide.co.uk", does that ring any bells? Regards, Alex On Wed, Aug 11, 2010 at 11:17 AM, Imran Aghayev <imran.agha...@hotmail.co.uk> wrote: This is what I have in bearebox log. smppbox inserts into dlr table and that is ok. But esme didnt receive DLR message. Maybe problem is in boxc name SMPP ? How to set smppbox name to SMPP ? Thanks 2010-08-10 22:23:51 [9436] [9] ERROR: HTTP[EXTERNAL]: Message was rejected. SMSC reponse was: 2010-08-10 22:23:51 [9436] [9] DEBUG: Octet string at 0x81ab8b8: 2010-08-10 22:23:51 [9436] [9] DEBUG: len: 278 2010-08-10 22:23:51 [9436] [9] DEBUG: size: 279 2010-08-10 22:23:51 [9436] [9] DEBUG: immutable: 0 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 3c 21 44 4f 43 54 59 50 45 20 48 54 4d 4c 20 50 <!DOCTYPE HTML P 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 55 42 4c 49 43 20 22 2d 2f 2f 49 45 54 46 2f 2f UBLIC "-//IETF// 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 44 54 44 20 48 54 4d 4c 20 32 2e 30 2f 2f 45 4e DTD HTML 2.0//EN 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 22 3e 0a 3c 68 74 6d 6c 3e 3c 68 65 61 64 3e 0a ">.<html><head>. 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 3c 74 69 74 6c 65 3e 34 30 34 20 4e 6f 74 20 46 <title>404 Not F 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 6f 75 6e 64 3c 2f 74 69 74 6c 65 3e 0a 3c 2f 68 ound</title>.</h 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 65 61 64 3e 3c 62 6f 64 79 3e 0a 3c 68 31 3e 4e ead><body>.<h1>N 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 6f 74 20 46 6f 75 6e 64 3c 2f 68 31 3e 0a 3c 70 ot Found</h1>.<p 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 3e 54 68 65 20 72 65 71 75 65 73 74 65 64 20 55 >The requested U 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 52 4c 20 2f 63 67 69 2d 62 69 6e 2f 73 65 6e 64 RL /cgi-bin/send 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 73 6d 73 20 77 61 73 20 6e 6f 74 20 66 6f 75 6e sms was not foun 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 64 20 6f 6e 20 74 68 69 73 20 73 65 72 76 65 72 d on this server 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 2e 3c 2f 70 3e 0a 3c 68 72 3e 0a 3c 61 64 64 72 .</p>.<hr>.<addr 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 65 73 73 3e 20 20 53 65 72 76 65 72 20 61 74 20 ess> Server at 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 77 77 77 2e 61 7a 65 72 69 67 75 69 64 65 2e 63 www.azeriguide.c 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 6f 2e 75 6b 20 50 6f 72 74 20 38 30 3c 2f 61 64 o.uk Port 80</ad 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 64 72 65 73 73 3e 0a 3c 2f 62 6f 64 79 3e 3c 2f dress>.</body></ 2010-08-10 22:23:51 [9436] [9] DEBUG: data: 68 74 6d 6c 3e 0a html>. 2010-08-10 22:23:51 [9436] [9] DEBUG: Octet string dump ends. 2010-08-10 22:23:51 [9436] [9] DEBUG: SMSC[EXTERNAL]: creating DLR message 2010-08-10 22:23:51 [9436] [9] DEBUG: SMSC[EXTERNAL]: DLR = c2ec14d5 2010-08-10 22:23:51 [9436] [27] DEBUG: send_msg: sending msg to boxc: <SMPP> 2010-08-10 22:23:51 [9436] [27] DEBUG: boxc_sender: sent message to <127.0.0.1> _____ From: rene.klu...@chimit.nl To: imran.agha...@hotmail.co.uk; users@kannel.org Subject: RE: dlr smppbox Date: Wed, 11 Aug 2010 00:26:45 +0200 1. Registered_delivery flag is not a Kannel variable. It can be set to 1 or 0. For its designated purposes, I refer to the SMPP specifications. 2. If you set registered_delivery flag to 1, you should get a delivery report (deliver_sm), once upon Kannel receives it. Do you see anything in your bearerbox logs indicating about that? == Rene From: Imran Aghayev [mailto:imran.agha...@hotmail.co.uk] Sent: Tuesday, 10 August, 2010 22:47 To: rene.klu...@chimit.nl; users@kannel.org Subject: RE: dlr smppbox Thanks, I have 2 more questions: 1. When I set registered_delivery flag to 1 kannel writes to dlr table but when I set registed_delivery flag to 31 (1f in hex) it does not write anything. Why and what is wrong ? 2. I use php esme client and after submit_sm I got only submit_sm_resp. Then I wait for next PDU but never got deliver_sm pdu. Why ? Thanks _____ From: rene.klu...@chimit.nl To: imran.agha...@hotmail.co.uk; users@kannel.org Subject: RE: dlr smppbox Date: Tue, 10 Aug 2010 20:02:59 +0200 You will get a deliver_sm for every message that you request a delivery report for. From: users-boun...@kannel.org [mailto:users-boun...@kannel.org] On Behalf Of Imran Aghayev Sent: Tuesday, 10 August, 2010 19:12 To: users@kannel.org Subject: dlr smppbox Dear all, What happens if I will request Delivery report from smppbox ? Thanks
