Re: Help... while sending Wap Push

[Nikos Balkanas]

Restart kannel. Seems after trying confirmed pap kannel stalls.
BR,
Nikos
----- Original Message ----- 
From: "Nikos Balkanas" <nbalka...@gmail.com>
To: "Ishtiaq Ahmed" <ishtiaq.ah...@ooober.com>
Cc: <users@kannel.org>
Sent: Thursday, February 19, 2009 3:33 PM
Subject: Re: Help... while sending Wap Push


Please use the main CVS head. Meta-branch has merged in.

Also there seems to be a problem with confirmed pap. I don't think is 
implemented. Try unconfirmed.

BR,
Nikos
----- Original Message ----- 
From: "Ishtiaq Ahmed" <ishtiaq.ah...@ooober.com>
To: "Nikos Balkanas" <nbalka...@gmail.com>
Cc: <users@kannel.org>
Sent: Thursday, February 19, 2009 2:33 PM
Subject: Re: Help... while sending Wap Push



Hi Nik....

Under at the PAP and the content file... and I have taken the latest code 
from CVS meta-data branch, compiled and installed... Under are the logs 
at this time...

?<--asdlfkjiurwgasf
Content-Type: application/xml

<?xml version="1.0"?>
<!DOCTYPE pap PUBLIC "-//WAPFORUM//DTD PAP//EN"
"http://www.wapforum.org/DTD/pap_1.0.dtd";>
<pap>
<push-message push-id="food...@1235046673421.com"
progress-notes-requested="true">
<address address-value="WAPPUSH=+923335712725/TYPE=PLMN"/>
<quality-of-service delivery-method="confirmed"
network-required="true"
network="GSM"
bearer-required="true"
bearer="SMS"/></push-message>
</pap>

--asdlfkjiurwgasf
Content-Type: text/vnd.wap.si

<?xml version="1.0"?>
<!DOCTYPE si PUBLIC "-//WAPFORUM//DTD SI 1.0//EN"
" http://www.wapforum.org/DTD/si.dtd";>
<si>
<indication action="" si-id="food...@1235046673421.com" 
href="http://api.ooober.com/deviceinfo.htm?uid=770963673";> Download 
Content</indication>
</si>
--asdlfkjiurwgasf--

*LOGS

*2009-02-19 17:21:09 [6492] [11] DEBUG: HTTP: Creating HTTPClient for 
`172.21.21.21'.
2009-02-19 17:21:09 [6492] [11] DEBUG: HTTP: Created HTTPClient area 
0xdc3d510.
2009-02-19 17:21:09 [6492] [14] WARNING: Your ppg core configuration 
allow all ips
2009-02-19 17:21:09 [6492] [14] INFO: PPG: Accept request </wappush> from 
<172.21.21.21>
2009-02-19 17:21:09 [6492] [14] DEBUG: PPG: http_read_thread: pap 
multipart accepted
2009-02-19 17:21:09 [6492] [14] INFO: client address was <+923335712725>, 
accepted
2009-02-19 17:21:09 [6492] [14] DEBUG: PAP COMPILER: doing semantic 
analysis for address type a phone number
2009-02-19 17:21:09 [6492] [14] DEBUG: using defaults instead of anys
2009-02-19 17:21:09 [6492] [14] DEBUG: PPG: http_read_thread: pap control 
entity compiled ok
2009-02-19 17:21:09 [6492] [14] WARNING: unparsable dlr mask, rejected
2009-02-19 17:21:09 [6492] [14] DEBUG: PPG: transform_message: push 
message content and headers valid
2009-02-19 17:21:09 [6492] [14] DEBUG: PPG: Created PPGSessionMachine 0
2009-02-19 17:21:09 [6492] [14] DEBUG: PPG: push machine 0 created
2009-02-19 17:21:09 [6492] [14] DEBUG: PPG: store_push_data: push machine 
0 appended to push list of sm machine 0
2009-02-19 17:21:09 [6492] [14] DEBUG: PPG: send_push_response: telling 
pi
2009-02-19 17:21:09 [6492] [14] DEBUG: HTTP: Resetting HTTPClient for 
`172.21.21.21'.
2009-02-19 17:21:09 [6492] [14] INFO: PPG: handle_push_message: push 
message accepted for processing
2009-02-19 17:21:09 [6492] [9] DEBUG: OTA: event arrived
2009-02-19 17:21:09 [6492] [9] DEBUG: OTA: making a connectionless 
session request for creating a session
2009-02-19 17:21:09 [6492] [2] DEBUG: WSP (UNIT): event arrived
2009-02-19 17:21:09 [6492] [2] DEBUG: WSP_UNIT: Connectionless push 
accepted
2009-02-19 17:21:09 [6492] [2] DEBUG: WSP: Mapping 
`application/vnd.wap.sia', WSP 1.2 to 0x0034.
2009-02-19 17:21:09 [6492] [2] DEBUG: WSP: Mapping `User-Agent', WSP 1.3 
to 0x0029.
2009-02-19 17:21:09 [6492] [2] DEBUG: WSP: Mapping `Content-Length', WSP 
1.3 to 0x000d.
2009-02-19 17:21:09 [6492] [2] DEBUG: WSP: Mapping 
`X-WAP-Application-Id', WSP 1.3 to 0x002f.
2009-02-19 17:21:09 [6492] [2] DEBUG: WSP: Mapping `Content-Type', WSP 
1.3 to 0x0011.
2009-02-19 17:21:09 [6492] [2] DEBUG: WSP: Mapping `Encoding-Version', 
WSP 1.3 to 0x0043.
2009-02-19 17:21:09 [6492] [2] DEBUG: WDP (wapbox): delivering 1 segments 
to bearerbox
2009-02-19 17:21:09 [6492] [2] DEBUG: WSP (UNIT): delivering to wdp
2009-02-19 17:21:09 [6492] [0] WARNING: Received other message than 
wdp/admin, ignoring!


