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 ?!