wap 1.x and wtls

[Armindo Antunes]

Hi!





Could it be the way I've generated the certificate?
     openssl req -new -newkey rsa:1024 -keyout server.key -out server.req


The OpenSSL version is 1.0.0d that I've compiled to include RC5 algorithm.





Any help would be appreciated.
Thanks in Advance,
Armindo Antunes



Complete logs:
sudo tail -f /var/log/kannel/*.log
==> /var/log/kannel/bearerbox.log <==
2011-07-06 10:44:44 [925] [10] DEBUG: datagram received

2011-07-06 10:44:44 [925] [13] INFO: Client connected from <127.0.0.1>

2011-07-06 10:44:44 [925] [21] DEBUG: setting up systems for new wapbox


==> /var/log/kannel/wapbox.log <==
2011-07-06 10:44:44 [2318] [0] DEBUG: Msg object at 0x8685038:
2011-07-06 10:44:44 [2318] [0] DEBUG:  type: wdp_datagram
2011-07-06 10:44:44 [2318] [0] DEBUG:  wdp_datagram.source_address:
2011-07-06 10:44:44 [2318] [0] DEBUG:  Octet string at 0x8683af0:
2011-07-06 10:44:44 [2318] [0] DEBUG:    len:  12
2011-07-06 10:44:44 [2318] [0] DEBUG:    size: 13
2011-07-06 10:44:44 [2318] [0] DEBUG:    immutable: 0

2011-07-06 10:44:44 [2318] [0] DEBUG:  Octet string dump ends.
2011-07-06 10:44:44 [2318] [0] DEBUG:  wdp_datagram.source_port: 8502
2011-07-06 10:44:44 [2318] [0] DEBUG:  wdp_datagram.destination_address:
2011-07-06 10:44:44 [2318] [0] DEBUG:  Octet string at 0x8683b98:
2011-07-06 10:44:44 [2318] [0] DEBUG:    len:  7
2011-07-06 10:44:44 [2318] [0] DEBUG:    size: 8
2011-07-06 10:44:44 [2318] [0] DEBUG:    immutable: 0

2011-07-06 10:44:44 [2318] [0] DEBUG:  Octet string dump ends.
2011-07-06 10:44:44 [2318] [0] DEBUG:  wdp_datagram.destination_port: 9203
2011-07-06 10:44:44 [2318] [0] DEBUG:  wdp_datagram.user_data:
2011-07-06 10:44:44 [2318] [0] DEBUG:  Octet string at 0x8683c30:
2011-07-06 10:44:44 [2318] [0] DEBUG:    len:  229
2011-07-06 10:44:44 [2318] [0] DEBUG:    size: 230
2011-07-06 10:44:44 [2318] [0] DEBUG:    immutable: 0

2011-07-06 10:44:44 [2318] [0] DEBUG:  Octet string dump ends.
2011-07-06 10:44:44 [2318] [0] DEBUG: Msg object ends.
2011-07-06 10:44:44 [2318] [0] INFO: Event created
2011-07-06 10:44:44 [2318] [0] INFO: Set address and stuff
2011-07-06 10:44:44 [2318] [0] DEBUG: Offset is now : 0
2011-07-06 10:44:44 [2318] [0] DEBUG: Finished, found 1 PDUs
2011-07-06 10:44:44 [2318] [0] INFO: Datagram unpacked!
2011-07-06 10:44:44 [2318] [9] DEBUG: event->type = 0

2011-07-06 10:44:44 [2337] [0] DEBUG: no syslog parameter

2011-07-06 10:44:44 [2337] [0] DEBUG: Connecting to <127.0.0.1>


==> /var/log/kannel/bearerbox.log <==
2011-07-06 10:44:55 [925] [10] DEBUG: datagram received
2011-07-06 10:44:55 [925] [12] DEBUG: Old wapbox has disappeared, re-routing


==> /var/log/kannel/wapbox.log <==
2011-07-06 10:44:55 [2337] [0] DEBUG: Msg object at 0x8d22c30:
2011-07-06 10:44:55 [2337] [0] DEBUG:  type: wdp_datagram
2011-07-06 10:44:55 [2337] [0] DEBUG:  wdp_datagram.source_address:
2011-07-06 10:44:55 [2337] [0] DEBUG:  Octet string at 0x8d21af0:
2011-07-06 10:44:55 [2337] [0] DEBUG:    len:  12
2011-07-06 10:44:55 [2337] [0] DEBUG:    size: 13
2011-07-06 10:44:55 [2337] [0] DEBUG:    immutable: 0

2011-07-06 10:44:55 [2337] [0] DEBUG:  Octet string dump ends.
2011-07-06 10:44:55 [2337] [0] DEBUG:  wdp_datagram.source_port: 8502
2011-07-06 10:44:55 [2337] [0] DEBUG:  wdp_datagram.destination_address:
2011-07-06 10:44:55 [2337] [0] DEBUG:  Octet string at 0x8d21b98:
2011-07-06 10:44:55 [2337] [0] DEBUG:    len:  7
2011-07-06 10:44:55 [2337] [0] DEBUG:    size: 8
2011-07-06 10:44:55 [2337] [0] DEBUG:    immutable: 0

2011-07-06 10:44:55 [2337] [0] DEBUG:  Octet string dump ends.
2011-07-06 10:44:55 [2337] [0] DEBUG:  wdp_datagram.destination_port: 9203
2011-07-06 10:44:55 [2337] [0] DEBUG:  wdp_datagram.user_data:
2011-07-06 10:44:55 [2337] [0] DEBUG:  Octet string at 0x8d21c30:
2011-07-06 10:44:55 [2337] [0] DEBUG:    len:  229
2011-07-06 10:44:55 [2337] [0] DEBUG:    size: 230
2011-07-06 10:44:55 [2337] [0] DEBUG:    immutable: 0

2011-07-06 10:44:55 [2337] [0] DEBUG:  Octet string dump ends.
2011-07-06 10:44:55 [2337] [0] DEBUG: Msg object ends.
2011-07-06 10:44:55 [2337] [0] INFO: Event created
2011-07-06 10:44:55 [2337] [0] INFO: Set address and stuff
2011-07-06 10:44:55 [2337] [0] DEBUG: Offset is now : 0
2011-07-06 10:44:55 [2337] [0] DEBUG: Finished, found 1 PDUs
2011-07-06 10:44:55 [2337] [0] INFO: Datagram unpacked!
2011-07-06 10:44:55 [2337] [9] DEBUG: event->type = 0


==> /var/log/kannel/bearerbox.log <==
2011-07-06 10:45:09 [925] [10] DEBUG: datagram received
2011-07-06 10:45:09 [925] [12] DEBUG: Old wapbox has disappeared, re-routing
2011-07-06 10:45:09 [925] [12] WARNING: Cannot route message, discard it
2011-07-06 10:45:27 [925] [10] DEBUG: datagram received

2011-07-06 10:45:27 [925] [12] WARNING: Cannot route message, discard it
2011-07-06 10:45:28 [925] [10] DEBUG: datagram received
2011-07-06 10:45:28 [925] [10] DEBUG: datagram received

2011-07-06 10:45:28 [925] [12] WARNING: Cannot route message, discard it

2011-07-06 10:45:28 [925] [12] WARNING: Cannot route message, discard it
2011-07-06 10:45:44 [925] [13] INFO: Client connected from <127.0.0.1>

2011-07-06 10:45:44 [925] [23] DEBUG: setting up systems for new wapbox


==> /var/log/kannel/wapbox.log <==

2011-07-06 10:45:44 [2350] [0] DEBUG: no syslog parameter

2011-07-06 10:45:44 [2350] [0] DEBUG: Connecting to <127.0.0.1>

I'm trying to use the current development release 1.5.0 as a wap 1.x gateway on ubuntu linux. Unsecured connections are working fine but when enabling secure connections, the wapbox doesn't respond and it crashes on the handshake phase. I've tested with Openwave v7 Simulator, Nokia 7650 and Sony Ericsson T68i without success. openssl ca -policy policy_anything -notext -in server.req -out server.crt Where can I find the proper instrutions to create the needed certificates for the WTLS connection? Please find in attach my kannel configuration (and certificates), wireshark capture and complete logs for the request. 2011-07-06 10:44:44 [925] [12] DEBUG: Did not find previous routing info for WDP, generating new 2011-07-06 10:44:44 [925] [20] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2011-07-06 10:44:44 [925] [20] DEBUG: boxc_sender: sent message to <127.0.0.1> 2011-07-06 10:44:44 [925] [19] INFO: Connection closed by the box <127.0.0.1> 2011-07-06 10:44:44 [925] [20] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2011-07-06 10:44:44 [925] [20] DEBUG: Thread 20 (gw/bb_boxc.c:boxc_sender) terminates. 2011-07-06 10:44:44 [925] [19] DEBUG: Thread 19 (gw/bb_boxc.c:function) terminates. 2011-07-06 10:44:44 [925] [13] DEBUG: Started thread 21 (gw/bb_boxc.c:function) 2011-07-06 10:44:44 [925] [21] DEBUG: Thread 21 (gw/bb_boxc.c:function) maps to pid 925. 2011-07-06 10:44:44 [925] [21] DEBUG: Started thread 22 (gw/bb_boxc.c:boxc_sender) 2011-07-06 10:44:44 [925] [22] DEBUG: Thread 22 (gw/bb_boxc.c:boxc_sender) maps to pid 925. 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 31 37 32 2e 31 38 2e 30 2e 32 33 33 172.18.0.233 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 30 2e 30 2e 30 2e 30 0.0.0.0 2011-07-06 10:44:44 [2318] [0] DEBUG: data: c3 00 00 00 e0 01 00 dd 01 00 05 f8 ee 51 c2 7f .............Q.. 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 27 4c c7 f2 5a 77 f0 1d f1 00 00 20 08 00 02 04 'L..Zw..... .... 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 00 00 00 00 05 00 02 04 00 00 00 00 07 00 02 04 ................ 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 00 00 00 00 06 00 02 04 00 00 00 00 00 8a 08 00 ................ 2011-07-06 10:44:44 [2318] [0] DEBUG: data: fe 7d fb 7f c3 4f 6e b9 6b 04 7e 77 5d 97 b2 27 .}...On.k.~w]..' 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 88 ed 34 c3 dc 08 00 fe 7a 48 cd 96 45 57 6a 0b ..4.....zH..EWj. 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 49 55 89 7e 45 f5 ee b7 92 48 37 7f 08 00 fe 27 IU.~E....H7....' 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 28 80 d8 ff 97 9c dc 60 33 6f b8 f0 a4 7d d7 b0 (......`3o...}.. 2011-07-06 10:44:44 [2318] [0] DEBUG: data: de 72 9c 08 00 fe c6 eb ff 5b 34 25 fe 67 44 81 .r.......[4%.gD. 2011-07-06 10:44:44 [2318] [0] DEBUG: data: d1 aa 3b 10 d8 71 b9 be 58 60 08 00 fe b1 b0 a7 ..;..q..X`...... 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 82 7e f7 18 c4 75 12 b7 37 29 06 2b 62 e4 57 64 .~...u..7).+b.Wd 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 09 08 00 fe 7c 67 ee 07 82 8c 0a 9f 5a 06 3f ee ....|g......Z.?. 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 9b bc f8 9d 1a 62 63 f6 18 03 03 03 02 03 01 02 .....bc......... 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 03 02 02 02 01 01 03 01 02 01 01 00 03 00 02 00 ................ 2011-07-06 10:44:44 [2318] [0] DEBUG: data: 01 01 00 02 10 ..... 2011-07-06 10:44:44 [2318] [9] DEBUG: WTLS: Created WTLSMachine 0 (0x0x8685208) 2011-07-06 10:44:44 [2318] [9] DEBUG: WTLS: wtls_machine 0, state NULL_STATE, event T-Unitdata.ind. 2011-07-06 10:44:44 [2318] [9] DEBUG: wtls_choose_ciphersuite ~> Accepted cipher: RC5_CBC, mac: SHA_NOLIMIT (#1/12) 2011-07-06 10:44:44 [2337] [0] INFO: Added logfile `/var/log/kannel/wapbox.log' with level `-1'. 2011-07-06 10:44:44 [2337] [0] INFO: Starting to log to file /var/log/kannel/wapbox.log level -1 2011-07-06 10:44:44 [2337] [0] DEBUG: certificate parameter is /etc/kannel/server.crt 2011-07-06 10:44:44 [2337] [0] DEBUG: certificate parameter is /etc/kannel/server.key 2011-07-06 10:44:44 [2337] [0] INFO: ------------------------------------------------------------ 2011-07-06 10:44:44 [2337] [0] INFO: Kannel wapbox version 1.5.0 starting up. 2011-07-06 10:44:44 [2337] [0] DEBUG: Started thread 1 (wap/wsp_session.c:main_thread) 2011-07-06 10:44:44 [2337] [1] DEBUG: Thread 1 (wap/wsp_session.c:main_thread) maps to pid 2337. 2011-07-06 10:44:44 [2337] [0] DEBUG: Started thread 2 (wap/wsp_unit.c:main_thread) 2011-07-06 10:44:44 [2337] [0] DEBUG: Started thread 3 (wap/wsp_push_client.c:main_thread) 2011-07-06 10:44:44 [2337] [3] DEBUG: Thread 3 (wap/wsp_push_client.c:main_thread) maps to pid 2337. 2011-07-06 10:44:44 [2337] [0] DEBUG: Started thread 4 (wap/timers.c:watch_timers) 2011-07-06 10:44:44 [2337] [2] DEBUG: Thread 2 (wap/wsp_unit.c:main_thread) maps to pid 2337. 2011-07-06 10:44:44 [2337] [0] DEBUG: Started thread 5 (wap/wtp_resp.c:main_thread) 2011-07-06 10:44:44 [2337] [4] DEBUG: Thread 4 (wap/timers.c:watch_timers) maps to pid 2337. 2011-07-06 10:44:44 [2337] [5] DEBUG: Thread 5 (wap/wtp_resp.c:main_thread) maps to pid 2337. 2011-07-06 10:44:44 [2337] [0] DEBUG: Started thread 6 (gw/wap-appl.c:main_thread) 2011-07-06 10:44:44 [2337] [6] DEBUG: Thread 6 (gw/wap-appl.c:main_thread) maps to pid 2337. 2011-07-06 10:44:44 [2337] [0] DEBUG: Started thread 7 (gw/wap-appl.c:return_replies_thread) 2011-07-06 10:44:44 [2337] [7] DEBUG: Thread 7 (gw/wap-appl.c:return_replies_thread) maps to pid 2337. 2011-07-06 10:44:44 [2337] [0] DEBUG: Started thread 8 (wap/wtls-secmgr.c:main_thread) 2011-07-06 10:44:44 [2337] [8] DEBUG: Thread 8 (wap/wtls-secmgr.c:main_thread) maps to pid 2337. 2011-07-06 10:44:44 [2337] [0] DEBUG: Started thread 9 (wap/wtls.c:main_thread) 2011-07-06 10:44:44 [2337] [9] DEBUG: Thread 9 (wap/wtls.c:main_thread) maps to pid 2337. 2011-07-06 10:44:44 [2337] [0] INFO: Connected to bearerbox at localhost port 13002. 2011-07-06 10:44:44 [2337] [0] DEBUG: Started thread 10 (gw/heartbeat.c:heartbeat_thread) 2011-07-06 10:44:44 [2337] [10] DEBUG: Thread 10 (gw/heartbeat.c:heartbeat_thread) maps to pid 2337. 2011-07-06 10:44:55 [925] [22] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2011-07-06 10:44:55 [925] [22] DEBUG: boxc_sender: sent message to <127.0.0.1> 2011-07-06 10:44:55 [925] [21] INFO: Connection closed by the box <127.0.0.1> 2011-07-06 10:44:55 [925] [22] DEBUG: send_msg: sending msg to box: <127.0.0.1> 2011-07-06 10:44:55 [925] [22] DEBUG: Thread 22 (gw/bb_boxc.c:boxc_sender) terminates. 2011-07-06 10:44:55 [925] [21] DEBUG: Thread 21 (gw/bb_boxc.c:function) terminates. 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 31 37 32 2e 31 38 2e 30 2e 32 33 33 172.18.0.233 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 30 2e 30 2e 30 2e 30 0.0.0.0 2011-07-06 10:44:55 [2337] [0] DEBUG: data: c3 00 00 00 e0 01 00 dd 01 00 05 f8 ee 51 c2 7f .............Q.. 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 27 4c c7 f2 5a 77 f0 1d f1 00 00 20 08 00 02 04 'L..Zw..... .... 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 00 00 00 00 05 00 02 04 00 00 00 00 07 00 02 04 ................ 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 00 00 00 00 06 00 02 04 00 00 00 00 00 8a 08 00 ................ 2011-07-06 10:44:55 [2337] [0] DEBUG: data: fe 7d fb 7f c3 4f 6e b9 6b 04 7e 77 5d 97 b2 27 .}...On.k.~w]..' 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 88 ed 34 c3 dc 08 00 fe 7a 48 cd 96 45 57 6a 0b ..4.....zH..EWj. 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 49 55 89 7e 45 f5 ee b7 92 48 37 7f 08 00 fe 27 IU.~E....H7....' 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 28 80 d8 ff 97 9c dc 60 33 6f b8 f0 a4 7d d7 b0 (......`3o...}.. 2011-07-06 10:44:55 [2337] [0] DEBUG: data: de 72 9c 08 00 fe c6 eb ff 5b 34 25 fe 67 44 81 .r.......[4%.gD. 2011-07-06 10:44:55 [2337] [0] DEBUG: data: d1 aa 3b 10 d8 71 b9 be 58 60 08 00 fe b1 b0 a7 ..;..q..X`...... 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 82 7e f7 18 c4 75 12 b7 37 29 06 2b 62 e4 57 64 .~...u..7).+b.Wd 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 09 08 00 fe 7c 67 ee 07 82 8c 0a 9f 5a 06 3f ee ....|g......Z.?. 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 9b bc f8 9d 1a 62 63 f6 18 03 03 03 02 03 01 02 .....bc......... 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 03 02 02 02 01 01 03 01 02 01 01 00 03 00 02 00 ................ 2011-07-06 10:44:55 [2337] [0] DEBUG: data: 01 01 00 02 10 ..... 2011-07-06 10:44:55 [2337] [9] DEBUG: WTLS: Created WTLSMachine 0 (0x0x8d22e00) 2011-07-06 10:44:55 [2337] [9] DEBUG: WTLS: wtls_machine 0, state NULL_STATE, event T-Unitdata.ind. 2011-07-06 10:44:55 [2337] [9] DEBUG: wtls_choose_ciphersuite ~> Accepted cipher: RC5_CBC, mac: SHA_NOLIMIT (#1/12) 2011-07-06 10:45:27 [925] [12] DEBUG: Did not find previous routing info for WDP, generating new 2011-07-06 10:45:28 [925] [12] DEBUG: Did not find previous routing info for WDP, generating new 2011-07-06 10:45:28 [925] [12] DEBUG: Did not find previous routing info for WDP, generating new 2011-07-06 10:45:44 [925] [13] DEBUG: Started thread 23 (gw/bb_boxc.c:function) 2011-07-06 10:45:44 [925] [23] DEBUG: Thread 23 (gw/bb_boxc.c:function) maps to pid 925. 2011-07-06 10:45:44 [925] [23] DEBUG: Started thread 24 (gw/bb_boxc.c:boxc_sender) 2011-07-06 10:45:44 [925] [24] DEBUG: Thread 24 (gw/bb_boxc.c:boxc_sender) maps to pid 925. 2011-07-06 10:45:44 [2350] [0] INFO: Added logfile `/var/log/kannel/wapbox.log' with level `-1'. 2011-07-06 10:45:44 [2350] [0] INFO: Starting to log to file /var/log/kannel/wapbox.log level -1 2011-07-06 10:45:44 [2350] [0] DEBUG: certificate parameter is /etc/kannel/server.crt 2011-07-06 10:45:44 [2350] [0] DEBUG: certificate parameter is /etc/kannel/server.key 2011-07-06 10:45:44 [2350] [0] INFO: ------------------------------------------------------------ 2011-07-06 10:45:44 [2350] [0] INFO: Kannel wapbox version 1.5.0 starting up. 2011-07-06 10:45:44 [2350] [0] DEBUG: Started thread 1 (wap/wsp_session.c:main_thread) 2011-07-06 10:45:44 [2350] [1] DEBUG: Thread 1 (wap/wsp_session.c:main_thread) maps to pid 2350. 2011-07-06 10:45:44 [2350] [0] DEBUG: Started thread 2 (wap/wsp_unit.c:main_thread) 2011-07-06 10:45:44 [2350] [2] DEBUG: Thread 2 (wap/wsp_unit.c:main_thread) maps to pid 2350. 2011-07-06 10:45:44 [2350] [0] DEBUG: Started thread 3 (wap/wsp_push_client.c:main_thread) 2011-07-06 10:45:44 [2350] [3] DEBUG: Thread 3 (wap/wsp_push_client.c:main_thread) maps to pid 2350. 2011-07-06 10:45:44 [2350] [0] DEBUG: Started thread 4 (wap/timers.c:watch_timers) 2011-07-06 10:45:44 [2350] [4] DEBUG: Thread 4 (wap/timers.c:watch_timers) maps to pid 2350. 2011-07-06 10:45:44 [2350] [0] DEBUG: Started thread 5 (wap/wtp_resp.c:main_thread) 2011-07-06 10:45:44 [2350] [5] DEBUG: Thread 5 (wap/wtp_resp.c:main_thread) maps to pid 2350. 2011-07-06 10:45:44 [2350] [0] DEBUG: Started thread 6 (gw/wap-appl.c:main_thread) 2011-07-06 10:45:44 [2350] [6] DEBUG: Thread 6 (gw/wap-appl.c:main_thread) maps to pid 2350. 2011-07-06 10:45:44 [2350] [0] DEBUG: Started thread 7 (gw/wap-appl.c:return_replies_thread) 2011-07-06 10:45:44 [2350] [7] DEBUG: Thread 7 (gw/wap-appl.c:return_replies_thread) maps to pid 2350. 2011-07-06 10:45:44 [2350] [0] DEBUG: Started thread 8 (wap/wtls-secmgr.c:main_thread) 2011-07-06 10:45:44 [2350] [8] DEBUG: Thread 8 (wap/wtls-secmgr.c:main_thread) maps to pid 2350. 2011-07-06 10:45:44 [2350] [0] DEBUG: Started thread 9 (wap/wtls.c:main_thread) 2011-07-06 10:45:44 [2350] [9] DEBUG: Thread 9 (wap/wtls.c:main_thread) maps to pid 2350. 2011-07-06 10:45:44 [2350] [0] INFO: Connected to bearerbox at localhost port 13002. 2011-07-06 10:45:44 [2350] [0] DEBUG: Started thread 10 (gw/heartbeat.c:heartbeat_thread) 2011-07-06 10:45:44 [2350] [10] DEBUG: Thread 10 (gw/heartbeat.c:heartbeat_thread) maps to pid 2350.

wtls.cap
Description: Binary data

kannel-conf.tar.gz
Description: application/gzip