----------------------------------



Nikos Balkanas wrote:
You mean there is no error in the logs and no message is delivered? What 
about the throttling errors you mentioned?

Please post your PAP message.

Nikos
----- Original Message ----- From: "Ishtiaq Ahmed" 
<ishtiaq.ah...@ooober.com>
To: "Nikos Balkanas" <nbalka...@gmail.com>
Cc: <users@kannel.org>
Sent: Thursday, February 19, 2009 1:56 PM
Subject: Re: Help... while sending Wap Push


Hi Nik.. there is no message being delivered to the client set.... But 
for now we are debugging the following error... even thou we are 
setting the headers right...



2009-02-19 16:47:01 [21019] [11] DEBUG: HTTP: Creating HTTPClient for 
`172.21.21.21'.
2009-02-19 16:47:01 [21019] [11] DEBUG: HTTP: Created HTTPClient area 
0x1a4562f0.
2009-02-19 16:47:01 [21019] [14] WARNING: Your ppg core configuration 
allow all ips
2009-02-19 16:47:01 [21019] [14] INFO: PPG: Accept request </wappush> 
from <172.21.21.21>
2009-02-19 16:47:01 [21019] [14] DEBUG: PPG: http_read_thread: pap 
multipart accepted
2009-02-19 16:47:01 [21019] [14] INFO: client address was 
<+923335712725>, accepted
2009-02-19 16:47:01 [21019] [14] DEBUG: PAP COMPILER: doing semantic 
analysis for address type a phone number
2009-02-19 16:47:01 [21019] [14] DEBUG: using defaults instead of anys
2009-02-19 16:47:01 [21019] [14] DEBUG: PPG: http_read_thread: pap 
control entity compiled ok
2009-02-19 16:47:01 [21019] [14] WARNING: unparsable dlr mask, rejected
2009-02-19 16:47:01 [21019] [14] DEBUG: PPG: transform_message: push 
message content and headers valid
2009-02-19 16:47:01 [21019] [14] DEBUG: PPG: push machine 5 created
2009-02-19 16:47:01 [21019] [14] DEBUG: PPG: store_push_data: push 
machine 5 appended to push list of sm machine 0
2009-02-19 16:47:01 [21019] [14] DEBUG: PPG: send_push_response: 
telling pi
2009-02-19 16:47:01 [21019] [14] DEBUG: HTTP: Resetting HTTPClient for 
`172.21.21.21'.
2009-02-19 16:47:01 [21019] [14] INFO: PPG: handle_push_message: push 
message accepted for processing
2009-02-19 16:47:01 [21019] [14] *WARNING: PPG: handle_push_message: 
wrong app id for confirmed push session creation*
2009-02-19 16:47:01 [21019] [14] DEBUG: PPG: send_push_response: 
telling pi



