Hi Friends,
I have a problem in sending pict.mesg/logo/tone etc using kannel through an SMPP smsc.
Longer messages are send as two or three parts, right? When sending like this
one part is failing. For 3 part messages the failing rate is much more than 2 part messages.
 
I will explain some thing that I noticed from the log files when sending a picture message:
 
The following is the log of the three parts I got from bearerbox access log.
You can see that the third part is FAILED.
 
Part 1
2003-03-11 10:14:15 Sent SMS [SMSC:smppsc] [SVC:usmsc] [ACT:] [from:333] [to:+971507200033] [flags:0:2:0:0:0]
[msg:128:3002010000481C01000007FFFFFFFF800000000FFFFFFFFF800000001FFFFFFFFF800000001FFFFFFFFF8000FFFFD7FFFFFFFFBFFFDDD5D7FFFFFFFFB67CFFFFD7FFFFFFFFBFFFF1B4D7FFFFFFFF1ABBFFFFDC429A2B32BFFF557551254892441535FF9FC7FFFFFFFFBFFEFE67CFFFF912951FF9FDFBD7E8094A90AFF7FBFDD7]
[udh:12:0B0504158A158A0003AF0301]
Part 2
2003-03-11 10:14:15 Sent SMS [SMSC:smppsc] [SVC:usmsc] [ACT:] [from:333] [to:+971507200033] [flags:0:2:0:0:0]
[msg:128:EEBFFFFFB7EFF7C6D7FB6FFFFF9BDFFF82D7E00FFFFF9BCE670157FBBFFFFF8984620057ED6FFEFF8984620057E00FFEFF8984620057EFBFFC7F8984620057FECFFC7F898462005FE00FFB3F898467005FFF7FF61F89846FFF5FEACFF41F9D8E60005FFFFFF45FBDBF60005FF01FF05F858008D49ABFF75055A15175697A92AA]
[udh:12:0B0504158A158A0003AF0302]
Part 3
2003-03-11 10:14:15 FAILED Send SMS [SMSC:smppsc] [SVC:usmsc] [ACT:] [from:333] [to:+971507200033] [flags:0:2:0:0:0]
[msg:4:B4BF32BA] [udh:12:0B0504158A158A0003AF0303]
 
The debug output from the bearer box for the above message is given below. You can see 'Sending PDU' 3 times for 3 parts
for each submit_sm.
There is also the response submit_sm_resp (Got PDU) 3 times.
In the third submit_sm_resp you can notice that the message_id is coming as NULL. This is the case where that part
is failing. Some times this is coming also for two of the three parts. When there are only two parts also,
this error is coming for one of the part, but not always.
When the message_id is coming as NULL and the part fails, there is an error coming as follows as you can notice
from the log:
2003-03-11 10:14:15 [6] ERROR: SMPP[smppsc]: SMSC returned error code 0x00000009 in response to submit_sm.
This NULL message_id and error code is coming for each failed part.
 
From bearerbox log :
 
