Test Information

TitleAccept both old and new SA for incoming traffic **
CommandLine./ENODE/R_RFC2408_5_3_2_2.seq -pkt ./ENODE/R_RFC2408_5_3_2_2.def test_phase=2 test_type=BASIC -log 249.html -ti Accept both old and new SA for incoming traffic **
TestVersionundefined
ToolVersionREL_3_0_8
Start2006/03/16 17:12:15
Tn/usr/local/v6eval//etc//tn.def
Nu/usr/local/v6eval//etc//nut.def
Pkt./ENODE/R_RFC2408_5_3_2_2.def
Systemfreebsd-i386
TargetNamefreebsd5.4
HostNameracoon
Typehost

Test Sequence Execution Log

17:12:15Start

*** Target IKE initialization phase ***
Target: Reset IKE SA entries: saddump
17:12:16 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
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:12:24 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;

EOD

? 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:12:31 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:19:46 2006  lastused: Mar 16 08:19:46 2006
        lifetime: 0(s) validtime: 0(s)
        spid=19058 seq=0 pid=3183
        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:12:39 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:19:54 2006  lastused: Mar 16 08:19:54 2006
        lifetime: 0(s) validtime: 0(s)
        spid=19059 seq=1 pid=3186
        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:19:46 2006  lastused: Mar 16 08:19:46 2006
        lifetime: 0(s) validtime: 0(s)
        spid=19058 seq=0 pid=3186
        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:12:46 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# 
echo $status
dell# echo $status
0~
[EOT]
17:12:59 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:12:59Start Capturing Packets (Link0)
17:12:59 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:13:14Clear Captured Packets (Link0)
17:13:14 vSend(Link0,isakmp_phase1_send_1st)
Send 1st message from HOST2(TN)

*** Phase-1 2nd message recieve ***
17:13:14 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:13:14Clear Captured Packets (Link0)
17:13:14 vSend(Link0,isakmp_phase1_send_3rd)
Send 3rd message from HOST2(TN)

*** Phase-1 4th message recieve ***
17:13:14 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:13:15Clear Captured Packets (Link0)
17:13:15 vSend(Link0,isakmp_phase1_send_5th)
Send 5th message from HOST2(TN)

*** Phase-1 6th message recieve ***
17:13:15 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:13:15Clear Captured Packets (Link0)
17:13:15 vSend(Link0,isakmp_phase2_send)
Send Phase-2 1st message (HDR*, HASH(1), SA, Ni) from HOST2(TN)

*** Phase-2 2nd message recv ***
17:13:15 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:13:15Clear Captured Packets (Link0)
17:13:15 vSend(Link0,isakmp_phase2_send_3rd)
Send Phase-2 3rd message HDR*, HASH(3) from HOST2(TN)
17:13:15 Wait 1 second

*** IPsec SA is esatblished ***
*** Wait 42 to expire IPsec SA Lifetime ***
17:13:16 Wait 42 second

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

*** Re-Key 2nd message recv ***
17:13:58 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
Receive Neighbor Solicitation from HOST-1(NUT)
17:13:59 vSend(Link0,na_llt)
Send Neighbor Advertisement(TN)
17:13:59 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:13:59Clear Captured Packets (Link0)
17:13:59 vSend(Link0,isakmp_phase2_send_3rd)
Send Phase-2 3rd message HDR*, HASH(3) from HOST2(TN)
17:13:59 Wait 1 second

*** Re-Key IPsec SA is esatblished ***
*** Encrypted Echo Request message send using 1st IPsec SA ***
## 1st SA elapsed time: 44 ##
## 2nd SA elapsed time: 0 ##
17:14:00Clear Captured Packets (Link0)
17:14:01 vSend(Link0,echo_request_send_esp_trans_net1host2_net0host1)
Send Encrypted Echo Request from HOST-2(TN)
17:14:01 vRecv(Link0,echo_reply_recv_esp_trans_net0host1_net1host2 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(222) over remain size(22)
recv unexpect packet at 17:14:01
vRecv() return status=1

Target: Reset IKE SA entries: saddump
17:14:06 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  b47b7848 1b8cd13d f3b54cbe 6704bb33 0f7c5d6b d9e44e30
        A: hmac-sha1  f6371624 2b809d9d 03641330 a902d07e 9fc6bcc3
        seq=0x00000000 replay=4 flags=0x00000000 state=mature 
        created: Mar 16 08:21:14 2006   current: Mar 16 08:21:21 2006
        diff: 7(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=3 pid=3196 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  f6c5eb81 d5b4604c 382c60eb 3aa52888 29d06a27 14a20203
        A: hmac-sha1  196867a2 9ed4ca26 b9121f62 c4451471 7a5736e3
        seq=0x00000001 replay=4 flags=0x00000000 state=dying 
        created: Mar 16 08:20:30 2006   current: Mar 16 08:21:21 2006
        diff: 51(s)     hard: 60(s)     soft: 48(s)
        last: Mar 16 08:21:15 2006      hard: 0(s)      soft: 0(s)
        current: 92(bytes)      hard: 0(bytes)  soft: 0(bytes)
        allocated: 1    hard: 0 soft: 0
        sadb_seq=2 pid=3196 refcnt=2
3ffe:501:ffff:101::11 3ffe:501:ffff:100:290:99ff:fe7e:3e52 
        esp mode=transport spi=240157144(0x0e5081d8) reqid=0(0x00000000)
        E: 3des-cbc  2ca14a4f 0268e238 df5628c0 20a87685 3eb7fd28 0709ccc5
        A: hmac-sha1  9df400bc 6efa95bc e716f580 edf7df51 6f8b3f5b
        seq=0x00000000 replay=4 flags=0x00000000 state=mature 
        created: Mar 16 08:21:14 2006   current: Mar 16 08:21:22 2006
        diff: 8(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=1 pid=3196 refcnt=1
3ffe:501:ffff:101::11 3ffe:501:ffff:100:290:99ff:fe7e:3e52 
        esp mode=transport spi=62193631(0x03b4ffdf) reqid=0(0x00000000)
        E: 3des-cbc  b19f7196 042f3fed 5c3116e9 449857f6 f39091d4 5d77de0c
        A: hmac-sha1  4a7d2c7d 9bd43286 19c69d73 ab3b8e7a f5000da4
        seq=0x00000001 replay=4 flags=0x00000000 state=dying 
        created: Mar 16 08:20:30 2006   current: Mar 16 08:21:22 2006
        diff: 52(s)     hard: 60(s)     soft: 48(s)
        last: Mar 16 08:21:15 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=3196 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# 
echo $status
dell# echo $status
0~
[EOT]

Target: Clear SPD entries: spddump
17:14:14 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:19:54 2006  lastused: Mar 16 08:21:15 2006
        lifetime: 0(s) validtime: 0(s)
        spid=19059 seq=1 pid=3199
        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:19:46 2006  lastused: Mar 16 08:21:15 2006
        lifetime: 0(s) validtime: 0(s)
        spid=19058 seq=0 pid=3199
        refcnt=1

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

Packet Reverse Log