----- Original Message -----
Sent: Thursday, March 13, 2003 12:37
PM
Subject: FAILED Send SMS
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.