2003-03-11 10:14:14 [9] DEBUG: boxc_receiver: sms received
2003-03-11 10:14:14 [9] DEBUG: boxc_receiver: sms received
2003-03-11 10:14:14 [9] DEBUG: boxc_receiver: sms received
2003-03-11 10:14:14 [6] DEBUG: SMPP[smppsc]: Manually forced source addr ton = 0, source add npi = 0
2003-03-11 10:14:14 [6] DEBUG: SMPP[smppsc]: Manually forced dest addr ton = 0, dest add npi = 0
2003-03-11 10:14:14 [6] DEBUG: SMPP[smppsc]: Sending PDU:
2003-03-11 10:14:14 [6] DEBUG: SMPP PDU 0x80e6148 dump:
2003-03-11 10:14:14 [6] DEBUG:   type_name: submit_sm
2003-03-11 10:14:14 [6] DEBUG:   command_id: 4 = 0x00000004
2003-03-11 10:14:14 [6] DEBUG:   command_status: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   sequence_number: 1356 = 0x0000054c
2003-03-11 10:14:14 [6] DEBUG:   service_type: NULL
2003-03-11 10:14:14 [6] DEBUG:   source_addr_ton: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   source_addr_npi: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   source_addr: "333"
2003-03-11 10:14:14 [6] DEBUG:   dest_addr_ton: 1 = 0x00000001
2003-03-11 10:14:14 [6] DEBUG:   dest_addr_npi: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   destination_addr: "971507200033"
2003-03-11 10:14:14 [6] DEBUG:   esm_class: 67 = 0x00000043
2003-03-11 10:14:14 [6] DEBUG:   protocol_id: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   priority_flag: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   schedule_delivery_time: NULL
2003-03-11 10:14:14 [6] DEBUG:   validity_period: NULL
2003-03-11 10:14:14 [6] DEBUG:   registered_delivery: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   replace_if_present_flag: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   data_coding: 4 = 0x00000004
2003-03-11 10:14:14 [6] DEBUG:   sm_default_msg_id: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   sm_length: 140 = 0x0000008c
2003-03-11 10:14:14 [6] DEBUG:   short_message:
2003-03-11 10:14:14 [6] DEBUG:    Octet string at 0x80e7bb0:
2003-03-11 10:14:14 [6] DEBUG:      len:  140
2003-03-11 10:14:14 [6] DEBUG:      size: 141
2003-03-11 10:14:14 [6] DEBUG:      immutable: 0
2003-03-11 10:14:14 [6] DEBUG:      data: 0b 05 04 15 8a 15 8a 00   ........
2003-03-11 10:14:14 [6] DEBUG:      data: 03 af 03 01 30 02 01 00   ....0...
2003-03-11 10:14:14 [6] DEBUG:      data: 00 48 1c 01 00 00 07 ff   .H......
2003-03-11 10:14:14 [6] DEBUG:      data: ff ff ff 80 00 00 00 0f   ........
2003-03-11 10:14:14 [6] DEBUG:      data: ff ff ff ff 80 00 00 00   ........
2003-03-11 10:14:14 [6] DEBUG:      data: 1f ff ff ff ff 80 00 00   ........
2003-03-11 10:14:14 [6] DEBUG:      data: 00 1f ff ff ff ff 80 00   ........
2003-03-11 10:14:14 [6] DEBUG:      data: ff ff d7 ff ff ff ff bf   ........
2003-03-11 10:14:14 [6] DEBUG:      data: ff dd d5 d7 ff ff ff ff   ........
2003-03-11 10:14:14 [6] DEBUG:      data: b6 7c ff ff d7 ff ff ff   .|......
2003-03-11 10:14:14 [6] DEBUG:      data: ff bf ff f1 b4 d7 ff ff   ........
2003-03-11 10:14:14 [6] DEBUG:      data: ff ff 1a bb ff ff dc 42   .......B
2003-03-11 10:14:14 [6] DEBUG:      data: 9a 2b 32 bf ff 55 75 51   .+2..UuQ
2003-03-11 10:14:14 [6] DEBUG:      data: 25 48 92 44 15 35 ff 9f   %H.D.5..
2003-03-11 10:14:14 [6] DEBUG:      data: c7 ff ff ff ff bf fe fe   ........
2003-03-11 10:14:14 [6] DEBUG:      data: 67 cf ff f9 12 95 1f f9   g.......
2003-03-11 10:14:14 [6] DEBUG:      data: fd fb d7 e8 09 4a 90 af   .....J..
2003-03-11 10:14:14 [6] DEBUG:      data: f7 fb fd d7               ....
2003-03-11 10:14:14 [6] DEBUG:    Octet string dump ends.
2003-03-11 10:14:14 [6] DEBUG: SMPP PDU dump ends.
2003-03-11 10:14:14 [6] DEBUG: SMPP[smppsc]: Manually forced source addr ton = 0, source add npi = 0
2003-03-11 10:14:14 [6] DEBUG: SMPP[smppsc]: Manually forced dest addr ton = 0, dest add npi = 0
2003-03-11 10:14:14 [6] DEBUG: SMPP[smppsc]: Sending PDU:
2003-03-11 10:14:14 [6] DEBUG: SMPP PDU 0x80e6148 dump:
2003-03-11 10:14:14 [6] DEBUG:   type_name: submit_sm
2003-03-11 10:14:14 [6] DEBUG:   command_id: 4 = 0x00000004
2003-03-11 10:14:14 [6] DEBUG:   command_status: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   sequence_number: 1357 = 0x0000054d
2003-03-11 10:14:14 [6] DEBUG:   service_type: NULL
2003-03-11 10:14:14 [6] DEBUG:   source_addr_ton: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   source_addr_npi: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   source_addr: "333"
2003-03-11 10:14:14 [6] DEBUG:   dest_addr_ton: 1 = 0x00000001
2003-03-11 10:14:14 [6] DEBUG:   dest_addr_npi: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   destination_addr: "971507200033"
2003-03-11 10:14:14 [6] DEBUG:   esm_class: 67 = 0x00000043
2003-03-11 10:14:14 [6] DEBUG:   protocol_id: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   priority_flag: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   schedule_delivery_time: NULL
2003-03-11 10:14:14 [6] DEBUG:   validity_period: NULL
2003-03-11 10:14:14 [6] DEBUG:   registered_delivery: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   replace_if_present_flag: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   data_coding: 4 = 0x00000004
2003-03-11 10:14:14 [6] DEBUG:   sm_default_msg_id: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   sm_length: 140 = 0x0000008c
2003-03-11 10:14:14 [6] DEBUG:   short_message:
2003-03-11 10:14:14 [6] DEBUG:    Octet string at 0x80e4df0:
2003-03-11 10:14:14 [6] DEBUG:      len:  140
2003-03-11 10:14:14 [6] DEBUG:      size: 141
2003-03-11 10:14:14 [6] DEBUG:      immutable: 0
2003-03-11 10:14:14 [6] DEBUG:      data: 0b 05 04 15 8a 15 8a 00   ........
2003-03-11 10:14:14 [6] DEBUG:      data: 03 af 03 02 ee bf ff ff   ........
2003-03-11 10:14:14 [6] DEBUG:      data: b7 ef f7 c6 d7 fb 6f ff   ......o.
2003-03-11 10:14:14 [6] DEBUG:      data: ff 9b df ff 82 d7 e0 0f   ........
2003-03-11 10:14:14 [6] DEBUG:      data: ff ff 9b ce 67 01 57 fb   ....g.W.
2003-03-11 10:14:14 [6] DEBUG:      data: bf ff ff 89 84 62 00 57   .....b.W
2003-03-11 10:14:14 [6] DEBUG:      data: ed 6f fe ff 89 84 62 00   .o....b.
2003-03-11 10:14:14 [6] DEBUG:      data: 57 e0 0f fe ff 89 84 62   W......b
2003-03-11 10:14:14 [6] DEBUG:      data: 00 57 ef bf fc 7f 89 84   .W......
2003-03-11 10:14:14 [6] DEBUG:      data: 62 00 57 fe cf fc 7f 89   b.W.....
2003-03-11 10:14:14 [6] DEBUG:      data: 84 62 00 5f e0 0f fb 3f   .b._...?
2003-03-11 10:14:14 [6] DEBUG:      data: 89 84 67 00 5f ff 7f f6   ..g._...
2003-03-11 10:14:14 [6] DEBUG:      data: 1f 89 84 6f ff 5f ea cf   ...o._..
2003-03-11 10:14:14 [6] DEBUG:      data: f4 1f 9d 8e 60 00 5f ff   ....`._.
2003-03-11 10:14:14 [6] DEBUG:      data: ff f4 5f bd bf 60 00 5f   .._..`._
2003-03-11 10:14:14 [6] DEBUG:      data: f0 1f f0 5f 85 80 08 d4   ..._....
2003-03-11 10:14:14 [6] DEBUG:      data: 9a bf f7 50 55 a1 51 75   ...PU.Qu
2003-03-11 10:14:14 [6] DEBUG:      data: 69 7a 92 aa               iz..
2003-03-11 10:14:14 [6] DEBUG:    Octet string dump ends.
2003-03-11 10:14:14 [6] DEBUG: SMPP PDU dump ends.
2003-03-11 10:14:14 [6] DEBUG: SMPP[smppsc]: Manually forced source addr ton = 0, source add npi = 0
2003-03-11 10:14:14 [6] DEBUG: SMPP[smppsc]: Manually forced dest addr ton = 0, dest add npi = 0
2003-03-11 10:14:14 [6] DEBUG: SMPP[smppsc]: Sending PDU:
2003-03-11 10:14:14 [6] DEBUG: SMPP PDU 0x80e6148 dump:
2003-03-11 10:14:14 [6] DEBUG:   type_name: submit_sm
2003-03-11 10:14:14 [6] DEBUG:   command_id: 4 = 0x00000004
2003-03-11 10:14:14 [6] DEBUG:   command_status: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   sequence_number: 1358 = 0x0000054e
2003-03-11 10:14:14 [6] DEBUG:   service_type: NULL
2003-03-11 10:14:14 [6] DEBUG:   source_addr_ton: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   source_addr_npi: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   source_addr: "333"
2003-03-11 10:14:14 [6] DEBUG:   dest_addr_ton: 1 = 0x00000001
2003-03-11 10:14:14 [6] DEBUG:   dest_addr_npi: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   destination_addr: "971507200033"
2003-03-11 10:14:14 [6] DEBUG:   esm_class: 67 = 0x00000043
2003-03-11 10:14:14 [6] DEBUG:   protocol_id: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   priority_flag: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   schedule_delivery_time: NULL
2003-03-11 10:14:14 [6] DEBUG:   validity_period: NULL
2003-03-11 10:14:14 [6] DEBUG:   registered_delivery: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   replace_if_present_flag: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   data_coding: 4 = 0x00000004
2003-03-11 10:14:14 [6] DEBUG:   sm_default_msg_id: 0 = 0x00000000
2003-03-11 10:14:14 [6] DEBUG:   sm_length: 16 = 0x00000010
2003-03-11 10:14:14 [6] DEBUG:   short_message:
2003-03-11 10:14:14 [6] DEBUG:    Octet string at 0x80e7bb0:
2003-03-11 10:14:14 [6] DEBUG:      len:  16
2003-03-11 10:14:14 [6] DEBUG:      size: 17
2003-03-11 10:14:14 [6] DEBUG:      immutable: 0
2003-03-11 10:14:14 [6] DEBUG:      data: 0b 05 04 15 8a 15 8a 00   ........
2003-03-11 10:14:14 [6] DEBUG:      data: 03 af 03 03 b4 bf 32 ba   ......2.
2003-03-11 10:14:14 [6] DEBUG:    Octet string dump ends.
2003-03-11 10:14:14 [6] DEBUG: SMPP PDU dump ends.
2003-03-11 10:14:15 [6] DEBUG: SMPP[smppsc]: Got PDU:
2003-03-11 10:14:15 [6] DEBUG: SMPP PDU 0x80e6148 dump:
2003-03-11 10:14:15 [6] DEBUG:   type_name: submit_sm_resp
2003-03-11 10:14:15 [6] DEBUG:   command_id: 2147483652 = 0x80000004
2003-03-11 10:14:15 [6] DEBUG:   command_status: 0 = 0x00000000
2003-03-11 10:14:15 [6] DEBUG:   sequence_number: 1356 = 0x0000054c
2003-03-11 10:14:15 [6] DEBUG:   message_id: "b63e03dd"
2003-03-11 10:14:15 [6] DEBUG: SMPP PDU dump ends.
2003-03-11 10:14:15 [1] DEBUG: Dumping 2 messages and 0 acks to store
2003-03-11 10:14:15 [6] DEBUG: SMPP[smppsc]: Got PDU:
2003-03-11 10:14:15 [6] DEBUG: SMPP PDU 0x80e6148 dump:
2003-03-11 10:14:15 [6] DEBUG:   type_name: submit_sm_resp
2003-03-11 10:14:15 [6] DEBUG:   command_id: 2147483652 = 0x80000004
2003-03-11 10:14:15 [6] DEBUG:   command_status: 0 = 0x00000000
2003-03-11 10:14:15 [6] DEBUG:   sequence_number: 1357 = 0x0000054d
2003-03-11 10:14:15 [6] DEBUG:   message_id: "b63e051f"
2003-03-11 10:14:15 [6] DEBUG: SMPP PDU dump ends.
2003-03-11 10:14:15 [6] DEBUG: SMPP[smppsc]: Got PDU:
2003-03-11 10:14:15 [6] DEBUG: SMPP PDU 0x80e6148 dump:
2003-03-11 10:14:15 [6] DEBUG:   type_name: submit_sm_resp
2003-03-11 10:14:15 [6] DEBUG:   command_id: 2147483652 = 0x80000004
2003-03-11 10:14:15 [6] DEBUG:   command_status: 9 = 0x00000009
2003-03-11 10:14:15 [6] DEBUG:   sequence_number: 1358 = 0x0000054e
2003-03-11 10:14:15 [6] DEBUG:   message_id: NULL
2003-03-11 10:14:15 [6] DEBUG: SMPP PDU dump ends.
2003-03-11 10:14:15 [6] ERROR: SMPP[smppsc]: SMSC returned error code 0x00000009 in response to submit_sm.
 
But the response we get from kannel after a sendsms is that it has sent the message successfully.
The following log from smsbox log shows that.
 
2003-03-11 10:14:14 [3] INFO: sendsms used by <smstester>
2003-03-11 10:14:14 [3] INFO: sendsms sender:<usmsc:333> (10.100.1.5) to:<+971507200033> msg:<0>
2003-03-11 10:14:14 [3] DEBUG: message length 260, sending 3 messages
2003-03-11 10:14:14 [3] DEBUG: Status: 202 Answer: <Sent. Message splits: 3>
 
I hope you have understood what the problem is.
If any one of you have any idea about this problem, please let me know.
I need an urgent rectification for this problem. So Please help.
 
Thanking you in advance.
Shanavaz.
 

Reply via email to