Howdy,
Have a bit of a head scratcher integrating with an SMSC and I'm not
sure which is the smoking gun. Sending and receiving messages works
fine, but DLRs are not.
Looking at the log I have a few theories, but I'm not sure which might be right:
1) My leading theory is that the service-type for the DLR is WAP, is
this causing the mismatch? (I tried setting the service type to WAP on
outgoing messages but their SMSC rejected it) Any ideas on workarounds
here if the SMSC can't change?
1) The ts recorded by Kannel is lowercase (ff011034909e) while the one
returned by the SMSC is uppercase (FF011034909E).
3) The dst on the Kannel side has a leading + while the trace when
looking for the DLR does not.
Any ideas for magical configuration items that might get these matching?
Below are the traces for the send and the missing DLR report.
2014-09-19 15:07:10 [1076] [9] DEBUG: SMPP[unicef_sz]: Manually forced
source addr ton = 1, source add npi = 1
2014-09-19 15:07:10 [1076] [9] DEBUG: SMPP[unicef_sz]: Sending PDU:
2014-09-19 15:07:10 [1076] [9] DEBUG: SMPP PDU 0x7f37c0000e50 dump:
2014-09-19 15:07:10 [1076] [9] DEBUG: type_name: submit_sm
2014-09-19 15:07:10 [1076] [9] DEBUG: command_id: 4 = 0x00000004
2014-09-19 15:07:10 [1076] [9] DEBUG: command_status: 0 = 0x00000000
2014-09-19 15:07:10 [1076] [9] DEBUG: sequence_number: 3 = 0x00000003
2014-09-19 15:07:10 [1076] [9] DEBUG: service_type: NULL
2014-09-19 15:07:10 [1076] [9] DEBUG: source_addr_ton: 1 = 0x00000001
2014-09-19 15:07:10 [1076] [9] DEBUG: source_addr_npi: 1 = 0x00000001
2014-09-19 15:07:10 [1076] [9] DEBUG: source_addr: "8500"
2014-09-19 15:07:10 [1076] [9] DEBUG: dest_addr_ton: 1 = 0x00000001
2014-09-19 15:07:10 [1076] [9] DEBUG: dest_addr_npi: 1 = 0x00000001
2014-09-19 15:07:10 [1076] [9] DEBUG: destination_addr: "26878027924"
2014-09-19 15:07:10 [1076] [9] DEBUG: esm_class: 3 = 0x00000003
2014-09-19 15:07:10 [1076] [9] DEBUG: protocol_id: 0 = 0x00000000
2014-09-19 15:07:10 [1076] [9] DEBUG: priority_flag: 0 = 0x00000000
2014-09-19 15:07:10 [1076] [9] DEBUG: schedule_delivery_time: NULL
2014-09-19 15:07:10 [1076] [9] DEBUG: validity_period: NULL
2014-09-19 15:07:10 [1076] [9] DEBUG: registered_delivery: 1 = 0x00000001
2014-09-19 15:07:10 [1076] [9] DEBUG: replace_if_present_flag: 0 = 0x00000000
2014-09-19 15:07:10 [1076] [9] DEBUG: data_coding: 0 = 0x00000000
2014-09-19 15:07:10 [1076] [9] DEBUG: sm_default_msg_id: 0 = 0x00000000
2014-09-19 15:07:10 [1076] [9] DEBUG: sm_length: 39 = 0x00000027
2014-09-19 15:07:10 [1076] [9] DEBUG: short_message:
2014-09-19 15:07:10 [1076] [9] DEBUG: Octet string at 0x7f37c00010a0:
2014-09-19 15:07:10 [1076] [9] DEBUG: len: 39
2014-09-19 15:07:10 [1076] [9] DEBUG: size: 40
2014-09-19 15:07:10 [1076] [9] DEBUG: immutable: 0
2014-09-19 15:07:10 [1076] [9] DEBUG: data: 4c 61 73 74 20 74 65
73 74 20 61 66 74 65 72 20 Last test after
2014-09-19 15:07:10 [1076] [9] DEBUG: data: 62 72 69 6e 67 69 6e
67 20 73 65 74 74 69 6e 67 bringing setting
2014-09-19 15:07:10 [1076] [9] DEBUG: data: 73 20 62 61 63 6b 2e
s back.
2014-09-19 15:07:10 [1076] [9] DEBUG: Octet string dump ends.
2014-09-19 15:07:10 [1076] [9] DEBUG: SMPP PDU dump ends.
2014-09-19 15:07:10 [1076] [9] DEBUG: SMPP[unicef_sz]: Got PDU:
2014-09-19 15:07:10 [1076] [9] DEBUG: SMPP PDU 0x7f37c0000ea0 dump:
2014-09-19 15:07:10 [1076] [9] DEBUG: type_name: submit_sm_resp
2014-09-19 15:07:10 [1076] [9] DEBUG: command_id: 2147483652 = 0x80000004
2014-09-19 15:07:10 [1076] [9] DEBUG: command_status: 0 = 0x00000000
2014-09-19 15:07:10 [1076] [9] DEBUG: sequence_number: 3 = 0x00000003
2014-09-19 15:07:10 [1076] [9] DEBUG: message_id: "ff011034909e"
2014-09-19 15:07:10 [1076] [9] DEBUG: SMPP PDU dump ends.
2014-09-19 15:07:10 [1076] [9] DEBUG: DLR[internal]: Adding DLR
smsc=unicef_sz, ts=ff011034909e, src=8500, dst=+26878027924, mask=31,
boxc=
2014-09-19 15:07:13 [1076] [9] DEBUG: SMPP[unicef_sz]: Got PDU:
2014-09-19 15:07:13 [1076] [9] DEBUG: SMPP PDU 0x7f37c0000ea0 dump:
2014-09-19 15:07:13 [1076] [9] DEBUG: type_name: deliver_sm
2014-09-19 15:07:13 [1076] [9] DEBUG: command_id: 5 = 0x00000005
2014-09-19 15:07:13 [1076] [9] DEBUG: command_status: 0 = 0x00000000
2014-09-19 15:07:13 [1076] [9] DEBUG: sequence_number: 6 = 0x00000006
2014-09-19 15:07:13 [1076] [9] DEBUG: service_type: "WAP"
2014-09-19 15:07:13 [1076] [9] DEBUG: source_addr_ton: 1 = 0x00000001
2014-09-19 15:07:13 [1076] [9] DEBUG: source_addr_npi: 1 = 0x00000001
2014-09-19 15:07:13 [1076] [9] DEBUG: source_addr: "26878027924"
2014-09-19 15:07:13 [1076] [9] DEBUG: dest_addr_ton: 1 = 0x00000001
2014-09-19 15:07:13 [1076] [9] DEBUG: dest_addr_npi: 1 = 0x00000001
2014-09-19 15:07:13 [1076] [9] DEBUG: destination_addr: "8500"
2014-09-19 15:07:13 [1076] [9] DEBUG: esm_class: 4 = 0x00000004
2014-09-19 15:07:13 [1076] [9] DEBUG: protocol_id: 0 = 0x00000000
2014-09-19 15:07:13 [1076] [9] DEBUG: priority_flag: 0 = 0x00000000
2014-09-19 15:07:13 [1076] [9] DEBUG: schedule_delivery_time: NULL
2014-09-19 15:07:13 [1076] [9] DEBUG: validity_period: NULL
2014-09-19 15:07:13 [1076] [9] DEBUG: registered_delivery: 0 = 0x00000000
2014-09-19 15:07:13 [1076] [9] DEBUG: replace_if_present_flag: 0 = 0x00000000
2014-09-19 15:07:13 [1076] [9] DEBUG: data_coding: 0 = 0x00000000
2014-09-19 15:07:13 [1076] [9] DEBUG: sm_default_msg_id: 0 = 0x00000000
2014-09-19 15:07:13 [1076] [9] DEBUG: sm_length: 127 = 0x0000007f
2014-09-19 15:07:13 [1076] [9] DEBUG: short_message:
2014-09-19 15:07:13 [1076] [9] DEBUG: Octet string at 0x7f37c0002610:
2014-09-19 15:07:13 [1076] [9] DEBUG: len: 127
2014-09-19 15:07:13 [1076] [9] DEBUG: size: 128
2014-09-19 15:07:13 [1076] [9] DEBUG: immutable: 0
2014-09-19 15:07:13 [1076] [9] DEBUG: data: 69 64 3a 32 38 30 33
38 30 30 33 31 39 33 30 35 id:2803800319305
2014-09-19 15:07:13 [1076] [9] DEBUG: data: 32 36 20 73 75 62 3a
30 30 31 20 64 6c 76 72 64 26 sub:001 dlvrd
2014-09-19 15:07:13 [1076] [9] DEBUG: data: 3a 30 30 31 20 73 75
62 6d 69 74 20 64 61 74 65 :001 submit date
2014-09-19 15:07:13 [1076] [9] DEBUG: data: 3a 31 34 30 39 31 39
31 37 30 37 20 64 6f 6e 65 :1409191707 done
2014-09-19 15:07:13 [1076] [9] DEBUG: data: 20 64 61 74 65 3a 31
34 30 39 31 39 31 37 30 37 date:1409191707
2014-09-19 15:07:13 [1076] [9] DEBUG: data: 20 73 74 61 74 3a 44
45 4c 49 56 52 44 20 65 72 stat:DELIVRD er
2014-09-19 15:07:13 [1076] [9] DEBUG: data: 72 3a 30 30 30 20 74
65 78 74 3a 4c 61 73 74 20 r:000 text:Last
2014-09-19 15:07:13 [1076] [9] DEBUG: data: 74 65 73 74 20 61 66
74 65 72 20 62 72 69 6e test after brin
2014-09-19 15:07:13 [1076] [9] DEBUG: Octet string dump ends.
2014-09-19 15:07:13 [1076] [9] DEBUG: message_state: 2 = 0x00000002
2014-09-19 15:07:13 [1076] [9] DEBUG: receipted_message_id: "FF011034909E"
2014-09-19 15:07:13 [1076] [9] DEBUG: SMPP PDU dump ends.
2014-09-19 15:07:13 [1076] [9] DEBUG: SMPP[unicef_sz] handle_pdu, got DLR
2014-09-19 15:07:13 [1076] [9] DEBUG: DLR[internal]: Looking for DLR
smsc=unicef_sz, ts=FF011034909E, dst=26878027924, type=1
2014-09-19 15:07:13 [1076] [9] WARNING: DLR[internal]: DLR from
SMSC<unicef_sz> for DST<26878027924> not found.
2014-09-19 15:07:13 [1076] [9] ERROR: SMPP[unicef_sz]: got DLR but
could not find message or was not interested in it id<FF011034909E>
dst<26878027924>, type<1>