Regards,


Ishtiaq Ahmed



Nikos Balkanas wrote:
Hi,

Am I missing something? I don't see errors in the logs.

BR,
Nikos
----- Original Message ----- From: "Ishtiaq Ahmed" 
<ishtiaq.ah...@ooober.com>
To: <users@kannel.org>
Sent: Thursday, February 19, 2009 9:50 AM
Subject: Help... while sending Wap Push


Hi users,

An urgent resolution is needed here.... While sending out the wap 
push I am getting problem as the message has been pushed as binary 
but need a throttling value of 2msg/sec... otherwise SMSC return 
Throttling error ... This mean the message is of length 2... where as 
its a simple wappush with small URL like http://kannel.org...

I have attached the logs... May someone of you can shed some light or 
mechanism over troubleshooting this one...

*WAPBOX Logs*

2009-02-19 02:39:57 [27549] [14] DEBUG: PPG: ip_allowed_by_ppg: ip 
found from allowed list
2009-02-19 02:39:57 [27549] [14] INFO: PPG: Accept request </wappush> 
from <10.248.103.177>
2009-02-19 02:39:57 [27549] [14] DEBUG: PPG: http_read_thread: pap 
multipart accepted
2009-02-19 02:39:57 [27549] [14] INFO: client address was 
<+923028560772>, accepted
2009-02-19 02:39:57 [27549] [14] DEBUG: PAP COMPILER: doing semantic 
analysis for address type a phone number
2009-02-19 02:39:57 [27549] [14] DEBUG: using defaults instead of 
anys
2009-02-19 02:39:57 [27549] [14] DEBUG: PPG: http_read_thread: pap 
control entity compiled ok
2009-02-19 02:39:57 [27549] [14] DEBUG: PPG: transform_message: push 
message content and headers valid
2009-02-19 02:39:57 [27549] [14] DEBUG: PPG: push machine 0 created
2009-02-19 02:39:57 [27549] [14] DEBUG: PPG: store_push_data: push 
machine 0 appended to unit push list
2009-02-19 02:39:57 [27549] [14] DEBUG: PPG: send_push_response: 
telling pi
2009-02-19 02:39:57 [27549] [14] DEBUG: HTTP: Resetting HTTPClient 
for `10.248.103.177'.
2009-02-19 02:39:57 [27549] [14] INFO: PPG: handle_push_message: push 
message accepted for processing
2009-02-19 02:39:57 [27549] [14] DEBUG: PPG: OTA request for unit 
push
2009-02-19 02:39:57 [27549] [14] INFO: PPG: unconfirmed push 
delivered to OTA
2009-02-19 02:39:57 [27549] [14] DEBUG: PPG: destroying push machine 
0
2009-02-19 02:39:57 [27549] [9] DEBUG: OTA: event arrived
2009-02-19 02:39:57 [27549] [9] DEBUG: OTA: made connectionless 
session service request
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP (UNIT): event arrived
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP_UNIT: Connectionless push 
accepted
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping 
`application/vnd.wap.sic', WSP 1.2 to 0x002e.
2009-02-19 02:39:57 [27549] [2] ERROR: HTTP: Header does not contain 
a colon. BAD.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `X-Unknown', WSP 
1.3 to 0xffffffff.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `Cache-Control', 
WSP 1.3 to 0x003d.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `Pragma', WSP 1.3 
to 0x001f.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `User-Agent', WSP 
1.3 to 0x0029.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `Accept', WSP 1.3 
to 0x0000.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `text/html', WSP 
1.2 to 0x0002.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `image/gif', WSP 
1.2 to 0x001d.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `image/jpeg', WSP 
1.2 to 0x001e.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `*', WSP 1.2 to 
0xffffffff.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `q', WSP 1.2 to 
0x0000.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `*/*', WSP 1.2 to 
0x0000.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `q', WSP 1.2 to 
0x0000.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `Content-Length', 
WSP 1.3 to 0x000d.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping `Content-Type', 
WSP 1.3 to 0x0011.
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP: Mapping 
`Encoding-Version', WSP 1.3 to 0x0043.
2009-02-19 02:39:57 [27549] [2] DEBUG: WDP (wapbox): delivering 2 
segments to bearerbox
2009-02-19 02:39:57 [27549] [2] DEBUG: WSP (UNIT): delivering to wdp
2009-02-19 02:39:57 [27549] [0] WARNING: Received other message than 
wdp/admin, ignoring!
2009-02-19 02:39:57 [27549] [0] WARNING: Received other message than 
wdp/admin, ignoring!


KANNEL.log
2009-02-19 12:32:16 [16854] [27] DEBUG: boxc_receiver: sms received
2009-02-19 12:32:16 [16854] [27] DEBUG: send_msg: sending msg to box: 
<127.0.0.1>
2009-02-19 12:32:16 [16854] [6] DEBUG: SMPP[Mobilink1]: Sending PDU:
2009-02-19 12:32:16 [16854] [6] DEBUG: SMPP PDU 0x2aaaac009f80 dump:
2009-02-19 12:32:16 [16854] [6] DEBUG: type_name: submit_sm
2009-02-19 12:32:16 [16854] [6] DEBUG: command_id: 4 = 0x00000004
2009-02-19 12:32:16 [16854] [6] DEBUG: command_status: 0 = 0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: sequence_number: 2255 = 
0x000008cf
2009-02-19 12:32:16 [16854] [6] DEBUG: service_type: NULL
2009-02-19 12:32:16 [16854] [6] DEBUG: source_addr_ton: 2 = 
0x00000002
2009-02-19 12:32:16 [16854] [6] DEBUG: source_addr_npi: 1 = 
0x00000001
2009-02-19 12:32:16 [16854] [6] DEBUG: source_addr: "6261"
2009-02-19 12:32:16 [16854] [6] DEBUG: dest_addr_ton: 1 = 0x00000001
2009-02-19 12:32:16 [16854] [6] DEBUG: dest_addr_npi: 1 = 0x00000001
2009-02-19 12:32:16 [16854] [6] DEBUG: destination_addr: 
"923028560772"
2009-02-19 12:32:16 [16854] [6] DEBUG: esm_class: 67 = 0x00000043
2009-02-19 12:32:16 [16854] [6] DEBUG: protocol_id: 0 = 0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: priority_flag: 0 = 0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: schedule_delivery_time: NULL
2009-02-19 12:32:16 [16854] [6] DEBUG: validity_period: NULL
2009-02-19 12:32:16 [16854] [6] DEBUG: registered_delivery: 0 = 
0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: replace_if_present_flag: 0 = 
0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: data_coding: 4 = 0x00000004
2009-02-19 12:32:16 [16854] [6] DEBUG: sm_default_msg_id: 0 = 
0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: sm_length: 140 = 0x0000008c
2009-02-19 12:32:16 [16854] [6] DEBUG: short_message:
2009-02-19 12:32:16 [16854] [6] DEBUG: Octet string at 
0x2aaaac008210:
2009-02-19 12:32:16 [16854] [6] DEBUG: len: 140
2009-02-19 12:32:16 [16854] [6] DEBUG: size: 1024
2009-02-19 12:32:16 [16854] [6] DEBUG: immutable: 0
2009-02-19 12:32:16 [16854] [6] DEBUG: data: 0b 05 04 0b 84 23 f0 00 
03 00 02 01 00 06 49 ae .....#........I.
2009-02-19 12:32:16 [16854] [6] DEBUG: data: 58 2d 55 6e 6b 6e 6f 77 
6e 00 58 2d 4b 61 6e 6e X-Unknown.X-Kann
2009-02-19 12:32:16 [16854] [6] DEBUG: data: 65 6c 2d 44 4c 52 2d 4d 
61 73 6b 00 bd 80 9f 80 el-DLR-Mask.....
2009-02-19 12:32:16 [16854] [6] DEBUG: data: a9 4a 61 76 61 2f 31 2e 
36 2e 30 5f 31 30 00 80 .Java/1.6.0_10..
2009-02-19 12:32:16 [16854] [6] DEBUG: data: 82 80 9d 80 9e 80 06 2a 
00 80 2e 32 00 80 05 80 .......*...2....
2009-02-19 12:32:16 [16854] [6] DEBUG: data: 80 2e 32 00 8d c2 c3 93 
02 05 6a 00 45 c6 05 11 ..2.......j.E...
2009-02-19 12:32:16 [16854] [6] DEBUG: data: 03 66 6f 6f 64 72 65 67 
40 2d 31 39 38 36 39 37 .food...@-198697
2009-02-19 12:32:16 [16854] [6] DEBUG: data: 32 39 32 32 2e 63 6f 6d 
00 0c 03 79 61 68 6f 6f 2922.com...yahoo
2009-02-19 12:32:16 [16854] [6] DEBUG: data: 2e 63 6f 6d 00 01 03 44 
6f 77 6e 6c .com...Downl
2009-02-19 12:32:16 [16854] [6] DEBUG: Octet string dump ends.
2009-02-19 12:32:16 [16854] [6] DEBUG: SMPP PDU dump ends.
2009-02-19 12:32:16 [16854] [27] DEBUG: boxc_receiver: sms received
2009-02-19 12:32:16 [16854] [27] DEBUG: send_msg: sending msg to box: 
<127.0.0.1>
2009-02-19 12:32:16 [16854] [6] DEBUG: SMPP[Mobilink1]: Sending PDU:
2009-02-19 12:32:16 [16854] [6] DEBUG: SMPP PDU 0x2aaaac009f80 dump:
2009-02-19 12:32:16 [16854] [6] DEBUG: type_name: submit_sm
2009-02-19 12:32:16 [16854] [6] DEBUG: command_id: 4 = 0x00000004
2009-02-19 12:32:16 [16854] [6] DEBUG: command_status: 0 = 0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: sequence_number: 2256 = 
0x000008d0
2009-02-19 12:32:16 [16854] [6] DEBUG: service_type: NULL
2009-02-19 12:32:16 [16854] [6] DEBUG: source_addr_ton: 2 = 
0x00000002
2009-02-19 12:32:16 [16854] [6] DEBUG: source_addr_npi: 1 = 
0x00000001
2009-02-19 12:32:16 [16854] [6] DEBUG: source_addr: "6261"
2009-02-19 12:32:16 [16854] [6] DEBUG: dest_addr_ton: 1 = 0x00000001
2009-02-19 12:32:16 [16854] [6] DEBUG: dest_addr_npi: 1 = 0x00000001
2009-02-19 12:32:16 [16854] [6] DEBUG: destination_addr: 
"923028560772"
2009-02-19 12:32:16 [16854] [6] DEBUG: esm_class: 67 = 0x00000043
2009-02-19 12:32:16 [16854] [6] DEBUG: protocol_id: 0 = 0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: priority_flag: 0 = 0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: schedule_delivery_time: NULL
2009-02-19 12:32:16 [16854] [6] DEBUG: validity_period: NULL
2009-02-19 12:32:16 [16854] [6] DEBUG: registered_delivery: 0 = 
0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: replace_if_present_flag: 0 = 
0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: data_coding: 4 = 0x00000004
2009-02-19 12:32:16 [16854] [6] DEBUG: sm_default_msg_id: 0 = 
0x00000000
2009-02-19 12:32:16 [16854] [6] DEBUG: sm_length: 26 = 0x0000001a
2009-02-19 12:32:16 [16854] [6] DEBUG: short_message:
2009-02-19 12:32:16 [16854] [6] DEBUG: Octet string at 
0x2aaaac0057a0:
2009-02-19 12:32:16 [16854] [6] DEBUG: len: 26
2009-02-19 12:32:16 [16854] [6] DEBUG: size: 1024
2009-02-19 12:32:16 [16854] [6] DEBUG: immutable: 0
2009-02-19 12:32:16 [16854] [6] DEBUG: data: 0b 05 04 0b 84 23 f0 00 
03 00 02 02 6f 61 64 20 .....#......oad
2009-02-19 12:32:16 [16854] [6] DEBUG: data: 43 6f 6e 74 65 6e 74 00 
01 01 Content...
2009-02-19 12:32:16 [16854] [6] DEBUG: Octet string dump ends.
2009-02-19 12:32:16 [16854] [6] DEBUG: SMPP PDU dump ends.