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: [email protected]
To: [email protected]; [email protected]; [email protected]
Subject: RE: dlr smppbox
Date: Wed, 11 Aug 2010 12:55:47 +0200
Could you also post your smppbox.log?
From:
[email protected] [mailto:[email protected]] On Behalf Of Imran
Aghayev
Sent: Wednesday, 11 August, 2010 12:14
To: [email protected]; [email protected]
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: [email protected]
To: [email protected]
CC: [email protected]; [email protected]
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 <[email protected]>
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:
[email protected]
To: [email protected];
[email protected]
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:[email protected]]
Sent: Tuesday, 10 August,
2010 22:47
To: [email protected]; [email protected]
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: [email protected]
To: [email protected];
[email protected]
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: [email protected] [mailto:[email protected]] On
Behalf Of Imran Aghayev
Sent: Tuesday, 10 August, 2010 19:12
To: [email protected]
Subject: dlr smppbox
Dear all,
What happens if I will request Delivery report from smppbox ?
Thanks
