[Couldnot parse DLR string sscanf way,fallback to old way. Please report!] -- problem with message id
Черепанов Алексей Влади славович <[email protected]> Fri, 12 Aug 2016 15:27:35 +0500
| Newsgroups | gmane.comp.mobile.kannel.devel |
|---|---|
| Message-ID | <[email protected]> |
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