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