Sorry, I hate to be a pain, but any clues here? Any other information I can include?
I imagine this is something people have run into before, any steps to work through to a solution? Thanks, -Nic On Fri, Sep 19, 2014 at 11:17 AM, Nic Pottier <nicpott...@gmail.com> wrote: > 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>
