Test Information

TitleRe-keying of IPsec SA
CommandLine./SGW/SG_I_RFC2407_4_5.seq -pkt ./SGW/SG_I_RFC2407_4_5.def test_phase=2 test_type=BASIC -log 107.html -ti Re-keying of IPsec SA
TestVersionundefined
ToolVersionREL_3_0_8
Start2006/03/16 14:37:19
Tn/usr/local/v6eval//etc//tn.def
Nu/usr/local/v6eval//etc//nut.def
Pkt./SGW/SG_I_RFC2407_4_5.def
Systemfreebsd-i386
TargetNameFreeBSD 5.4-RELEASE
HostNametarget1.tahi.org
Typerouter

Test Sequence Execution Log

14:37:20Start

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

target1# 
target1# /usr/sbin/setkey -c <<EOD
dump;
flush;
E? dump;
? flush;
OD

? EOD
The result of line 1: No SAD entries.

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

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

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

Target: Clear SPD entries: spddump
14:37:26 vRemote(ipsecResetSPD.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ipsecResetSPD.rmt -t freebsd-i386 -u root -p v6eval -d cuad0 -o 1 spddump ''
Connected

target1# 
target1# /usr/sbin/setkey -c <<EOD
spddump;
spdflu? spddump;
sh;
EOD

? spdflush;
EOD

? EOD
The result of line 1: No SPD entries.
target1# 
target1# sendMessagesSync: never got /usr/sbin/setkey -c <<EODspddump;spdflush;EOD
echo $status
0
~
[EOT]

Target: Set SPD entries: src=3ffe:501:ffff:100::/64 dst=3ffe:501:ffff:104::/64 tsrc=3ffe:501:ffff:102::1 tdst=3ffe:501:ffff:103::11 upperspec=any direction=out protocol=PROTO_IPSEC_ESP mode=Tunnel
14:37:32 vRemote(ipsecSetSPD.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ipsecSetSPD.rmt -t freebsd-i386 -u root -p v6eval -d cuad0 -o 1 src=3ffe:501:ffff:100::/64 dst=3ffe:501:ffff:104::/64 tsrc=3ffe:501:ffff:102::1 tdst=3ffe:501:ffff:103::11 upperspec=any direction=out protocol=PROTO_IPSEC_ESP mode=Tunnel ''
Connected

target1# 
target1# /usr/sbin/setkey -c <<EOD
spdadd 3ffe:501:ffff:100::/64 3ffe:501:ffff:104::/64
       any
       -P out ipsec
       esp/tunnel/3ffe:501:ffff:102::1-3ffe:501:ffff:103::11/require
;
spddump;
EOD

? spdadd 3ffe:501:ffff:100::/64 3ffe:501:ffff:104::/64
       any
       -P out ipsec
       esp/tunnel/3ffe:501:ffff:102::1-3ffe:501:ffff:103::11/require
;
spddump;
EOD

?        any
?        -P out ipsec
?        esp/tunnel/3ffe:501:ffff:102::1-3ffe:501:ffff:103::11/require
? ;
? spddump;
? EOD
3ffe:501:ffff:100::/64[any] 3ffe:501:ffff:104::/64[any] any
        out ipsec
        esp/tunnel/3ffe:501:ffff:102::1-3ffe:501:ffff:103::11/require
        created: Mar 16 14:44:42 2006  lastused: Mar 16 14:44:42 2006
        lifetime: 0(s) validtime: 0(s)
        spid=17513 seq=0 pid=1566
        refcnt=1
target1# 
target1# sendMessagesSync: never got /usr/sbin/setkey -c <<EODspdadd 3ffe:501:ffff:100::/64 3ffe:501:ffff:104::/64       any       -P out ipsec       esp/tunnel/3ffe:501:ffff:102::1-3ffe:501:ffff:103::11/require;spddump;EOD
echo $status
0
~
[EOT]

Target: Set IKE SA entries: dst=3ffe:501:ffff:103::11 dst_port=500 exchange_mode=main doi=ipsec_doi situation=identity_only isakmp_src_id_type=address isakmp_src_id=3ffe:501:ffff:102::1 dh_group=2 lifetime=28800 lifetime_unit=seconds encryption_algorithm=3des hash_algorithm=sha1 authentication_method=pre_shared_key key_id=3ffe:501:ffff:103::11 key_value=0x494b452d54455354 ph2_id_type=address ph2_src_id=3ffe:501:ffff:100::/64 ph2_dst_id=3ffe:501:ffff:104::/64 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
14:37:39 vRemote(ikeSetSA.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ikeSetSA.rmt -t freebsd-i386 -u root -p v6eval -d cuad0 -o 1 dst=3ffe:501:ffff:103::11 dst_port=500 exchange_mode=main doi=ipsec_doi situation=identity_only isakmp_src_id_type=address isakmp_src_id=3ffe:501:ffff:102::1 dh_group=2 lifetime=28800 lifetime_unit=seconds encryption_algorithm=3des hash_algorithm=sha1 authentication_method=pre_shared_key key_id=3ffe:501:ffff:103::11 key_value=0x494b452d54455354 ph2_id_type=address ph2_src_id=3ffe:501:ffff:100::/64 ph2_dst_id=3ffe:501:ffff:104::/64 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

target1# 
target1# ~[set] echocheck

target1# 
target1# ~[put] freebsd-i386.psk.txt /tmp/psk.txt
Dtarget1# 
target1# 
target1# /bin/chmod 600 /tmp/psk.txt
target1# echo $status
0
target1# ~[set] echocheck

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

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

target1# 
target1# echo $status
0
~
[EOT]
14:37:54 vRemote(ikeEnable.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ikeEnable.rmt -t freebsd-i386 -u root -p v6eval -d cuad0 -o 1 ''


*** Target initialization phase ***
14:37:55Start Capturing Packets (Link0)
14:37:55Start Capturing Packets (Link1)

*** Echo Request message send ***
14:37:55Clear Captured Packets (Link0)
14:37:55Clear Captured Packets (Link1)
14:37:55 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Target pre-test seaquence ***
14:37:55Clear Captured Packets (Link0)

*** Phase-1 1st message recv ***
14:37:55 vRecv(Link0,isakmp_phase1_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:10 cntLimit:0 seektime:0
Receive Neighbor Solicitation from SGW1(NUT)
14:38:01 vSend(Link0,router_na)
Send Neighbor Advertisement(TN)
14:38:01 vRecv(Link0,isakmp_phase1_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:10 cntLimit:0 seektime:0
Recv 1st message from HOST1(NUT)

OK payload_check
*** Phase-1 2nd message send ***
14:38:05Clear Captured Packets (Link0)
14:38:05 vSend(Link0,isakmp_phase1_send_2nd)
Send 2nd message from HOST2(TN)

*** Phase-1 3rd message recv ***
14:38:06 vRecv(Link0,isakmp_phase1_recv_3rd router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:0 seektime:0
Recv 3rd message from HOST1(NUT)

OK payload_check
*** Phase-1 4th message send ***
14:38:06Clear Captured Packets (Link0)
14:38:07 vSend(Link0,isakmp_phase1_send_4th)
Send 4th message from HOST2(TN)

*** Phase-1 5th message recv ***
14:38:07 vRecv(Link0,isakmp_phase1_recv_5th router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:0 seektime:0
Recv 5th message from HOST1(NUT)

OK payload_check
*** Phase-1 6th message send ***
14:38:07Clear Captured Packets (Link0)
14:38:07 vSend(Link0,isakmp_phase1_send_6th)
Send 6th message from HOST2(TN)

*** Target testing phase start ***
*** Phase-2 1st message recv ***
14:38:08 vRecv(Link0,isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:0 seektime:0
recv unexpect packet at 14:38:07
Recv Phase-2 1st message (HDR*, HASH(1), SA, Ni, *, *) from HOST1(NUT)

OK payload_check
*** Phase-2 2nd message send ***
14:38:08Clear Captured Packets (Link0)
14:38:08 vSend(Link0,isakmp_phase2_send_2nd)
Send Phase-2 2nd message (HDR*, HASH(2), SA, Nr) from HOST2(TN)

*** Phase-2 3rd message recv ***
14:38:09 vRecv(Link0,isakmp_phase2_recv_3rd router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:0 seektime:0
Recv Phase-2 3rd message HDR*, HASH(3) from HOST1(NUT)

OK payload_check
*** 1st IPsec SA is esatblished ***
14:38:09Clear Captured Packets (Link0)
14:38:09Clear Captured Packets (Link1)
14:38:09 vRecv(Link0,echo_request_send_net0host1_net4host2 router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:3 cntLimit:0 seektime:0
vRecv() return status=1

NG:Receive no packets
*** Echo Request message send ***
14:38:12 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)
14:38:13 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 1 ***
*** Re-key testing phase start ***
*** Echo Request message send ***
14:38:13 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 4 ##
14:38:13 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 2 ***
14:38:13 Wait 3 second

*** Echo Request message send ***
14:38:16Clear Captured Packets (Link1)
14:38:16 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 8 ##
14:38:17 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 3 ***
14:38:17 Wait 3 second

*** Echo Request message send ***
14:38:20Clear Captured Packets (Link1)
14:38:20 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 11 ##
14:38:20 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 4 ***
14:38:21 Wait 3 second

*** Echo Request message send ***
14:38:24Clear Captured Packets (Link1)
14:38:24 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 15 ##
14:38:24 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 5 ***
14:38:24 Wait 3 second

*** Echo Request message send ***
14:38:27Clear Captured Packets (Link1)
14:38:27 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 18 ##
14:38:28 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Receive Neighbor Solicitation from SGW1(NUT)
14:38:28 vSend(Link0,router_na)
Send Neighbor Advertisement(TN)
14:38:28 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Receive Neighbor Solicitation from SGW1(NUT)
14:38:28 vSend(Link0,router_na)
Send Neighbor Advertisement(TN)
14:38:28 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 6 ***
14:38:29 Wait 3 second

*** Echo Request message send ***
14:38:32Clear Captured Packets (Link1)
14:38:32 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 23 ##
14:38:32 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Receive Neighbor Solicitation from SGW1(NUT)
14:38:32 vSend(Link0,router_na)
Send Neighbor Advertisement(TN)
14:38:33 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 7 ***
14:38:33 Wait 3 second

*** Echo Request message send ***
14:38:36Clear Captured Packets (Link1)
14:38:36 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 27 ##
14:38:36 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 8 ***
14:38:36 Wait 3 second

*** Echo Request message send ***
14:38:39Clear Captured Packets (Link1)
14:38:39 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 31 ##
14:38:40 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 9 ***
14:38:40 Wait 3 second

*** Echo Request message send ***
14:38:43Clear Captured Packets (Link1)
14:38:43 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 34 ##
14:38:43 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 10 ***
14:38:44 Wait 3 second

*** Echo Request message send ***
14:38:47Clear Captured Packets (Link1)
14:38:47 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 38 ##
14:38:47 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 11 ***
14:38:47 Wait 3 second

*** Echo Request message send ***
14:38:50Clear Captured Packets (Link1)
14:38:50 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 42 ##
14:38:51 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 12 ***
14:38:51 Wait 3 second

*** Echo Request message send ***
14:38:54Clear Captured Packets (Link1)
14:38:54 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 45 ##
14:38:54 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 13 ***
14:38:55 Wait 3 second

*** Echo Request message send ***
14:38:58Clear Captured Packets (Link1)
14:38:58 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

## 1st SA elapsed time: 49 ##
14:38:58 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 isakmp_phase2_recv router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:1 seektime:0
!!! ISAKMP PayloadLength decode(11441) over remain size(168)
Recv Phase-2 1st message (HDR*, HASH(1), SA, Ni, *, *) from HOST1(NUT)

OK payload_check
*** Re-Key 1st message recv ***
*** Re-Key 2nd message send ***
14:38:58Clear Captured Packets (Link0)
14:38:58 vSend(Link0,isakmp_phase2_send_2nd)
Send Phase-2 2nd message (HDR*, HASH(2), SA, Nr) from HOST2(TN)

*** Re-Key 3rd message recv ***
14:38:59 vRecv(Link0,isakmp_phase2_recv_3rd router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:0 seektime:0
Recv Phase-2 3rd message HDR*, HASH(3) from HOST1(NUT)

OK payload_check
*** 2nd IPsec SA is esatblished ***
*** Encrypted Echo Request message recv using 1st or 2nd IPsec SA ***
*** Echo Request message send ***
14:38:59Clear Captured Packets (Link0)
14:38:59Clear Captured Packets (Link1)
14:38:59 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)
14:39:00 vRecv(Link0,echo_request_recv_esp_tunnel_net2sgw1_net3sgw2 echo_request_recv_esp_tunnel_net2sgw1_net3sgw2_2 router_ns_multi router_ns_uni_link1 router_ns_uni_tll_sll_link1 router_ns_multi_llt_link1 router_ns_uni_sll router_ns_uni router_ns_multi_llt router_ns_uni_sll_link1 router_ns_multi_link1 router_ns_uni_tll_sll) timeout:5 cntLimit:2 seektime:0
Receive Encrypted Echo Request from HOST-1(TN)

## 1st SA elapsed time: 51 ##
## 2nd SA elapsed time: 1 ##
*** Encrypted Echo Request message recv using 1st IPsec SA ***
Re-keying of IPsec SA is correct
*** Target test finish ***
14:39:00Stop Capturing Packets (Link0)
14:39:00Stop Capturing Packets (Link1)

Target: Reset IKE SA entries: saddump
14:39:00 vRemote(ikeResetSA.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ikeResetSA.rmt -t freebsd-i386 -u root -p v6eval -d cuad0 -o 1 saddump ''
Connected

target1# 
target1# /usr/sbin/setkey -c <<EOD
dump;
flush;
EOD? dump;
? flush;

? EOD

3ffe:501:ffff:102::1 3ffe:501:ffff:103::11 
        esp mode=tunnel spi=4097(0x00001001) reqid=0(0x00000000)
        E: 3des-cbc  d3e41205 e20b4f75 f0111590 47b1eda4 c48d70a3 9fbe6f2d
        A: hmac-sha1  31e1e76f 47f4d135 5c7d3fe8 b23c93f5 d0525749
        seq=0x00000000 replay=4 flags=0x00000000 state=mature 
        created: Mar 16 14:46:07 2006   current: Mar 16 14:46:10 2006
        diff: 3(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=1576 refcnt=1
3ffe:501:ffff:102::1 3ffe:501:ffff:103::11 
        esp mode=tunnel spi=4096(0x00001000) reqid=0(0x00000000)
        E: 3des-cbc  5b7ccc57 b6f34139 b75297b1 7812ca98 4e1578c3 0a5974af
        A: hmac-sha1  08088033 4f337000 90a39fc3 2098a3fc c77ddbd7
        seq=0x0000000f replay=4 flags=0x00000000 state=dying 
        created: Mar 16 14:45:17 2006   current: Mar 16 14:46:10 2006
        diff: 53(s)     hard: 60(s)     soft: 48(s)
        last: Mar 16 14:46:08 2006      hard: 0(s)      soft: 0(s)
        current: 1980(bytes)    hard: 0(bytes)  soft: 0(bytes)
        allocated: 15   hard: 0 soft: 0
        sadb_seq=1 pid=1576 refcnt=2
3ffe:501:ffff:103::11 3ffe:501:ffff:102::1 
        esp mode=tunnel spi=187790639(0x0b31752f) reqid=0(0x00000000)
        E: 3des-cbc  d37a9d1c 49ad91ac 84a9d30e f8b285e1 d2b41815 4cde7696
        A: hmac-sha1  a008198d eb6c21e1 2544118f ebab2315 6537530a
        seq=0x00000000 replay=4 flags=0x00000000 state=mature 
        created: Mar 16 14:46:07 2006   current: Mar 16 14:46:11 2006
        diff: 4(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=0 pid=1576 refcnt=1
target1# 
target1# sendMessagesSync: never got /usr/sbin/setkey -c <<EODdump;flush;EOD
echo $status
0
target1# kill -TERM `head -1 /var/run/racoon.pid`

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

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

Target: Clear SPD entries: spddump
14:39:07 vRemote(ipsecResetSPD.rmt) ``/usr/local/v6eval//bin/freebsd-i386//ipsecResetSPD.rmt -t freebsd-i386 -u root -p v6eval -d cuad0 -o 1 spddump ''
Connected

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

? spdflush;
EOD

? EOD
3ffe:501:ffff:100::/64[any] 3ffe:501:ffff:104::/64[any] any
        out ipsec
        esp/tunnel/3ffe:501:ffff:102::1-3ffe:501:ffff:103::11/require
        created: Mar 16 14:44:42 2006  lastused: Mar 16 14:46:08 2006
        lifetime: 0(s) validtime: 0(s)
        spid=17513 seq=0 pid=1579
        refcnt=1
target1# 
target1# sendMessagesSync: never got /usr/sbin/setkey -c <<EODspddump;spdflush;EOD
echo $status
0
~
[EOT]

OK
14:39:13End

Packet Reverse Log