Test Information

TitleUsing new SA for outbound traffic **
CommandLine./ENODE/R_RFC2408_5_3_2_1.seq -pkt ./ENODE/R_RFC2408_5_3_2_1.def test_phase=2 test_type=BASIC -log 248.html -ti Using new SA for outbound traffic **
TestVersionundefined
ToolVersionREL_3_0_8
Start2006/03/16 17:10:04
Tn/usr/local/v6eval//etc//tn.def
Nu/usr/local/v6eval//etc//nut.def
Pkt./ENODE/R_RFC2408_5_3_2_1.def
Systemfreebsd-i386
TargetNamefreebsd5.4
HostNameracoon
Typehost

Test Sequence Execution Log

17:10:04Start

*** Target IKE initialization phase ***
Target: Reset IKE SA entries: saddump
17:10:05 vRemote(ikeResetSA.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ikeResetSA.rmt -t freebsd-i386 -u root -p v6eval -d ucom0 -o 1 saddump ''
Connected

dell# 
dell# /usr/local/sbin/setkey -c <<EOD
dump;
flush;
EOD

? dump;
? flush;
? EOD
No SAD entries.
dell# 
dell# sendMessagesSync: never got /usr/local/sbin/setkey -c <<EODdump;flush;EOD
echo $status
0
dell# kill -TERM `head -1 /var/run/racoon.pid`
head: /var/run/racoon.pid: No such file or directory

dell# 
echo $status
dell# echo $status
1
dell# /bin/rm -f /var/run/racoon.pid

dell# 
echo $status
dell# echo $status
0~
[EOT]

Target: Clear SPD entries: spddump
17:10:13 vRemote(ipsecResetSPD.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ipsecResetSPD.rmt -t freebsd-i386 -u root -p v6eval -d ucom0 -o 1 spddump ''
Connected

dell# 
dell# /usr/local/sbin/setkey -c <<EOD
spddump;
spdflush;
? spddump;
? spdflush;
EOD

? EOD
No SPD entries.

dell# 
dell# sendMessagesSync: never got /usr/local/sbin/setkey -c <<EODspddump;spdflush;EOD
echo $status
0~
[EOT]

Target: Set SPD entries: src=3ffe:501:ffff:100:290:99ff:fe7e:3e52 dst=3ffe:501:ffff:101::11 upperspec=any direction=out protocol=PROTO_IPSEC_ESP mode=Transport
17:10:21 vRemote(ipsecSetSPD.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ipsecSetSPD.rmt -t freebsd-i386 -u root -p v6eval -d ucom0 -o 1 src=3ffe:501:ffff:100:290:99ff:fe7e:3e52 dst=3ffe:501:ffff:101::11 upperspec=any direction=out protocol=PROTO_IPSEC_ESP mode=Transport ''
Connected

dell# 
dell# /usr/local/sbin/setkey -c <<EOD
spdadd 3ffe:501:ffff:100:290:99ff:fe7e:3e52 3ffe:501:ffff:101::11
       any
       -P out ipsec
       esp/transport/3ffe:501:ffff:100:290:99ff:fe7e:3e52-3ffe:501:ffff:101::11/require
;
spddump;
EOD

? spdadd 3ffe:501:ffff:100:290:99ff:fe7e:3e52 3ffe:501:ffff:101::11
       any
       -P out ipsec
       esp/transport/3ffe:501:ffff:100:290:99ff:fe7e:3e52-3ffe:501:ffff:101::11/require
;
spddump;
EOD

?        any
?        -P out ipsec
?        esp/transport/3ffe:501:ffff:100:290:99ff:fe7e:3e52-3ffe:501:ffff:101::1 1/require
? ;
? spddump;
? EOD
3ffe:501:ffff:100:290:99ff:fe7e:3e52[any] 3ffe:501:ffff:101::11[any] any
        out ipsec
        esp/transport/3ffe:501:ffff:100:290:99ff:fe7e:3e52-3ffe:501:ffff:101::11/require
        created: Mar 16 08:17:36 2006  lastused: Mar 16 08:17:36 2006
        lifetime: 0(s) validtime: 0(s)
        spid=19044 seq=0 pid=3167
        refcnt=1
dell# 
dell# sendMessagesSync: never got /usr/local/sbin/setkey -c <<EODspdadd 3ffe:501:ffff:100:290:99ff:fe7e:3e52 3ffe:501:ffff:101::11       any       -P out ipsec       esp/transport/3ffe:501:ffff:100:290:99ff:fe7e:3e52-3ffe:501:ffff:101::11/require;spddump;EOD
echo $status
0~
[EOT]

Target: Set SPD entries: dst=3ffe:501:ffff:100:290:99ff:fe7e:3e52 src=3ffe:501:ffff:101::11 upperspec=any direction=in protocol=PROTO_IPSEC_ESP mode=Transport
17:10:28 vRemote(ipsecSetSPD.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ipsecSetSPD.rmt -t freebsd-i386 -u root -p v6eval -d ucom0 -o 1 dst=3ffe:501:ffff:100:290:99ff:fe7e:3e52 src=3ffe:501:ffff:101::11 upperspec=any direction=in protocol=PROTO_IPSEC_ESP mode=Transport ''
Connected

dell# 
dell# /usr/local/sbin/setkey -c <<EOD
spdadd 3ffe:501:ffff:101::11 3ffe:501:ffff:100:290:99ff:fe7e:3e52
       any
       -P in ipsec
       esp/transport/3ffe:501:ffff:101::11-3ffe:501:ffff:100:290:99ff:fe7e:3e52/require
;
spddump;
EOD

? spdadd 3ffe:501:ffff:101::11 3ffe:501:ffff:100:290:99ff:fe7e:3e52
       any
       -P in ipsec
       esp/transport/3ffe:501:ffff:101::11-3ffe:501:ffff:100:290:99ff:fe7e:3e52/require
;
spddump;
EOD

?        any
?        -P in ipsec
?        esp/transport/3ffe:501:ffff:101::11-3ffe:501:ffff:100:290:99ff:fe7e:3e5 2/require
? ;
? spddump;
? EOD
3ffe:501:ffff:101::11[any] 3ffe:501:ffff:100:290:99ff:fe7e:3e52[any] any
        in ipsec
        esp/transport/3ffe:501:ffff:101::11-3ffe:501:ffff:100:290:99ff:fe7e:3e52/require
        created: Mar 16 08:17:43 2006  lastused: Mar 16 08:17:43 2006
        lifetime: 0(s) validtime: 0(s)
        spid=19045 seq=1 pid=3168
        refcnt=1
3ffe:501:ffff:100:290:99ff:fe7e:3e52[any] 3ffe:501:ffff:101::11[any] any
        out ipsec
        esp/transport/3ffe:501:ffff:100:290:99ff:fe7e:3e52-3ffe:501:ffff:101::11/require
        created: Mar 16 08:17:36 2006  lastused: Mar 16 08:17:36 2006
        lifetime: 0(s) validtime: 0(s)
        spid=19044 seq=0 pid=3168
        refcnt=1
dell# 
dell# sendMessagesSync: never got /usr/local/sbin/setkey -c <<EODspdadd 3ffe:501:ffff:101::11 3ffe:501:ffff:100:290:99ff:fe7e:3e52       any       -P in ipsec       esp/transport/3ffe:501:ffff:101::11-3ffe:501:ffff:100:290:99ff:fe7e:3e52/require;spddump;EOD
echo $status
0~
[EOT]

Target: Set IKE SA entries: dst=3ffe:501:ffff:101::11 dst_port=500 exchange_mode=main doi=ipsec_doi situation=identity_only isakmp_src_id_type=address isakmp_src_id=3ffe:501:ffff:100:290:99ff:fe7e:3e52 dh_group=2 lifetime=28800 lifetime_unit=seconds encryption_algorithm=3des hash_algorithm=sha1 authentication_method=pre_shared_key key_id=3ffe:501:ffff:101::11 key_value=0x494b452d54455354 ph2_id_type=address ph2_src_id=3ffe:501:ffff:100:290:99ff:fe7e:3e52 ph2_dst_id=3ffe:501:ffff:101::11 ph2_src_upper=any ph2_dst_upper=any ipsec_p_num=1 ipsec_p1_t_num=1 ph2_p1_t1_lt=60 ph2_p1_t1_lt_unit=seconds ph2_p1_t1_enc_alg=ESP_3DES ph2_p1_t1_auth_mtd=HMAC_SHA
17:10:35 vRemote(ikeSetSA.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ikeSetSA.rmt -t freebsd-i386 -u root -p v6eval -d ucom0 -o 1 dst=3ffe:501:ffff:101::11 dst_port=500 exchange_mode=main doi=ipsec_doi situation=identity_only isakmp_src_id_type=address isakmp_src_id=3ffe:501:ffff:100:290:99ff:fe7e:3e52 dh_group=2 lifetime=28800 lifetime_unit=seconds encryption_algorithm=3des hash_algorithm=sha1 authentication_method=pre_shared_key key_id=3ffe:501:ffff:101::11 key_value=0x494b452d54455354 ph2_id_type=address ph2_src_id=3ffe:501:ffff:100:290:99ff:fe7e:3e52 ph2_dst_id=3ffe:501:ffff:101::11 ph2_src_upper=any ph2_dst_upper=any ipsec_p_num=1 ipsec_p1_t_num=1 ph2_p1_t1_lt=60 ph2_p1_t1_lt_unit=seconds ph2_p1_t1_enc_alg=ESP_3DES ph2_p1_t1_auth_mtd=HMAC_SHA ''
Connected

dell# 
dell# ~[set] echocheck

dell# 
dell# ~[put] freebsd-i386.psk.txt /tmp/psk.txt
D
dell# 

dell# 
dell# /bin/chmod 600 /tmp/psk.txt
dell# echo $status
0
dell# ~[set] echocheck

dell# 
dell# ~[put] freebsd-i386.ike.conf /tmp/ike.conf
Ddell# 
dell# 
dell# test -f /var/run/racoon.pid &&kill -TERM `head -1 /var/run/racoon.pid`

dell# 
echo $status
dell# echo $status
1
dell# /usr/local/sbin/racoon -f /tmp/ike.conf

dell# 
dell# echo $status
0~
[EOT]
17:10:48 vRemote(ikeEnable.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ikeEnable.rmt -t freebsd-i386 -u root -p v6eval -d ucom0 -o 1 ''


*** Target initialization phase ***
17:10:48Start Capturing Packets (Link0)
17:10:48 vRecv(Link0,rs_from_nut rs_from_nut_wsll) timeout:15 cntLimit:0 seektime:0
vRecv() return status=1

*** Target pre-test seaquence ***
*** Phase-1 1st message send ***
17:11:03Clear Captured Packets (Link0)
17:11:03 vSend(Link0,isakmp_phase1_send_1st)
Send 1st message from HOST2(TN)

*** Phase-1 2nd message recieve ***
17:11:03 vRecv(Link0,isakmp_phase1_recv_2nd ns_uni rs_from_nut rs_from_nut_wsll ns_uni_tll_sll ns_uni_sll ns_multi_llt ns_multi) timeout:5 cntLimit:0 seektime:0
Receive Neighbor Solicitation from HOST-1(NUT)
17:11:04 vSend(Link0,na_llt)
Send Neighbor Advertisement(TN)
17:11:04 vRecv(Link0,isakmp_phase1_recv_2nd ns_uni rs_from_nut rs_from_nut_wsll ns_uni_tll_sll ns_uni_sll ns_multi_llt ns_multi) timeout:5 cntLimit:0 seektime:0
Recv 2nd message from HOST1(NUT)

OK payload_check
*** Phase-1 3rd message send ***
17:11:04Clear Captured Packets (Link0)
17:11:04 vSend(Link0,isakmp_phase1_send_3rd)
Send 3rd message from HOST2(TN)

*** Phase-1 4th message recieve ***
17:11:04 vRecv(Link0,isakmp_phase1_recv_4th ns_uni rs_from_nut rs_from_nut_wsll ns_uni_tll_sll ns_uni_sll ns_multi_llt ns_multi) timeout:5 cntLimit:0 seektime:0
Recv 4th message from HOST1(NUT)

OK payload_check
*** Phase-1 5th message send ***
17:11:05Clear Captured Packets (Link0)
17:11:05 vSend(Link0,isakmp_phase1_send_5th)
Send 5th message from HOST2(TN)

*** Phase-1 6th message recieve ***
17:11:05 vRecv(Link0,isakmp_phase1_recv_6th ns_uni rs_from_nut rs_from_nut_wsll ns_uni_tll_sll ns_uni_sll ns_multi_llt ns_multi) timeout:5 cntLimit:0 seektime:0
Recv 6th message from HOST1(NUT)

OK payload_check
*** Target testing phase start ***
*** Phase-2 1st message send ***
17:11:05Clear Captured Packets (Link0)
17:11:05 vSend(Link0,isakmp_phase2_send)
Send Phase-2 1st message (HDR*, HASH(1), SA, Ni) from HOST2(TN)

*** Phase-2 2nd message recv ***
17:11:05 vRecv(Link0,isakmp_phase2_recv_2nd ns_uni rs_from_nut rs_from_nut_wsll ns_uni_tll_sll ns_uni_sll ns_multi_llt ns_multi) timeout:5 cntLimit:0 seektime:0
Recv Phase-2 2nd message (HDR*, HASH(2), SA, Ni) from HOST1(NUT)

OK payload_check
*** Phase-2 3rd message send ***
17:11:05Clear Captured Packets (Link0)
17:11:05 vSend(Link0,isakmp_phase2_send_3rd)
Send Phase-2 3rd message HDR*, HASH(3) from HOST2(TN)
17:11:05 Wait 1 second

*** IPsec SA is esatblished ***
*** Wait 42 to expire IPsec SA Lifetime ***
17:11:06 Wait 42 second

## 1st SA elapsed time: 42 ##
*** Re-Key phase start ***
*** Re-Key 1st message send ***
17:11:48Clear Captured Packets (Link0)
17:11:48 vSend(Link0,isakmp_phase2_send)
Send Phase-2 1st message (HDR*, HASH(1), SA, Ni) from HOST2(TN)

*** Re-Key 2nd message recv ***
17:11:48 vRecv(Link0,isakmp_phase2_recv_2nd ns_uni rs_from_nut rs_from_nut_wsll ns_uni_tll_sll ns_uni_sll ns_multi_llt ns_multi) timeout:5 cntLimit:0 seektime:0
Recv Phase-2 2nd message (HDR*, HASH(2), SA, Ni) from HOST1(NUT)

OK payload_check
*** Re-Key 3rd message send ***
17:11:49Clear Captured Packets (Link0)
17:11:49 vSend(Link0,isakmp_phase2_send_3rd)
Send Phase-2 3rd message HDR*, HASH(3) from HOST2(TN)
17:11:49 Wait 1 second

*** Re-Key IPsec SA is esatblished ***
*** Encrypted Echo Request message send using 2nd IPsec SA ***
## 1st SA elapsed time: 44 ##
## 2nd SA elapsed time: 0 ##
17:11:50Clear Captured Packets (Link0)
17:11:50 vSend(Link0,echo_request_send_esp_trans_net1host2_net0host1)
Send Encrypted Echo Request from HOST-2(TN)
17:11:50 vRecv(Link0,echo_reply_recv_esp_trans_net1host2_net0host1 ns_uni rs_from_nut rs_from_nut_wsll ns_uni_tll_sll ns_uni_sll ns_multi_llt ns_multi) timeout:5 cntLimit:0 seektime:0
!!! ESP Padding size(30) over remain size(22)
recv unexpect packet at 17:11:50
Receive Neighbor Solicitation from HOST-1(NUT)
17:11:53 vSend(Link0,na_ll_llt)
Send Neighbor Advertisement(TN)
17:11:53 vRecv(Link0,echo_reply_recv_esp_trans_net1host2_net0host1 ns_uni rs_from_nut rs_from_nut_wsll ns_uni_tll_sll ns_uni_sll ns_multi_llt ns_multi) timeout:5 cntLimit:0 seektime:0
vRecv() return status=1

NG:Receive no packets
Target: Reset IKE SA entries: saddump
17:11:58 vRemote(ikeResetSA.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ikeResetSA.rmt -t freebsd-i386 -u root -p v6eval -d ucom0 -o 1 saddump ''
Connected

dell# 
dell# /usr/local/sbin/setkey -c <<EOD
dump;
flush;
EOD

? dump;
? flush;
? EOD
3ffe:501:ffff:100:290:99ff:fe7e:3e52 3ffe:501:ffff:101::11 
        esp mode=transport spi=4097(0x00001001) reqid=0(0x00000000)
        E: 3des-cbc  c3166975 639aa426 f49cefe9 9608b314 da5a1ea8 92ef5e09
        A: hmac-sha1  13a21ce1 29b75263 3d9ba1b0 db2af422 69ec7b5a
        seq=0x00000000 replay=4 flags=0x00000000 state=mature 
        created: Mar 16 08:19:03 2006   current: Mar 16 08:19:13 2006
        diff: 10(s)     hard: 60(s)     soft: 48(s)
        last:                           hard: 0(s)      soft: 0(s)
        current: 0(bytes)       hard: 0(bytes)  soft: 0(bytes)
        allocated: 0    hard: 0 soft: 0
        sadb_seq=2 pid=3175 refcnt=1
3ffe:501:ffff:100:290:99ff:fe7e:3e52 3ffe:501:ffff:101::11 
        esp mode=transport spi=4096(0x00001000) reqid=0(0x00000000)
        E: 3des-cbc  a296d255 77d203d9 516ff8d9 55ac4d7d 8d238245 45da6139
        A: hmac-sha1  e5abac18 e713065c 8dec9622 2cfa5be2 eb7b8dcb
        seq=0x00000001 replay=4 flags=0x00000000 state=dying 
        created: Mar 16 08:18:20 2006   current: Mar 16 08:19:13 2006
        diff: 53(s)     hard: 60(s)     soft: 48(s)
        last: Mar 16 08:19:04 2006      hard: 0(s)      soft: 0(s)
        current: 92(bytes)      hard: 0(bytes)  soft: 0(bytes)
        allocated: 1    hard: 0 soft: 0
        sadb_seq=1 pid=3175 refcnt=2
3ffe:501:ffff:101::11 3ffe:501:ffff:100:290:99ff:fe7e:3e52 
        esp mode=transport spi=259783206(0x0f7bfa26) reqid=0(0x00000000)
        E: 3des-cbc  cac2efb1 410927d3 ef24495c 4c4122e3 2585331c 5f2861e9
        A: hmac-sha1  16c6631f 7aa6afd9 94536573 8e1c2f30 11501aa9
        seq=0x00000001 replay=4 flags=0x00000000 state=mature 
        created: Mar 16 08:19:03 2006   current: Mar 16 08:19:14 2006
        diff: 11(s)     hard: 60(s)     soft: 48(s)
        last: Mar 16 08:19:04 2006      hard: 0(s)      soft: 0(s)
        current: 62(bytes)      hard: 0(bytes)  soft: 0(bytes)
        allocated: 1    hard: 0 soft: 0
        sadb_seq=0 pid=3175 refcnt=1
dell# 
dell# sendMessagesSync: never got /usr/local/sbin/setkey -c <<EODdump;flush;EOD
echo $status
0
dell# kill -TERM `head -1 /var/run/racoon.pid`

dell# 
echo $status
dell# echo $status
0
dell# /bin/rm -f /var/run/racoon.pid

dell# 
dell# echo $status
0~
[EOT]

Target: Clear SPD entries: spddump
17:12:06 vRemote(ipsecResetSPD.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ipsecResetSPD.rmt -t freebsd-i386 -u root -p v6eval -d ucom0 -o 1 spddump ''
Connected

dell# 
dell# /usr/local/sbin/setkey -c <<EOD
spddump;
spdflush;
? spddump;
? spdflush;
EOD

? EOD
3ffe:501:ffff:101::11[any] 3ffe:501:ffff:100:290:99ff:fe7e:3e52[any] any
        in ipsec
        esp/transport/3ffe:501:ffff:101::11-3ffe:501:ffff:100:290:99ff:fe7e:3e52/require
        created: Mar 16 08:17:43 2006  lastused: Mar 16 08:19:04 2006
        lifetime: 0(s) validtime: 0(s)
        spid=19045 seq=1 pid=3178
        refcnt=1
3ffe:501:ffff:100:290:99ff:fe7e:3e52[any] 3ffe:501:ffff:101::11[any] any
        out ipsec
        esp/transport/3ffe:501:ffff:100:290:99ff:fe7e:3e52-3ffe:501:ffff:101::11/require
        created: Mar 16 08:17:36 2006  lastused: Mar 16 08:19:04 2006
        lifetime: 0(s) validtime: 0(s)
        spid=19044 seq=0 pid=3178
        refcnt=1

dell# 
dell# sendMessagesSync: never got /usr/local/sbin/setkey -c <<EODspddump;spdflush;EOD
echo $status
0~
[EOT]
17:12:14End

Packet Reverse Log