Hi, there. Having a problem with handling deliver_sm.
Kannel couldn't parse message id:
2016-08-12 14:45:59 [2952] [6] DEBUG: SMPP[SMSC]: Couldnot parse DLR
string sscanf way,fallback to old way. Please report!
2016-08-12 14:45:59 [2952] [6] ERROR: SMPP[SMSC]: got DLR but could not
find message or was not interested in it id<> dst<79028710256>, type<2>
2016-08-12 14:45:59 [2952] [6] DEBUG: message_id: NULL
*
*
**Full log**
2016-08-12 14:45:53 [2952] [6] DEBUG: SMPP[SMSC]: Sending PDU:
2016-08-12 14:45:53 [2952] [6] DEBUG: SMPP PDU 0x7f42b00029c0 dump:
2016-08-12 14:45:53 [2952] [6] DEBUG: type_name: submit_sm
2016-08-12 14:45:53 [2952] [6] DEBUG: command_id: 4 = 0x00000004
2016-08-12 14:45:53 [2952] [6] DEBUG: command_status: 0 = 0x00000000
2016-08-12 14:45:53 [2952] [6] DEBUG: sequence_number: 4 = 0x00000004
2016-08-12 14:45:53 [2952] [6] DEBUG: service_type: NULL
2016-08-12 14:45:53 [2952] [6] DEBUG: source_addr_ton: 1 = 0x00000001
2016-08-12 14:45:53 [2952] [6] DEBUG: source_addr_npi: 1 = 0x00000001
2016-08-12 14:45:53 [2952] [6] DEBUG: source_addr: "3432690000"
2016-08-12 14:45:53 [2952] [6] DEBUG: dest_addr_ton: 1 = 0x00000001
2016-08-12 14:45:53 [2952] [6] DEBUG: dest_addr_npi: 1 = 0x00000001
2016-08-12 14:45:53 [2952] [6] DEBUG: destination_addr: "79028710256"
2016-08-12 14:45:53 [2952] [6] DEBUG: esm_class: 67 = 0x00000043
2016-08-12 14:45:53 [2952] [6] DEBUG: protocol_id: 127 = 0x0000007f
2016-08-12 14:45:53 [2952] [6] DEBUG: priority_flag: 0 = 0x00000000
2016-08-12 14:45:53 [2952] [6] DEBUG: schedule_delivery_time: NULL
2016-08-12 14:45:53 [2952] [6] DEBUG: validity_period: NULL
2016-08-12 14:45:53 [2952] [6] DEBUG: registered_delivery: 1 = 0x00000001
2016-08-12 14:45:53 [2952] [6] DEBUG: replace_if_present_flag: 0 =
0x00000000
2016-08-12 14:45:53 [2952] [6] DEBUG: data_coding: 246 = 0x000000f6
2016-08-12 14:45:53 [2952] [6] DEBUG: sm_default_msg_id: 0 = 0x00000000
2016-08-12 14:45:53 [2952] [6] DEBUG: sm_length: 53 = 0x00000035
2016-08-12 14:45:53 [2952] [6] DEBUG: short_message:
2016-08-12 14:45:53 [2952] [6] DEBUG: Octet string at 0x7f42b0000be0:
2016-08-12 14:45:53 [2952] [6] DEBUG: len: 53
2016-08-12 14:45:53 [2952] [6] DEBUG: size: 1024
2016-08-12 14:45:53 [2952] [6] DEBUG: immutable: 0
2016-08-12 14:45:53 [2952] [6] DEBUG: data: 02 70 00 00 30 15 06 3a 19
19 b0 00 00 1f 7e 72 .p..0..:......~r
2016-08-12 14:45:53 [2952] [6] DEBUG: data: da 2c d1 5e 41 a3 4a 95 0e
20 e4 92 8e 4d fe d8 .,.^A.J.. ...M..
2016-08-12 14:45:53 [2952] [6] DEBUG: data: cc 7a 3f 62 f5 ce 8d 25 70
65 47 db ad cf 3e 77 .z?b...%peG...>w
2016-08-12 14:45:53 [2952] [6] DEBUG: data: 4b 2a 57 09 1f K*W..
2016-08-12 14:45:53 [2952] [6] DEBUG: Octet string dump ends.
2016-08-12 14:45:53 [2952] [6] DEBUG: SMPP PDU dump ends.
2016-08-12 14:45:53 [2952] [6] DEBUG: SMPP[SMSC]: Got PDU:
2016-08-12 14:45:53 [2952] [6] DEBUG: SMPP PDU 0x7f42b00029c0 dump:
2016-08-12 14:45:53 [2952] [6] DEBUG: type_name: submit_sm_resp
2016-08-12 14:45:53 [2952] [6] DEBUG: command_id: 2147483652 = 0x80000004
2016-08-12 14:45:53 [2952] [6] DEBUG: command_status: 0 = 0x00000000
2016-08-12 14:45:53 [2952] [6] DEBUG: sequence_number: 4 = 0x00000004
**2016-08-12 14:45:53 [2952] [6] DEBUG: message_id: "6D7872F"**
2016-08-12 14:45:53 [2952] [6] DEBUG: SMPP PDU dump ends.
2016-08-12 14:45:53 [2952] [6] DEBUG: DLR[internal]: Adding DLR
smsc=SMSC, ts=114788143, src=3432690000, dst=79028710256, mask=7,
boxc=smsbox-web
2016-08-12 14:45:59 [2952] [6] DEBUG: Optional parameter tag (0x0427)
2016-08-12 14:45:59 [2952] [6] DEBUG: Optional parameter length read as 1
2016-08-12 14:45:59 [2952] [6] DEBUG: Optional parameter tag (0x0423)
2016-08-12 14:45:59 [2952] [6] DEBUG: Optional parameter length read as 3
2016-08-12 14:45:59 [2952] [6] DEBUG: Optional parameter tag (0x001e)
2016-08-12 14:45:59 [2952] [6] DEBUG: Optional parameter length read as 8
2016-08-12 14:45:59 [2952] [6] DEBUG: Optional parameter tag (0x0202)
2016-08-12 14:45:59 [2952] [6] DEBUG: Optional parameter length read as 12
2016-08-12 14:45:59 [2952] [6] DEBUG: SMPP[SMSC]: Got PDU:
2016-08-12 14:45:59 [2952] [6] DEBUG: SMPP PDU 0x7f42b0002d70 dump:
2016-08-12 14:45:59 [2952] [6] DEBUG: type_name: deliver_sm
2016-08-12 14:45:59 [2952] [6] DEBUG: command_id: 5 = 0x00000005
2016-08-12 14:45:59 [2952] [6] DEBUG: command_status: 0 = 0x00000000
2016-08-12 14:45:59 [2952] [6] DEBUG: sequence_number: 653984193 =
0x26fb01c1
2016-08-12 14:45:59 [2952] [6] DEBUG: service_type: NULL
2016-08-12 14:45:59 [2952] [6] DEBUG: source_addr_ton: 1 = 0x00000001
2016-08-12 14:45:59 [2952] [6] DEBUG: source_addr_npi: 1 = 0x00000001
2016-08-12 14:45:59 [2952] [6] DEBUG: source_addr: "79028710256"
2016-08-12 14:45:59 [2952] [6] DEBUG: dest_addr_ton: 1 = 0x00000001
2016-08-12 14:45:59 [2952] [6] DEBUG: dest_addr_npi: 1 = 0x00000001
2016-08-12 14:45:59 [2952] [6] DEBUG: destination_addr: "3432690000"
2016-08-12 14:45:59 [2952] [6] DEBUG: esm_class: 4 = 0x00000004
2016-08-12 14:45:59 [2952] [6] DEBUG: protocol_id: 127 = 0x0000007f
2016-08-12 14:45:59 [2952] [6] DEBUG: priority_flag: 0 = 0x00000000
2016-08-12 14:45:59 [2952] [6] DEBUG: schedule_delivery_time: NULL
2016-08-12 14:45:59 [2952] [6] DEBUG: validity_period: NULL
2016-08-12 14:45:59 [2952] [6] DEBUG: registered_delivery: 0 = 0x00000000
2016-08-12 14:45:59 [2952] [6] DEBUG: replace_if_present_flag: 0 =
0x00000000
2016-08-12 14:45:59 [2952] [6] DEBUG: data_coding: 0 = 0x00000000
2016-08-12 14:45:59 [2952] [6] DEBUG: sm_default_msg_id: 0 = 0x00000000
2016-08-12 14:45:59 [2952] [6] DEBUG: sm_length: 0 = 0x00000000
2016-08-12 14:45:59 [2952] [6] DEBUG: short_message: ""
2016-08-12 14:45:59 [2952] [6] DEBUG: source_subaddress:
2016-08-12 14:45:59 [2952] [6] DEBUG: Octet string at 0x7f42b0002ae0:
2016-08-12 14:45:59 [2952] [6] DEBUG: len: 12
2016-08-12 14:45:59 [2952] [6] DEBUG: size: 13
2016-08-12 14:45:59 [2952] [6] DEBUG: immutable: 0
2016-08-12 14:45:59 [2952] [6] DEBUG: data: a0 37 39 30 32 38 37 31 30
30 39 39 .79028710099
2016-08-12 14:45:59 [2952] [6] DEBUG: Octet string dump ends.
2016-08-12 14:45:59 [2952] [6] DEBUG: network_error_code:
2016-08-12 14:45:59 [2952] [6] DEBUG: Octet string at 0x7f42b0002a60:
2016-08-12 14:45:59 [2952] [6] DEBUG: len: 3
2016-08-12 14:45:59 [2952] [6] DEBUG: size: 4
2016-08-12 14:45:59 [2952] [6] DEBUG: immutable: 0
2016-08-12 14:45:59 [2952] [6] DEBUG: data: 03 00 00 ...
2016-08-12 14:45:59 [2952] [6] DEBUG: Octet string dump ends.
2016-08-12 14:45:59 [2952] [6] DEBUG: message_state: 2 = 0x00000002
**2016-08-12 14:45:59 [2952] [6] DEBUG: receipted_message_id: "6D7872F"**
2016-08-12 14:45:59 [2952] [6] DEBUG: SMPP PDU dump ends.
2016-08-12 14:45:59 [2952] [6] DEBUG: SMPP[SMSC] handle_pdu, got DLR
**2016-08-12 14:45:59 [2952] [6] DEBUG: SMPP[SMSC]: Couldnot parse DLR
string sscanf way,fallback to old way. Please report!***
***2016-08-12 14:45:59 [2952] [6] ERROR: SMPP[SMSC]: got DLR but could
not find message or was not interested in it id<> dst<79028710256>,
type<2>**
2016-08-12 14:45:59 [2952] [6] DEBUG: SMPP[SMSC]: Sending PDU:
2016-08-12 14:45:59 [2952] [6] DEBUG: SMPP PDU 0x7f42b0003020 dump:
2016-08-12 14:45:59 [2952] [6] DEBUG: type_name: deliver_sm_resp
2016-08-12 14:45:59 [2952] [6] DEBUG: command_id: 2147483653 = 0x80000005
2016-08-12 14:45:59 [2952] [6] DEBUG: command_status: 0 = 0x00000000
2016-08-12 14:45:59 [2952] [6] DEBUG: sequence_number: 653984193 =
0x26fb01c1
**2016-08-12 14:45:59 [2952] [6] DEBUG: message_id: NULL**
2016-08-12 14:45:59 [2952] [6] DEBUG: SMPP PDU dump ends.
**Kannel version**
Kannel bearerbox version `1.4.4'.
Build `Jan 1 1970 00:00:00', compiler `5.3.1 20160528'.
System Linux, release 3.16.0-4-amd64, version #1 SMP Debian
3.16.7-ckt25-2+deb8u3 (2016-07-02), machine x86_64.
Libxml version 2.9.3.
Using OpenSSL 1.0.2h 3 May 2016.
Compiled with MySQL 5.6.30, using MySQL 5.5.50.
Compiled with PostgreSQL 9.5.3.
Using SQLite 3.13.0.
Using hiredis API 0.13.3
Using native malloc.
Status: running, uptime 0d 0h 0m 26s
WDP: received 0 (0 queued), sent 0 (0 queued)
SMS: received 0 (0 queued), sent 1 (0 queued), store size 0
SMS: inbound (0.00,0.00,0.00) msg/sec, outbound (0.04,0.04,0.04) msg/sec
DLR: received 0, sent 0
DLR: inbound (0.00,0.00,0.00) msg/sec, outbound (0.00,0.00,0.00) msg/sec
DLR: 1 queued, using internal storage
--
Alexey Cherepanov
