Hi, because your provider sending wrong esm_class for DLRs.
Thanks, Alex > Am 27.06.2016 um 16:45 schrieb Ahmed Shabana <a.shab...@cequens.com>: > > Dears , > > I face strange issue with my Kannel with a new smsc connection > > Although the provider pack dlr properly as shown in below logs in Deliver_SM > PDU but kannel bb access log show them as “Receive SMS “ !! > > > Kannel logs > 2016-05-22 10:39:28 Sent SMS [SMSC:xxxx] [ACT:] [FID:14639063686890014103] > [from:SMS] [to:20100xxxx] [msg:27:hellooooooo..xxx] [META:?smpp_resp?] > [udh:0:] > 2016-05-22 10:39:29 Receive SMS [SMSC:xxxx] [ACT:xxx_xx] > [FID:14639063686890014103] [from:+20100xxxx] [to:...] > [msg:112:id:14639063686890014103 sub:001 dlvrd:001 submit date:1605221039 > done date:1605221039 stat:DELIVRD err:000 text:] [META:?smpp?] [udh:0:] > 2016-05-22 10:40:14 Receive SMS [SMSC:xxxxx] [ACT:xxxxx] > [FID:14639063686890014103] [from:+20100xxxx] [to:...] > [msg:109:id:14639063686890014103 sub:001 dlvrd:001 submit date:1605221040 > done date:1605221040 stat:SEEN err:000 text:] [META:?smpp?] [udh:0:] > > > PDU logs > > 2016-05-22 10:39:28 [15726] [68] DEBUG: SMPP PDU 0x7f6b4c00b8d0 dump: > 2016-05-22 10:39:28 [15726] [68] DEBUG: type_name: submit_sm > 2016-05-22 10:39:28 [15726] [68] DEBUG: command_id: 4 = 0x00000004 > 2016-05-22 10:39:28 [15726] [68] DEBUG: command_status: 0 = 0x00000000 > 2016-05-22 10:39:28 [15726] [68] DEBUG: sequence_number: 78 = 0x0000004e > 2016-05-22 10:39:28 [15726] [68] DEBUG: service_type: NULL > 2016-05-22 10:39:28 [15726] [68] DEBUG: source_addr_ton: 5 = 0x00000005 > 2016-05-22 10:39:28 [15726] [68] DEBUG: source_addr_npi: 0 = 0x00000000 > 2016-05-22 10:39:28 [15726] [68] DEBUG: source_addr: "SMS" > 2016-05-22 10:39:28 [15726] [68] DEBUG: dest_addr_ton: 1 = 0x00000001 > 2016-05-22 10:39:28 [15726] [68] DEBUG: dest_addr_npi: 0 = 0x00000000 > 2016-05-22 10:39:28 [15726] [68] DEBUG: destination_addr: "20100xxxx" > 2016-05-22 10:39:28 [15726] [68] DEBUG: esm_class: 3 = 0x00000003 > 2016-05-22 10:39:28 [15726] [68] DEBUG: protocol_id: 0 = 0x00000000 > 2016-05-22 10:39:28 [15726] [68] DEBUG: priority_flag: 0 = 0x00000000 > 2016-05-22 10:39:28 [15726] [68] DEBUG: schedule_delivery_time: NULL > 2016-05-22 10:39:28 [15726] [68] DEBUG: validity_period: NULL > 2016-05-22 10:39:28 [15726] [68] DEBUG: registered_delivery: 17 = 0x00000011 > 2016-05-22 10:39:28 [15726] [68] DEBUG: replace_if_present_flag: 0 = > 0x00000000 > 2016-05-22 10:39:28 [15726] [68] DEBUG: data_coding: 0 = 0x00000000 > 2016-05-22 10:39:28 [15726] [68] DEBUG: sm_default_msg_id: 0 = 0x00000000 > 2016-05-22 10:39:28 [15726] [68] DEBUG: sm_length: 27 = 0x0000001b > 2016-05-22 10:39:28 [15726] [68] DEBUG: short_message: > 2016-05-22 10:39:28 [15726] [68] DEBUG: Octet string at 0x7f6b4c000fc0: > 2016-05-22 10:39:28 [15726] [68] DEBUG: len: 27 > 2016-05-22 10:39:28 [15726] [68] DEBUG: size: 28 > 2016-05-22 10:39:28 [15726] [68] DEBUG: immutable: 0 > 2016-05-22 10:39:28 [15726] [68] DEBUG: data: 68 65 6c 6c 6f 6f 6f 6f 6f > 6f 6f 0d 0a 4c 69 6e hellooooooo..Lin > 2016-05-22 10:39:28 [15726] [68] DEBUG: . > 2016-05-22 10:39:28 [15726] [68] DEBUG: . > 2016-05-22 10:39:28 [15726] [68] DEBUG: Octet string dump ends. > 2016-05-22 10:39:28 [15726] [68] DEBUG: SMPP PDU dump ends. > 2016-05-22 10:39:28 [15726] [68] DEBUG: SMPP[xxxx]: throughput (1.00,0.00) > 2016-05-22 10:39:28 [15726] [68] DEBUG: SMPP[xxxx]: throughput (1.00,0.00) > 2016-05-22 10:39:28 [15726] [68] DEBUG: SMPP[xxxx]: Got PDU: > 2016-05-22 10:39:28 [15726] [68] DEBUG: SMPP PDU 0x7f6b4c00b8d0 dump: > 2016-05-22 10:39:28 [15726] [68] DEBUG: type_name: submit_sm_resp > 2016-05-22 10:39:28 [15726] [68] DEBUG: command_id: 2147483652 = 0x80000004 > 2016-05-22 10:39:28 [15726] [68] DEBUG: command_status: 0 = 0x00000000 > 2016-05-22 10:39:28 [15726] [68] DEBUG: sequence_number: 78 = 0x0000004e > 2016-05-22 10:39:28 [15726] [68] DEBUG: message_id: > 2016-05-22 10:39:28 [15726] [68] DEBUG: Octet string at 0x7f6b4c000d30: > 2016-05-22 10:39:28 [15726] [68] DEBUG: len: 20 > 2016-05-22 10:39:28 [15726] [68] DEBUG: size: 21 > 2016-05-22 10:39:28 [15726] [68] DEBUG: immutable: 0 > 2016-05-22 10:39:28 [15726] [68] DEBUG: data: 31 34 36 33 39 30 36 33 36 > 38 36 38 39 30 30 31 1463906368689001 > 2016-05-22 10:39:28 [15726] [68] DEBUG: data: 34 31 30 33 > 4103 > 2016-05-22 10:39:28 [15726] [68] DEBUG: Octet string dump ends. > 2016-05-22 10:39:28 [15726] [68] DEBUG: SMPP PDU dump ends. > 2016-05-22 10:39:28 [15726] [68] DEBUG: DLR[mysql]: Adding DLR smsc=xxxx, > ts=14639063686890014103, src=SMS, dst=201003441970, mask=7, boxc=kannel5 > 2016-05-22 10:39:28 [15726] [68] DEBUG: adding DLR entry into database > 2016-05-22 10:39:28 [15726] [68] DEBUG: sql: INSERT INTO `dlr3` (`smsc`, > `ts`, `source`, `destination`, `service`, `url`, `mask`, `boxc`, `status`) > VALUES (?, ?, ?, ?, ?, ?, ?, ?, 0) > 2016-05-22 10:39:28 [15726] [68] DEBUG: SMPP[xxxxx]: throughput (1.00,0.00) > ----------- > 2016-05-22 10:39:29 [15726] [68] DEBUG: type_name: deliver_sm > 2016-05-22 10:39:29 [15726] [68] DEBUG: command_id: 5 = 0x00000005 > 2016-05-22 10:39:29 [15726] [68] DEBUG: command_status: 0 = 0x00000000 > 2016-05-22 10:39:29 [15726] [68] DEBUG: sequence_number: 2 = 0x00000002 > 2016-05-22 10:39:29 [15726] [68] DEBUG: service_type: NULL > 2016-05-22 10:39:29 [15726] [68] DEBUG: source_addr_ton: 1 = 0x00000001 > 2016-05-22 10:39:29 [15726] [68] DEBUG: source_addr_npi: 1 = 0x00000001 > 2016-05-22 10:39:29 [15726] [68] DEBUG: source_addr: "2010xxxx" > 2016-05-22 10:39:29 [15726] [68] DEBUG: dest_addr_ton: 5 = 0x00000005 > 2016-05-22 10:39:29 [15726] [68] DEBUG: dest_addr_npi: 0 = 0x00000000 > 2016-05-22 10:39:29 [15726] [68] DEBUG: destination_addr: "..." > 2016-05-22 10:39:29 [15726] [68] DEBUG: esm_class: 0 = 0x00000000 > 2016-05-22 10:39:29 [15726] [68] DEBUG: protocol_id: 0 = 0x00000000 > 2016-05-22 10:39:29 [15726] [68] DEBUG: priority_flag: 0 = 0x00000000 > 2016-05-22 10:39:29 [15726] [68] DEBUG: schedule_delivery_time: NULL > 2016-05-22 10:39:29 [15726] [68] DEBUG: validity_period: NULL > 2016-05-22 10:39:29 [15726] [68] DEBUG: registered_delivery: 0 = 0x00000000 > 2016-05-22 10:39:29 [15726] [68] DEBUG: replace_if_present_flag: 0 = > 0x00000000 > 2016-05-22 10:39:29 [15726] [68] DEBUG: data_coding: 0 = 0x00000000 > 2016-05-22 10:39:29 [15726] [68] DEBUG: sm_default_msg_id: 0 = 0x00000000 > 2016-05-22 10:39:29 [15726] [68] DEBUG: sm_length: 112 = 0x00000070 > 2016-05-22 10:39:29 [15726] [68] DEBUG: short_message: > 2016-05-22 10:39:29 [15726] [68] DEBUG: Octet string at 0x7f6b4c000f40: > 2016-05-22 10:39:29 [15726] [68] DEBUG: len: 112 > 2016-05-22 10:39:29 [15726] [68] DEBUG: size: 113 > 2016-05-22 10:39:29 [15726] [68] DEBUG: immutable: 0 > 2016-05-22 10:39:29 [15726] [68] DEBUG: data: 69 64 3a 31 34 36 33 39 30 > 36 33 36 38 36 38 39 id:1463906368689 > 2016-05-22 10:39:29 [15726] [68] DEBUG: data: 30 30 31 34 31 30 33 20 73 > 75 62 3a 30 30 31 20 0014103 sub:001 > 2016-05-22 10:39:29 [15726] [68] DEBUG: data: 64 6c 76 72 64 3a 30 30 31 > 20 73 75 62 6d 69 74 dlvrd:001 submit > 2016-05-22 10:39:29 [15726] [68] DEBUG: data: 20 64 61 74 65 3a 31 36 30 > 35 32 32 31 30 33 39 date:1605221039 > 2016-05-22 10:39:29 [15726] [68] DEBUG: data: 20 64 6f 6e 65 20 64 61 74 > 65 3a 31 36 30 35 32 done date:16052 > 2016-05-22 10:39:29 [15726] [68] DEBUG: data: 32 31 30 33 39 20 73 74 61 > 74 3a 44 45 4c 49 56 21039 stat:DELIV > 2016-05-22 10:39:29 [15726] [68] DEBUG: data: 52 44 20 65 72 72 3a 30 30 > 30 20 74 65 78 74 3a RD err:000 text: > 2016-05-22 10:39:29 [15726] [68] DEBUG: Octet string dump ends. > 2016-05-22 10:39:29 [15726] [68] DEBUG: message_state: 2 = 0x00000002 > 2016-05-22 10:39:29 [15726] [68] DEBUG: receipted_message_id: > 2016-05-22 10:39:29 [15726] [68] DEBUG: Octet string at 0x7f6b4c00b9a0: > 2016-05-22 10:39:29 [15726] [68] DEBUG: len: 20 > 2016-05-22 10:39:29 [15726] [68] DEBUG: size: 21 > 2016-05-22 10:39:29 [15726] [68] DEBUG: immutable: 0 > 2016-05-22 10:39:29 [15726] [68] DEBUG: data: 31 34 36 33 39 30 36 33 36 > 38 36 38 39 30 30 31 1463906368689001 > 2016-05-22 10:39:29 [15726] [68] DEBUG: data: 34 31 30 33 > 4103 > 2016-05-22 10:39:29 [15726] [68] DEBUG: Octet string dump ends. > 2016-05-22 10:39:29 [15726] [68] DEBUG: SMPP PDU dump ends. > > > 2016-05-22 10:40:14 [15726] [68] DEBUG: SMPP PDU 0x7f6b4c00c530 dump: > 2016-05-22 10:40:14 [15726] [68] DEBUG: type_name: deliver_sm > 2016-05-22 10:40:14 [15726] [68] DEBUG: command_id: 5 = 0x00000005 > 2016-05-22 10:40:14 [15726] [68] DEBUG: command_status: 0 = 0x00000000 > 2016-05-22 10:40:14 [15726] [68] DEBUG: sequence_number: 3 = 0x00000003 > 2016-05-22 10:40:14 [15726] [68] DEBUG: service_type: NULL > 2016-05-22 10:40:14 [15726] [68] DEBUG: source_addr_ton: 1 = 0x00000001 > 2016-05-22 10:40:14 [15726] [68] DEBUG: source_addr_npi: 1 = 0x00000001 > 2016-05-22 10:40:14 [15726] [68] DEBUG: source_addr: "201003441970" > 2016-05-22 10:40:14 [15726] [68] DEBUG: dest_addr_ton: 5 = 0x00000005 > 2016-05-22 10:40:14 [15726] [68] DEBUG: dest_addr_npi: 0 = 0x00000000 > 2016-05-22 10:40:14 [15726] [68] DEBUG: destination_addr: "..." > 2016-05-22 10:40:14 [15726] [68] DEBUG: esm_class: 0 = 0x00000000 > 2016-05-22 10:40:14 [15726] [68] DEBUG: protocol_id: 0 = 0x00000000 > 2016-05-22 10:40:14 [15726] [68] DEBUG: priority_flag: 0 = 0x00000000 > 2016-05-22 10:40:14 [15726] [68] DEBUG: schedule_delivery_time: NULL > 2016-05-22 10:40:14 [15726] [68] DEBUG: validity_period: NULL > 2016-05-22 10:40:14 [15726] [68] DEBUG: registered_delivery: 0 = 0x00000000 > 2016-05-22 10:40:14 [15726] [68] DEBUG: replace_if_present_flag: 0 = > 0x00000000 > 2016-05-22 10:40:14 [15726] [68] DEBUG: data_coding: 0 = 0x00000000 > 2016-05-22 10:40:14 [15726] [68] DEBUG: sm_default_msg_id: 0 = 0x00000000 > 2016-05-22 10:40:14 [15726] [68] DEBUG: sm_length: 109 = 0x0000006d > 2016-05-22 10:40:14 [15726] [68] DEBUG: short_message: > 2016-05-22 10:40:14 [15726] [68] DEBUG: Octet string at 0x7f6b4c00bb60: > 2016-05-22 10:40:14 [15726] [68] DEBUG: len: 109 > 2016-05-22 10:40:14 [15726] [68] DEBUG: size: 110 > 2016-05-22 10:40:14 [15726] [68] DEBUG: immutable: 0 > 2016-05-22 10:40:14 [15726] [68] DEBUG: data: 69 64 3a 31 34 36 33 39 30 > 36 33 36 38 36 38 39 id:1463906368689 > 2016-05-22 10:40:14 [15726] [68] DEBUG: data: 30 30 31 34 31 30 33 20 73 > 75 62 3a 30 30 31 20 0014103 sub:001 > 2016-05-22 10:40:14 [15726] [68] DEBUG: data: 64 6c 76 72 64 3a 30 30 31 > 20 73 75 62 6d 69 74 dlvrd:001 submit > 2016-05-22 10:40:14 [15726] [68] DEBUG: data: 20 64 61 74 65 3a 31 36 30 > 35 32 32 31 30 34 30 date:1605221040 > 2016-05-22 10:40:14 [15726] [68] DEBUG: data: 20 64 6f 6e 65 20 64 61 74 > 65 3a 31 36 30 35 32 done date:16052 > 2016-05-22 10:40:14 [15726] [68] DEBUG: data: 32 31 30 34 30 20 73 74 61 > 74 3a 53 45 45 4e 20 21040 stat:SEEN > 2016-05-22 10:40:14 [15726] [68] DEBUG: data: 65 72 72 3a 30 30 30 20 74 > 65 78 74 3a err:000 text: > 2016-05-22 10:40:14 [15726] [68] DEBUG: Octet string dump ends. > 2016-05-22 10:40:14 [15726] [68] DEBUG: message_state: 2 = 0x00000002 > 2016-05-22 10:40:14 [15726] [68] DEBUG: receipted_message_id: > 2016-05-22 10:40:14 [15726] [68] DEBUG: Octet string at 0x7f6b4c00bc10: > 2016-05-22 10:40:14 [15726] [68] DEBUG: len: 20 > 2016-05-22 10:40:14 [15726] [68] DEBUG: size: 21 > 2016-05-22 10:40:14 [15726] [68] DEBUG: immutable: 0 > 2016-05-22 10:40:14 [15726] [68] DEBUG: data: 31 34 36 33 39 30 36 33 36 > 38 36 38 39 30 30 31 1463906368689001 > 2016-05-22 10:40:14 [15726] [68] DEBUG: data: 34 31 30 33 > 4103 > 2016-05-22 10:40:14 [15726] [68] DEBUG: Octet string dump ends. > 2016-05-22 10:40:14 [15726] [68] DEBUG: SMPP PDU dump ends. > > > Did anyone have idea what is make kannel behave like that ?!
