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 106.html -ti Re-keying of IPsec SA
TestVersionundefined
ToolVersionREL_3_0_8
Start2006/03/13 14:46:24
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:46:24Start

*** Target IKE initialization phase ***
Target: Reset IKE SA entries: saddump
14:46:25 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
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:46:32 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;
spdflus? spddump;
h;
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:46:38 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 13 14:53:16 2006  lastused: Mar 13 14:53:16 2006
        lifetime: 0(s) validtime: 0(s)
        spid=17494 seq=0 pid=1549
        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:46:44 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:46:58 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:46:59Start Capturing Packets (Link0)
14:46:59Start Capturing Packets (Link1)

*** Echo Request message send ***
14:46:59Clear Captured Packets (Link0)
14:46:59Clear Captured Packets (Link1)
14:46:59 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:46:59Clear Captured Packets (Link0)

*** Phase-1 1st message recv ***
14:47:00 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:47:04 vSend(Link0,router_na)
Send Neighbor Advertisement(TN)
14:47:05 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:47:09Clear Captured Packets (Link0)
14:47:10 vSend(Link0,isakmp_phase1_send_2nd)
Send 2nd message from HOST2(TN)

*** Phase-1 3rd message recv ***
14:47:10 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:47:10Clear Captured Packets (Link0)
14:47:11 vSend(Link0,isakmp_phase1_send_4th)
Send 4th message from HOST2(TN)

*** Phase-1 5th message recv ***
14:47:11 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:47:11Clear Captured Packets (Link0)
14:47:12 vSend(Link0,isakmp_phase1_send_6th)
Send 6th message from HOST2(TN)

*** Target testing phase start ***
*** Phase-2 1st message recv ***
14:47:12 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:47:12
Recv Phase-2 1st message (HDR*, HASH(1), SA, Ni, *, *) from HOST1(NUT)

OK payload_check
*** Phase-2 2nd message send ***
14:47:12Clear Captured Packets (Link0)
14:47:12 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:47:13 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:47:13Clear Captured Packets (Link0)
14:47:13Clear Captured Packets (Link1)
14:47:13 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:47:17 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)
14:47:17 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:47:17 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:47:18 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:47:18 Wait 3 second

*** Echo Request message send ***
14:47:21Clear Captured Packets (Link1)
14:47:21 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:47:21 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:47:22 Wait 3 second

*** Echo Request message send ***
14:47:25Clear Captured Packets (Link1)
14:47:25 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: 12 ##
14:47:25 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:47:25 Wait 3 second

*** Echo Request message send ***
14:47:28Clear Captured Packets (Link1)
14:47:28 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:47:29 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:47:29 Wait 3 second

*** Echo Request message send ***
14:47:32Clear Captured Packets (Link1)
14:47: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: 19 ##
14:47: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
Received Encapsulated Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)

*** Encrypted Echo Request message recv 6 ***
14:47:32 Wait 3 second

*** Echo Request message send ***
14:47:35Clear Captured Packets (Link1)
14:47: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: 23 ##
14:47: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
Receive Neighbor Solicitation from SGW1(NUT)
14:47:36 vSend(Link0,router_na)
Send Neighbor Advertisement(TN)
14:47: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
Receive Neighbor Solicitation from SGW1(NUT)
14:47:37 vSend(Link0,router_na)
Send Neighbor Advertisement(TN)
14:47:37 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:47:37 vSend(Link0,router_na)
Send Neighbor Advertisement(TN)
14:47:37 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:47:37 Wait 3 second

*** Echo Request message send ***
14:47:40Clear Captured Packets (Link1)
14:47:40 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: 28 ##
14:47:41 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:47:41 Wait 3 second

*** Echo Request message send ***
14:47:44Clear Captured Packets (Link1)
14:47:44 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:47:44 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:47:45 Wait 3 second

*** Echo Request message send ***
14:47:48Clear Captured Packets (Link1)
14:47:48 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: 35 ##
14:47:48 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:47:48 Wait 3 second

*** Echo Request message send ***
14:47:51Clear Captured Packets (Link1)
14:47:51 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: 39 ##
14:47:52 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:47:52 Wait 3 second

*** Echo Request message send ***
14:47:55Clear Captured Packets (Link1)
14:47:55 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:47:55 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:47:56 Wait 3 second

*** Echo Request message send ***
14:47:59Clear Captured Packets (Link1)
14:47:59 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: 46 ##
14:47:59 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:47:59 Wait 3 second

*** Echo Request message send ***
14:48:02Clear Captured Packets (Link1)
14:48:02 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: 50 ##
14:48:03 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(46774) 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:48:03Clear Captured Packets (Link0)
14:48:03 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:48:04 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:48:04Clear Captured Packets (Link0)
14:48:04Clear Captured Packets (Link1)
14:48:04 vSend(Link1,echo_request_send_net0host1_net4host2)
Send Echo Request from Host-1(TN) to Host-2(TN) via SGW1(NUT)
14:48:05 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: 52 ##
## 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:48:05Stop Capturing Packets (Link0)
14:48:05Stop Capturing Packets (Link1)

Target: Reset IKE SA entries: saddump
14:48:05 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  f4c1dd47 21801586 6fd3506e ab99aca2 474a1f0d 6d790cf0
        A: hmac-sha1  efee7da6 0d359919 7101ce26 f812f579 50288a78
        seq=0x00000000 replay=4 flags=0x00000000 state=mature 
        created: Mar 13 14:54:40 2006   current: Mar 13 14:54:43 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=1558 refcnt=1
3ffe:501:ffff:102::1 3ffe:501:ffff:103::11 
        esp mode=tunnel spi=4096(0x00001000) reqid=0(0x00000000)
        E: 3des-cbc  0c0a5489 fa2dd3cf 79e23265 a7f86657 09e08b74 2159d4a2
        A: hmac-sha1  b5a924d7 e4aa1563 a27fddff 8573eeff 4315f1ed
        seq=0x0000000f replay=4 flags=0x00000000 state=dying 
        created: Mar 13 14:53:49 2006   current: Mar 13 14:54:43 2006
        diff: 54(s)     hard: 60(s)     soft: 48(s)
        last: Mar 13 14:54:41 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=1558 refcnt=2
3ffe:501:ffff:103::11 3ffe:501:ffff:102::1 
        esp mode=tunnel spi=32030064(0x01e8bd70) reqid=0(0x00000000)
        E: 3des-cbc  36ae4807 7abffe0e 2c88bcf0 bdc755c7 18e57cdd 86b42446
        A: hmac-sha1  3ebb62a6 cd14f054 867ba83c f132f100 f3da22ba
        seq=0x00000000 replay=4 flags=0x00000000 state=mature 
        created: Mar 13 14:54:40 2006   current: Mar 13 14:54:44 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=1558 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:48:12 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;
spdfl? spddump;
ush;
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 13 14:53:16 2006  lastused: Mar 13 14:54:41 2006
        lifetime: 0(s) validtime: 0(s)
        spid=17494 seq=0 pid=1561
        refcnt=1
target1# 
target1# sendMessagesSync: never got /usr/sbin/setkey -c <<EODspddump;spdflush;EOD
echo $status
0
~
[EOT]

OK
14:48:18End

Packet Reverse Log