(racoon 892) "DOI value of CONNECTED Notify Message has problem"
"Haoda" <[email protected]> Fri, 7 Jan 2005 21:54:54 +0900
| Newsgroups | gmane.network.ipv6.kame.racoon |
|---|---|
| Message-ID | <[email protected]> |
Hi, I'm Li Hao, a member of TAHI project. I made a test of IKE using the TAHI conformance test tool. I found a strange case of CONNECTED Notify Message. *****Test enviroment****************************************************************** *** FreeBSD version: 5.3-RELEASE racoon version: freebsd-20040818a ******The config file & Log file of racoon ********************************************** I attached 2 file after this message, they are, /tmp/ike.conf /var/log/racoon.log ******The test sequence****************************************************************** * PHASE II QUICK MODE # Initiator(TN) Direction Responder(NUT) (1) HDR*, HASH(1), SA, Ni ========> <--- Commit Bit = 1 (2) <======== HDR*, HASH(2), SA, Nr (3) HDR*, HASH(3) ========> <--- Commit Bit = 1 (4) <======== HDR*, HASH(1),N/D (5) HDR*; HASH(1), N/D ========> <--- Commit Bit = 1 The "TN" means tester node, and the "NUT" means FreeBSD. The "N/D" means Notify payload. And at step (4), One "CONNECTED" Notify Message will be received at the side of Initiator. But I found the recevicing result is strange. The value of "DOI" field is "16777216", but I think the right value is 1 according with RFC2408. The binary format of "16777216" is "1000000000000000000000000", just the reversed bit order of "0000000000000000000000001". I think maybe this is racoon's problem. Please give me a solution to solve it. Regards, Li Hao
ike.conf
(application/octet-stream, 566 B)
path pre_shared_key "/tmp/psk.txt";
remote 3ffe:501:ffff:101::11 [500]{
exchange_mode main;
doi ipsec_doi;
situation identity_only;
my_identifier address "3ffe:501:ffff:100:240:26ff:fed2:d2e9";
proposal{
encryption_algorithm 3des;
hash_algorithm sha1;
authentication_method pre_shared_key;
dh_group 2;
lifetime time 28800 seconds;
}
}
sainfo address 3ffe:501:ffff:100:240:26ff:fed2:d2e9 any address 3ffe:501:ffff:101::11 any{
lifetime time 8 hour;
encryption_algorithm 3des;
authentication_algorithm hmac_sha1;
compression_algorithm deflate;
}
racoon.log
(application/octet-stream, 49.8 KB)
2005-01-07 17:25:45: INFO: main.c:172:main(): @(#)package version freebsd-20040818a 2005-01-07 17:25:45: INFO: main.c:174:main(): @(#)internal version 20001216 [email protected] 2005-01-07 17:25:45: INFO: main.c:175:main(): @(#)This product linked OpenSSL 0.9.7d 17 Mar 2004 (http://www.openssl.org/) 2005-01-07 17:25:45: DEBUG: pfkey.c:434:pfkey_init(): call pfkey_send_register for AH 2005-01-07 17:25:45: DEBUG: pfkey.c:434:pfkey_init(): call pfkey_send_register for ESP 2005-01-07 17:25:45: DEBUG: pfkey.c:434:pfkey_init(): call pfkey_send_register for IPCOMP 2005-01-07 17:25:45: DEBUG: cftoken.l:578:yycf_set_buffer(): reading config file /tmp/ike.conf 2005-01-07 17:25:45: DEBUG: pfkey.c:2379:pk_checkalg(): compression algorithm can not be checked because sadb message doesn't support it. 2005-01-07 17:25:45: DEBUG: grabmyaddr.c:206:grab_myaddrs(): my interface: fe80::240:26ff:fed2:d2e9%aue0 (aue0) 2005-01-07 17:25:45: DEBUG: grabmyaddr.c:206:grab_myaddrs(): my interface: 3ffe:501:ffff:100:240:26ff:fed2:d2e9 (aue0) 2005-01-07 17:25:45: DEBUG: grabmyaddr.c:206:grab_myaddrs(): my interface: 127.0.0.1 (lo0) 2005-01-07 17:25:45: DEBUG: grabmyaddr.c:206:grab_myaddrs(): my interface: ::1 (lo0) 2005-01-07 17:25:45: DEBUG: grabmyaddr.c:206:grab_myaddrs(): my interface: fe80::1%lo0 (lo0) 2005-01-07 17:25:45: DEBUG: grabmyaddr.c:474:autoconf_myaddrsport(): configuring default isakmp port. 2005-01-07 17:25:45: DEBUG: grabmyaddr.c:496:autoconf_myaddrsport(): 5 addrs are configured successfully 2005-01-07 17:25:45: INFO: isakmp.c:1368:isakmp_open(): fe80::1%lo0[500] used as isakmp port (fd=5) 2005-01-07 17:25:45: INFO: isakmp.c:1368:isakmp_open(): ::1[500] used as isakmp port (fd=6) 2005-01-07 17:25:45: INFO: isakmp.c:1368:isakmp_open(): 127.0.0.1[500] used as isakmp port (fd=7) 2005-01-07 17:25:45: INFO: isakmp.c:1368:isakmp_open(): 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] used as isakmp port (fd=8) 2005-01-07 17:25:45: INFO: isakmp.c:1368:isakmp_open(): fe80::240:26ff:fed2:d2e9%aue0[500] used as isakmp port (fd=9) 2005-01-07 17:25:45: DEBUG: pfkey.c:197:pfkey_handler(): get pfkey X_SPDDUMP message 2005-01-07 17:25:45: DEBUG: pfkey.c:197:pfkey_handler(): get pfkey X_SPDDUMP message 2005-01-07 17:25:45: DEBUG: policy.c:184:cmpspidxstrict(): sub:0xbfbfea50: 3ffe:501:ffff:100:240:26ff:fed2:d2e9/128[0] 3ffe:501:ffff:101::11/128[0] proto=any dir=out 2005-01-07 17:25:45: DEBUG: policy.c:185:cmpspidxstrict(): db :0x809dc08: 3ffe:501:ffff:101::11/128[0] 3ffe:501:ffff:100:240:26ff:fed2:d2e9/128[0] proto=any dir=in 2005-01-07 17:25:47: DEBUG: isakmp.c:233:isakmp_handler(): === 2005-01-07 17:25:47: DEBUG: isakmp.c:234:isakmp_handler(): 84 bytes message received from 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:47: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 00000000 00000000 01100200 00000000 00000054 00000038 00000001 00000001 0000002c 01010001 00000024 01010000 80010005 80020002 80030001 80040002 800b0001 000c0004 00007080 2005-01-07 17:25:47: DEBUG: remoteconf.c:118:getrmconf(): configuration found for 3ffe:501:ffff:101::11[500]. 2005-01-07 17:25:47: DEBUG: isakmp.c:899:isakmp_ph1begin_r(): === 2005-01-07 17:25:47: INFO: isakmp.c:904:isakmp_ph1begin_r(): respond new phase 1 negotiation: 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500]<=>3ffe:501:ffff:101::11[500] 2005-01-07 17:25:47: INFO: isakmp.c:909:isakmp_ph1begin_r(): begin Identity Protection mode. 2005-01-07 17:25:47: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:47: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=1(sa) 2005-01-07 17:25:47: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=52 2005-01-07 17:25:47: DEBUG: plog.c:193:plogdump(): 00000001 00000001 0000002c 01010001 00000024 01010000 80010005 80020002 80030001 80040002 800b0001 000c0004 00007080 2005-01-07 17:25:47: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:47: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=2(prop) 2005-01-07 17:25:47: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #1 len=44 2005-01-07 17:25:47: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:47: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=3(trns) 2005-01-07 17:25:47: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1311:get_transform(): transform #1 len=36 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2005-01-07 17:25:47: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2005-01-07 17:25:47: DEBUG: algorithm.c:256:alg_oakley_hashdef(): hash(sha1) 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2005-01-07 17:25:47: DEBUG: algorithm.c:614:alg_oakley_dhdef(): hmac(modp1024) 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Duration, flag=0x0000, lorv=4 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 1: 2005-01-07 17:25:47: DEBUG: proposal.c:895:print_proppair0(): 0x80a3d80: next=0x0 tnext=0x0 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #1: 1 transform 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:322:get_ph1approvalx(): prop#=1, prot-id=ISAKMP, spi-size=0, #trns=1 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:327:get_ph1approvalx(): trns#=1, trns-id=IKE 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Life Type, flag=0x8000, lorv=seconds 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Life Duration, flag=0x0000, lorv=4 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:338:get_ph1approvalx(): Compared: DB:Peer 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:339:get_ph1approvalx(): (lifetime = 28800:28800) 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:341:get_ph1approvalx(): (lifebyte = 0:0) 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:343:get_ph1approvalx(): enctype = 3DES-CBC:3DES-CBC 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:348:get_ph1approvalx(): (encklen = 0:0) 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:350:get_ph1approvalx(): hashtype = SHA:SHA 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:355:get_ph1approvalx(): authmethod = pre-shared key:pre-shared key 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:360:get_ph1approvalx(): dh_group = 1024-bit MODP group:1024-bit MODP group 2005-01-07 17:25:47: DEBUG: ipsec_doi.c:248:get_ph1approval(): an acceptable proposal found. 2005-01-07 17:25:47: DEBUG: algorithm.c:614:alg_oakley_dhdef(): hmac(modp1024) 2005-01-07 17:25:47: DEBUG: isakmp.c:2006:isakmp_newcookie(): new cookie: 56d1f16d0f2ccfc8 2005-01-07 17:25:47: DEBUG: isakmp.c:2130:set_isakmp_payload_c(): add payload of len 52, next type 1 2005-01-07 17:25:47: DEBUG: isakmp.c:2130:set_isakmp_payload_c(): add payload of len 16, next type 13 2005-01-07 17:25:47: DEBUG: sockmisc.c:421:sendfromto(): sockname 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:47: DEBUG: sockmisc.c:423:sendfromto(): send packet from 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:47: DEBUG: sockmisc.c:425:sendfromto(): send packet to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:47: DEBUG: sockmisc.c:481:sendfromto(): src6 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 0 2005-01-07 17:25:47: DEBUG: sockmisc.c:485:sendfromto(): dst6 3ffe:501:ffff:101::11[500] 0 2005-01-07 17:25:47: DEBUG: sockmisc.c:497:sendfromto(): 1 times of 104 bytes message will be sent to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:47: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 01100200 00000000 00000068 0d000038 00000001 00000001 0000002c 01010001 00000024 01010000 80010005 80020002 80030001 80040002 800b0001 000c0004 00007080 00000014 7003cbc1 097dbe9c 2600ba69 83bc8b35 2005-01-07 17:25:47: DEBUG: isakmp.c:1459:isakmp_ph1resend(): resend phase1 packet de690b1b87a67361:56d1f16d0f2ccfc8 2005-01-07 17:25:48: DEBUG: isakmp.c:233:isakmp_handler(): === 2005-01-07 17:25:48: DEBUG: isakmp.c:234:isakmp_handler(): 180 bytes message received from 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 04100200 00000000 000000b4 0a000084 d5886e40 41b63053 167e5b79 ac0b5bdc 7abe9e31 246a761c 2955ed73 6007246a 1e56b0f4 56b4f48c 9a79cf8f 339e79f0 0f6f1535 d78a5619 2aceef06 7fbd8e14 42e4fd6d 5358dcd0 7eb1975d 524b7d8e 003ac8b0 bb6ed57b 60c100df 36733495 1524225c 58ede145 ac6cf4b6 d2c8a159 abdf185a 509caa82 cc1030aa c3009232 00000014 00000000 00000000 00000000 00000000 2005-01-07 17:25:48: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:48: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=4(ke) 2005-01-07 17:25:48: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=10(nonce) 2005-01-07 17:25:48: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:48: DEBUG: isakmp.c:633:ph1_main(): === 2005-01-07 17:25:48: DEBUG: oakley.c:300:oakley_dh_generate(): compute DH's private. 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): 55bfe804 039a544d 8f826100 7f9c51d7 8ca81dbb 0de08f5d 369a093d 08d35276 91875849 706b3ae4 9b99e47a 1edf439d 1996e036 2784452d df6aed88 70c3b346 7bebf2e0 eca64044 68fe4e4e 601a3542 9e490ea1 50d0084f c85eac68 811c765f 3e3f35cd 6eb9738a 97be7099 1020a577 5c47ed3f bdb667e8 5036522a c92c936f 2005-01-07 17:25:48: DEBUG: oakley.c:302:oakley_dh_generate(): compute DH's public. 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): f5166e1b b23a6c9b 0032e507 a5601c67 37c7370d 4537ff06 bf741127 6cdafaef ba84769c 3e33d9fb 2e91f860 66d21c8d ea800669 9b09777d f91938f6 503e96c5 5e2387ea ff814d15 c65b345e fccf7ef4 36dec282 0231f8c6 1a06b50c cd73c02e 7252f314 51c16193 2306bde7 fa0fbe3f 3db093e0 4748ccff 23633065 51bb47cf 2005-01-07 17:25:48: DEBUG: isakmp.c:2130:set_isakmp_payload_c(): add payload of len 128, next type 4 2005-01-07 17:25:48: DEBUG: isakmp.c:2130:set_isakmp_payload_c(): add payload of len 16, next type 10 2005-01-07 17:25:48: DEBUG: isakmp.c:2130:set_isakmp_payload_c(): add payload of len 16, next type 13 2005-01-07 17:25:48: DEBUG: sockmisc.c:421:sendfromto(): sockname 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:48: DEBUG: sockmisc.c:423:sendfromto(): send packet from 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:48: DEBUG: sockmisc.c:425:sendfromto(): send packet to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:48: DEBUG: sockmisc.c:481:sendfromto(): src6 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 0 2005-01-07 17:25:48: DEBUG: sockmisc.c:485:sendfromto(): dst6 3ffe:501:ffff:101::11[500] 0 2005-01-07 17:25:48: DEBUG: sockmisc.c:497:sendfromto(): 1 times of 200 bytes message will be sent to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 04100200 00000000 000000c8 0a000084 f5166e1b b23a6c9b 0032e507 a5601c67 37c7370d 4537ff06 bf741127 6cdafaef ba84769c 3e33d9fb 2e91f860 66d21c8d ea800669 9b09777d f91938f6 503e96c5 5e2387ea ff814d15 c65b345e fccf7ef4 36dec282 0231f8c6 1a06b50c cd73c02e 7252f314 51c16193 2306bde7 fa0fbe3f 3db093e0 4748ccff 23633065 51bb47cf 0d000014 f30a4895 1704ba07 4e7e5edf 03a1808b 00000014 7003cbc1 097dbe9c 2600ba69 83bc8b35 2005-01-07 17:25:48: DEBUG: isakmp.c:1459:isakmp_ph1resend(): resend phase1 packet de690b1b87a67361:56d1f16d0f2ccfc8 2005-01-07 17:25:48: DEBUG: oakley.c:250:oakley_dh_compute(): compute DH's shared. 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): e75623f4 c244b202 16f591a4 139191a5 ce916eb4 4b80b4de 1f1ced2e 67efa7a1 89ebef17 2136816d a96ce28d 6fd7acad 6cf949d3 ecce061d e8376be9 ba406980 d5e19f03 d28689d0 5b4d6d1b e5c48b39 ecc7e0ff 14950a86 07e43267 8cfc7ff1 267b2a7b 3bffac71 108ec0ed 21493fee 46051443 c83fc452 aac14040 6e7e2b5c 2005-01-07 17:25:48: DEBUG: oakley.c:2104:oakley_skeyid(): the psk found. 2005-01-07 17:25:48: DEBUG: oakley.c:2119:oakley_skeyid(): nonce 1: 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): 00000000 00000000 00000000 00000000 2005-01-07 17:25:48: DEBUG: oakley.c:2125:oakley_skeyid(): nonce 2: 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): f30a4895 1704ba07 4e7e5edf 03a1808b 2005-01-07 17:25:48: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:48: DEBUG: oakley.c:2178:oakley_skeyid(): SKEYID computed: 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): a4616b26 1929e78c 2a6ee50b d07faf3e f16aab2c 2005-01-07 17:25:48: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:48: DEBUG: oakley.c:2235:oakley_skeyid_dae(): SKEYID_d computed: 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): 881f9b0c f87d1fb0 0bd37914 bb1a813d 9694282d 2005-01-07 17:25:48: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:48: DEBUG: oakley.c:2264:oakley_skeyid_dae(): SKEYID_a computed: 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): 3c3bdfcb 167716ba 59291048 4e326c0b fea5a01b 2005-01-07 17:25:48: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:48: DEBUG: oakley.c:2293:oakley_skeyid_dae(): SKEYID_e computed: 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): ea3c228d fc0674b9 faaec2a3 8f4f4476 5cbbc37a 2005-01-07 17:25:48: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:48: DEBUG: algorithm.c:256:alg_oakley_hashdef(): hash(sha1) 2005-01-07 17:25:48: DEBUG: oakley.c:2362:oakley_compute_enckey(): len(SKEYID_e) < len(Ka) (20 < 24), generating long key (Ka = K1 | K2 | ...) 2005-01-07 17:25:48: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:48: DEBUG: oakley.c:2387:oakley_compute_enckey(): compute intermediate encryption key K1 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): 00 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 2005-01-07 17:25:48: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:48: DEBUG: oakley.c:2387:oakley_compute_enckey(): compute intermediate encryption key K2 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): dad94517 b13fc5a6 aa235998 96e3a57d 08b4b815 2005-01-07 17:25:48: DEBUG: oakley.c:2435:oakley_compute_enckey(): final encryption key computed: 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 dad94517 2005-01-07 17:25:48: DEBUG: algorithm.c:256:alg_oakley_hashdef(): hash(sha1) 2005-01-07 17:25:48: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:48: DEBUG: oakley.c:2546:oakley_newiv(): IV computed: 2005-01-07 17:25:48: DEBUG: plog.c:193:plogdump(): 456f3162 5f774eec 2005-01-07 17:25:49: DEBUG: isakmp.c:233:isakmp_handler(): === 2005-01-07 17:25:49: DEBUG: isakmp.c:234:isakmp_handler(): 84 bytes message received from 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 05100201 00000000 00000054 57507dcf 64344a87 9ac0a5d0 cb51247e f28628ae cf90be2b e5cc8766 859c3ae4 af22ca68 059b2f40 e780208b 0b1f488a 0dbbd258 a7fa675c 2005-01-07 17:25:49: DEBUG: oakley.c:2666:oakley_do_decrypt(): begin decryption. 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2680:oakley_do_decrypt(): IV was saved for next processing: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 0dbbd258 a7fa675c 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2705:oakley_do_decrypt(): with key: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 dad94517 2005-01-07 17:25:49: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted payload by IV: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 0dbbd258 a7fa675c 2005-01-07 17:25:49: DEBUG: oakley.c:2716:oakley_do_decrypt(): decrypted payload, but not trimed. 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 08000018 051101f4 3ffe0501 ffff0101 00000000 00000011 00000018 e8b29edd a05e4192 118ae9ac 5d8d5294 f2d75b15 00000000 00000007 2005-01-07 17:25:49: DEBUG: oakley.c:2725:oakley_do_decrypt(): padding len=8 2005-01-07 17:25:49: DEBUG: oakley.c:2739:oakley_do_decrypt(): skip to trim padding. 2005-01-07 17:25:49: DEBUG: oakley.c:2754:oakley_do_decrypt(): decrypted. 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 05100201 00000000 00000054 08000018 051101f4 3ffe0501 ffff0101 00000000 00000011 00000018 e8b29edd a05e4192 118ae9ac 5d8d5294 f2d75b15 00000000 00000007 2005-01-07 17:25:49: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:49: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=5(id) 2005-01-07 17:25:49: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=8(hash) 2005-01-07 17:25:49: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:49: DEBUG: oakley.c:1206:oakley_validate_auth(): HASH received:2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): e8b29edd a05e4192 118ae9ac 5d8d5294 f2d75b15 2005-01-07 17:25:49: DEBUG: oakley.c:911:oakley_ph1hash_common(): HASH with: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): d5886e40 41b63053 167e5b79 ac0b5bdc 7abe9e31 246a761c 2955ed73 6007246a 1e56b0f4 56b4f48c 9a79cf8f 339e79f0 0f6f1535 d78a5619 2aceef06 7fbd8e14 42e4fd6d 5358dcd0 7eb1975d 524b7d8e 003ac8b0 bb6ed57b 60c100df 36733495 1524225c 58ede145 ac6cf4b6 d2c8a159 abdf185a 509caa82 cc1030aa c3009232 f5166e1b b23a6c9b 0032e507 a5601c67 37c7370d 4537ff06 bf741127 6cdafaef ba84769c 3e33d9fb 2e91f860 66d21c8d ea800669 9b09777d f91938f6 503e96c5 5e2387ea ff814d15 c65b345e fccf7ef4 36dec282 0231f8c6 1a06b50c cd73c02e 7252f314 51c16193 2306bde7 fa0fbe3f 3db093e0 4748ccff 23633065 51bb47cf de690b1b 87a67361 56d1f16d 0f2ccfc8 00000001 00000001 0000002c 01010001 00000024 01010000 80010005 80020002 80030001 80040002 800b0001 000c0004 00007080 051101f4 3ffe0501 ffff0101 00000000 00000011 2005-01-07 17:25:49: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:49: DEBUG: oakley.c:921:oakley_ph1hash_common(): HASH computed: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): e8b29edd a05e4192 118ae9ac 5d8d5294 f2d75b15 2005-01-07 17:25:49: DEBUG: oakley.c:1237:oakley_validate_auth(): HASH for PSK validated. 2005-01-07 17:25:49: DEBUG: isakmp_ident.c:1242:ident_r3recv(): peer's ID 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 051101f4 3ffe0501 ffff0101 00000000 00000011 2005-01-07 17:25:49: DEBUG: isakmp.c:633:ph1_main(): === 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:3238:ipsecdoi_setid1(): use ID type of IPv6_address 2005-01-07 17:25:49: DEBUG: isakmp_ident.c:1322:ident_r3send(): generate HASH_R 2005-01-07 17:25:49: DEBUG: oakley.c:911:oakley_ph1hash_common(): HASH with: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): f5166e1b b23a6c9b 0032e507 a5601c67 37c7370d 4537ff06 bf741127 6cdafaef ba84769c 3e33d9fb 2e91f860 66d21c8d ea800669 9b09777d f91938f6 503e96c5 5e2387ea ff814d15 c65b345e fccf7ef4 36dec282 0231f8c6 1a06b50c cd73c02e 7252f314 51c16193 2306bde7 fa0fbe3f 3db093e0 4748ccff 23633065 51bb47cf d5886e40 41b63053 167e5b79 ac0b5bdc 7abe9e31 246a761c 2955ed73 6007246a 1e56b0f4 56b4f48c 9a79cf8f 339e79f0 0f6f1535 d78a5619 2aceef06 7fbd8e14 42e4fd6d 5358dcd0 7eb1975d 524b7d8e 003ac8b0 bb6ed57b 60c100df 36733495 1524225c 58ede145 ac6cf4b6 d2c8a159 abdf185a 509caa82 cc1030aa c3009232 56d1f16d 0f2ccfc8 de690b1b 87a67361 00000001 00000001 0000002c 01010001 00000024 01010000 80010005 80020002 80030001 80040002 800b0001 000c0004 00007080 051101f4 3ffe0501 ffff0100 024026ff fed2d2e9 2005-01-07 17:25:49: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:49: DEBUG: oakley.c:921:oakley_ph1hash_common(): HASH computed: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): bb95f9b7 94153dd8 46f06496 3b7b5604 d9e1ac4c 2005-01-07 17:25:49: DEBUG: isakmp.c:2130:set_isakmp_payload_c(): add payload of len 20, next type 5 2005-01-07 17:25:49: DEBUG: isakmp.c:2130:set_isakmp_payload_c(): add payload of len 20, next type 8 2005-01-07 17:25:49: DEBUG: oakley.c:2789:oakley_do_encrypt(): begin encryption. 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2805:oakley_do_encrypt(): pad length = 8 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 08000018 051101f4 3ffe0501 ffff0100 024026ff fed2d2e9 00000018 bb95f9b7 94153dd8 46f06496 3b7b5604 d9e1ac4c aa2b94db e728ec07 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2840:oakley_do_encrypt(): with key: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 dad94517 2005-01-07 17:25:49: DEBUG: oakley.c:2848:oakley_do_encrypt(): encrypted payload by IV: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 243d3277 ac28553f 2005-01-07 17:25:49: DEBUG: oakley.c:2855:oakley_do_encrypt(): save IV for next: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 243d3277 ac28553f 2005-01-07 17:25:49: DEBUG: oakley.c:2872:oakley_do_encrypt(): encrypted. 2005-01-07 17:25:49: DEBUG: sockmisc.c:421:sendfromto(): sockname 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:49: DEBUG: sockmisc.c:423:sendfromto(): send packet from 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:49: DEBUG: sockmisc.c:425:sendfromto(): send packet to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:49: DEBUG: sockmisc.c:481:sendfromto(): src6 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 0 2005-01-07 17:25:49: DEBUG: sockmisc.c:485:sendfromto(): dst6 3ffe:501:ffff:101::11[500] 0 2005-01-07 17:25:49: DEBUG: sockmisc.c:497:sendfromto(): 1 times of 84 bytes message will be sent to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 05100201 00000000 00000054 2545142c e94cbca8 b2cb2ad3 974a2a84 7fa10b43 c0283387 1b30e2c0 3c88e248 8a91e7a8 b4f19631 6bd045d1 787322f2 243d3277 ac28553f 2005-01-07 17:25:49: DEBUG: oakley.c:2590:oakley_newiv2(): compute IV for phase2 2005-01-07 17:25:49: DEBUG: oakley.c:2591:oakley_newiv2(): phase1 last IV: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 243d3277 ac28553f cf2857be 2005-01-07 17:25:49: DEBUG: algorithm.c:256:alg_oakley_hashdef(): hash(sha1) 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2623:oakley_newiv2(): phase2 IV computed: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): c1adf144 0b256d12 2005-01-07 17:25:49: DEBUG: oakley.c:792:oakley_compute_hash1(): HASH with: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): cf2857be 0000001c 00000001 01106002 de690b1b 87a67361 56d1f16d 0f2ccfc8 2005-01-07 17:25:49: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:49: DEBUG: oakley.c:802:oakley_compute_hash1(): HASH computed: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 54aec757 a438040f 572e756f 50cd60bf a56442e0 2005-01-07 17:25:49: DEBUG: oakley.c:2789:oakley_do_encrypt(): begin encryption. 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2805:oakley_do_encrypt(): pad length = 4 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 0b000018 54aec757 a438040f 572e756f 50cd60bf a56442e0 0000001c 00000001 01106002 de690b1b 87a67361 56d1f16d 0f2ccfc8 489b6003 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2840:oakley_do_encrypt(): with key: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 dad94517 2005-01-07 17:25:49: DEBUG: oakley.c:2848:oakley_do_encrypt(): encrypted payload by IV: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): bcb5bab6 f19e1848 2005-01-07 17:25:49: DEBUG: oakley.c:2855:oakley_do_encrypt(): save IV for next: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): bcb5bab6 f19e1848 2005-01-07 17:25:49: DEBUG: oakley.c:2872:oakley_do_encrypt(): encrypted. 2005-01-07 17:25:49: DEBUG: sockmisc.c:421:sendfromto(): sockname 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:49: DEBUG: sockmisc.c:423:sendfromto(): send packet from 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:49: DEBUG: sockmisc.c:425:sendfromto(): send packet to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:49: DEBUG: sockmisc.c:481:sendfromto(): src6 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 0 2005-01-07 17:25:49: DEBUG: sockmisc.c:485:sendfromto(): dst6 3ffe:501:ffff:101::11[500] 0 2005-01-07 17:25:49: DEBUG: sockmisc.c:497:sendfromto(): 1 times of 84 bytes message will be sent to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 08100501 cf2857be 00000054 9cc5b1d3 85d40544 4aa517b6 2921fb4e 86f4cd56 ee50f447 03c15096 3c964c7e a154a1e5 ab1d4327 289ddf59 bda90bd4 bcb5bab6 f19e1848 2005-01-07 17:25:49: DEBUG: isakmp_inf.c:699:isakmp_info_send_common(): sendto Information notify. 2005-01-07 17:25:49: INFO: isakmp.c:2459:log_ph1established(): ISAKMP-SA established 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500]-3ffe:501:ffff:101::11[500] spi:de690b1b87a67361:56d1f16d0f2ccfc8 2005-01-07 17:25:49: DEBUG: isakmp.c:680:ph1_main(): === 2005-01-07 17:25:49: DEBUG: isakmp.c:233:isakmp_handler(): === 2005-01-07 17:25:49: DEBUG: isakmp.c:234:isakmp_handler(): 132 bytes message received from 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 08102003 0000ffff 00000084 906ab7bd 86705fac 59ba1046 74b9364e 075d0b2f 643d77bf 52f91768 15ffa0a3 8485084c bc94d7b5 910cbb9b c8fb5cd2 e51f5ce5 82e4e604 9886adc1 b01885e6 d03cfd15 993a2dba 31f7862c 07802fbc 35df2f3d 1b17da2b e6597cfb 2262e5d2 7ae0e298 e9cd528f 2005-01-07 17:25:49: DEBUG: oakley.c:2590:oakley_newiv2(): compute IV for phase2 2005-01-07 17:25:49: DEBUG: oakley.c:2591:oakley_newiv2(): phase1 last IV: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 243d3277 ac28553f 0000ffff 2005-01-07 17:25:49: DEBUG: algorithm.c:256:alg_oakley_hashdef(): hash(sha1) 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2623:oakley_newiv2(): phase2 IV computed: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 886344a0 cba5e09e 2005-01-07 17:25:49: DEBUG: isakmp.c:1054:isakmp_ph2begin_r(): === 2005-01-07 17:25:49: INFO: isakmp.c:1059:isakmp_ph2begin_r(): respond new phase 2 negotiation: 3ffe:501:ffff:100:240:26ff:fed2:d2e9[0]<=>3ffe:501:ffff:101::11[0] 2005-01-07 17:25:49: DEBUG: oakley.c:2666:oakley_do_decrypt(): begin decryption. 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2680:oakley_do_decrypt(): IV was saved for next processing: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 7ae0e298 e9cd528f 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2705:oakley_do_decrypt(): with key: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 dad94517 2005-01-07 17:25:49: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted payload by IV: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 7ae0e298 e9cd528f 2005-01-07 17:25:49: DEBUG: oakley.c:2716:oakley_do_decrypt(): decrypted payload, but not trimed. 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 01000018 be530fcb fa9b7d8b b094fa6a b4ed3ab4 a51b173b 0a000034 00000001 00000001 00000028 01030401 00001000 0000001c 01030000 80010001 00020004 00007080 80040002 80050002 00000014 00000000 00000000 00000000 00000001 00000000 00000007 2005-01-07 17:25:49: DEBUG: oakley.c:2725:oakley_do_decrypt(): padding len=8 2005-01-07 17:25:49: DEBUG: oakley.c:2739:oakley_do_decrypt(): skip to trim padding. 2005-01-07 17:25:49: DEBUG: oakley.c:2754:oakley_do_decrypt(): decrypted. 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 08102003 0000ffff 00000084 01000018 be530fcb fa9b7d8b b094fa6a b4ed3ab4 a51b173b 0a000034 00000001 00000001 00000028 01030401 00001000 0000001c 01030000 80010001 00020004 00007080 80040002 80050002 00000014 00000000 00000000 00000000 00000001 00000000 00000007 2005-01-07 17:25:49: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:49: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=8(hash) 2005-01-07 17:25:49: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=1(sa) 2005-01-07 17:25:49: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=10(nonce) 2005-01-07 17:25:49: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:49: DEBUG: isakmp_quick.c:1025:quick_r1recv(): HASH(1) validate:2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): be530fcb fa9b7d8b b094fa6a b4ed3ab4 a51b173b 2005-01-07 17:25:49: DEBUG: oakley.c:792:oakley_compute_hash1(): HASH with: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 0000ffff 0a000034 00000001 00000001 00000028 01030401 00001000 0000001c 01030000 80010001 00020004 00007080 80040002 80050002 00000014 00000000 00000000 00000000 00000001 2005-01-07 17:25:49: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:49: DEBUG: oakley.c:802:oakley_compute_hash1(): HASH computed: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): be530fcb fa9b7d8b b094fa6a b4ed3ab4 a51b173b 2005-01-07 17:25:49: DEBUG: isakmp_quick.c:1817:get_sainfo_r(): get sa info: 2005-01-07 17:25:49: DEBUG: isakmp_quick.c:1909:get_proposal_r(): get a destination address of SP index from phase1 address due to no ID payloads found OR because ID type is not address. 2005-01-07 17:25:49: DEBUG: isakmp_quick.c:1970:get_proposal_r(): get a source address of SP index from phase1 address due to no ID payloads found OR because ID type is not address. 2005-01-07 17:25:49: DEBUG: isakmp_quick.c:1995:get_proposal_r(): get a src address from ID payload 3ffe:501:ffff:101::11[0] prefixlen=128 ul_proto=0 2005-01-07 17:25:49: DEBUG: isakmp_quick.c:2000:get_proposal_r(): get dst address from ID payload 3ffe:501:ffff:100:240:26ff:fed2:d2e9[0] prefixlen=128 ul_proto=0 2005-01-07 17:25:49: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfe8d0: 3ffe:501:ffff:101::11/128[0] 3ffe:501:ffff:100:240:26ff:fed2:d2e9/128[0] proto=any dir=in 2005-01-07 17:25:49: DEBUG: policy.c:217:cmpspidxwild(): db: 0x809dc08: 3ffe:501:ffff:101::11/128[0] 3ffe:501:ffff:100:240:26ff:fed2:d2e9/128[0] proto=any dir=in 2005-01-07 17:25:49: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfe8d0 masked with /128: 3ffe:501:ffff:101::11[0] 2005-01-07 17:25:49: DEBUG: policy.c:246:cmpspidxwild(): 0x809dc08 masked with /128: 3ffe:501:ffff:101::11[0] 2005-01-07 17:25:49: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfe8d0 masked with /128: 3ffe:501:ffff:100:240:26ff:fed2:d2e9[0] 2005-01-07 17:25:49: DEBUG: policy.c:262:cmpspidxwild(): 0x809dc08 masked with /128: 3ffe:501:ffff:100:240:26ff:fed2:d2e9[0] 2005-01-07 17:25:49: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfe8d0: 3ffe:501:ffff:100:240:26ff:fed2:d2e9/128[0] 3ffe:501:ffff:101::11/128[0] proto=any dir=out 2005-01-07 17:25:49: DEBUG: policy.c:217:cmpspidxwild(): db: 0x809dc08: 3ffe:501:ffff:101::11/128[0] 3ffe:501:ffff:100:240:26ff:fed2:d2e9/128[0] proto=any dir=in 2005-01-07 17:25:49: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfe8d0: 3ffe:501:ffff:100:240:26ff:fed2:d2e9/128[0] 3ffe:501:ffff:101::11/128[0] proto=any dir=out 2005-01-07 17:25:49: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80b3008: 3ffe:501:ffff:100:240:26ff:fed2:d2e9/128[0] 3ffe:501:ffff:101::11/128[0] proto=any dir=out 2005-01-07 17:25:49: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfe8d0 masked with /128: 3ffe:501:ffff:100:240:26ff:fed2:d2e9[0] 2005-01-07 17:25:49: DEBUG: policy.c:246:cmpspidxwild(): 0x80b3008 masked with /128: 3ffe:501:ffff:100:240:26ff:fed2:d2e9[0] 2005-01-07 17:25:49: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfe8d0 masked with /128: 3ffe:501:ffff:101::11[0] 2005-01-07 17:25:49: DEBUG: policy.c:262:cmpspidxwild(): 0x80b3008 masked with /128: 3ffe:501:ffff:101::11[0] 2005-01-07 17:25:49: DEBUG: isakmp_quick.c:2056:get_proposal_r(): suitable SP found:3ffe:501:ffff:100:240:26ff:fed2:d2e9/128[0] 3ffe:501:ffff:101::11/128[0] proto=any dir=out 2005-01-07 17:25:49: DEBUG: proposal.c:828:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Transport reqid=0:0) 2005-01-07 17:25:49: DEBUG: proposal.c:862:printsatrns(): (trns_id=3DES encklen=0 authtype=hmac-sha) 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=48 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000028 01030401 00001000 0000001c 01030000 80010001 00020004 00007080 80040002 80050002 2005-01-07 17:25:49: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:49: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=2(prop) 2005-01-07 17:25:49: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #1 len=40 2005-01-07 17:25:49: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:49: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=3(trns) 2005-01-07 17:25:49: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1311:get_transform(): transform #1 len=28 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x0000, lorv=4 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encryption Mode, flag=0x8000, lorv=Transport 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 1: 2005-01-07 17:25:49: DEBUG: proposal.c:895:print_proppair0(): 0x80b50a0: next=0x0 tnext=0x0 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #1: 1 transform 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:948:get_ph2approval(): begin compare proposals. 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:954:get_ph2approval(): pair[1]: 0x80b50a0 2005-01-07 17:25:49: DEBUG: proposal.c:895:print_proppair0(): 0x80b50a0: next=0x0 tnext=0x0 2005-01-07 17:25:49: DEBUG: proposal.c:684:aproppair2saprop(): prop#=1 prot-id=ESP spi-size=4 #trns=1 trns#=1 trns-id=3DES 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:3663:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:3663:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x0000, lorv=4 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:3663:ipsecdoi_t2satrns(): type=Encryption Mode, flag=0x8000, lorv=Transport 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:3663:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:990:get_ph2approvalx(): peer's single bundle: 2005-01-07 17:25:49: DEBUG: proposal.c:828:printsaproto(): (proto_id=ESP spisize=4 spi=00001000 spi_p=00000000 encmode=Transport reqid=0:0) 2005-01-07 17:25:49: DEBUG: proposal.c:862:printsatrns(): (trns_id=3DES encklen=0 authtype=hmac-sha) 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:993:get_ph2approvalx(): my single bundle: 2005-01-07 17:25:49: DEBUG: proposal.c:828:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Transport reqid=0:0) 2005-01-07 17:25:49: DEBUG: proposal.c:862:printsatrns(): (trns_id=3DES encklen=0 authtype=hmac-sha) 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1012:get_ph2approvalx(): matched 2005-01-07 17:25:49: DEBUG: isakmp.c:1088:isakmp_ph2begin_r(): === 2005-01-07 17:25:49: DEBUG: pfkey.c:872:pk_sendgetspi(): call pfkey_send_getspi 2005-01-07 17:25:49: DEBUG: pfkey.c:885:pk_sendgetspi(): pfkey GETSPI sent: ESP/Transport 3ffe:501:ffff:101::11->3ffe:501:ffff:100:240:26ff:fed2:d2e9 2005-01-07 17:25:49: DEBUG: isakmp_quick.c:1143:quick_r1prep(): pfkey getspi sent. 2005-01-07 17:25:49: DEBUG: pfkey.c:197:pfkey_handler(): get pfkey GETSPI message 2005-01-07 17:25:49: DEBUG: pfkey.c:956:pk_recvgetspi(): pfkey GETSPI succeeded: ESP/Transport 3ffe:501:ffff:101::11->3ffe:501:ffff:100:240:26ff:fed2:d2e9 spi=217103566(0xcf0bcce) 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=48 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000028 01030401 00000000 0000001c 01030000 80010001 00020004 00007080 80040002 80050002 2005-01-07 17:25:49: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:49: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=2(prop) 2005-01-07 17:25:49: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #1 len=40 2005-01-07 17:25:49: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:49: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=3(trns) 2005-01-07 17:25:49: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1311:get_transform(): transform #1 len=28 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x0000, lorv=4 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encryption Mode, flag=0x8000, lorv=Transport 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 1: 2005-01-07 17:25:49: DEBUG: proposal.c:895:print_proppair0(): 0x80a3fc0: next=0x0 tnext=0x0 2005-01-07 17:25:49: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #1: 1 transform 2005-01-07 17:25:49: DEBUG: isakmp.c:2159:set_isakmp_payload(): add payload of len 48, next type 10 2005-01-07 17:25:49: DEBUG: isakmp.c:2159:set_isakmp_payload(): add payload of len 16, next type 0 2005-01-07 17:25:49: DEBUG: oakley.c:792:oakley_compute_hash1(): HASH with: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 0000ffff 00000000 00000000 00000000 00000001 0a000034 00000001 00000001 00000028 01030401 0cf0bcce 0000001c 01030000 80010001 00020004 00007080 80040002 80050002 00000014 a3b03096 b5da69b3 7b5d31bb aea3753d 2005-01-07 17:25:49: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:49: DEBUG: oakley.c:802:oakley_compute_hash1(): HASH computed: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 9b92d06b 35a1f20e 82d8ba53 f2efe0b4 0ff16372 2005-01-07 17:25:49: DEBUG: isakmp.c:2159:set_isakmp_payload(): add payload of len 20, next type 1 2005-01-07 17:25:49: DEBUG: oakley.c:2789:oakley_do_encrypt(): begin encryption. 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2805:oakley_do_encrypt(): pad length = 8 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 01000018 9b92d06b 35a1f20e 82d8ba53 f2efe0b4 0ff16372 0a000034 00000001 00000001 00000028 01030401 0cf0bcce 0000001c 01030000 80010001 00020004 00007080 80040002 80050002 00000014 a3b03096 b5da69b3 7b5d31bb aea3753d e5885203 29091a07 2005-01-07 17:25:49: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:49: DEBUG: oakley.c:2840:oakley_do_encrypt(): with key: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 dad94517 2005-01-07 17:25:49: DEBUG: oakley.c:2848:oakley_do_encrypt(): encrypted payload by IV: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 4415cf46 541b0fc5 2005-01-07 17:25:49: DEBUG: oakley.c:2855:oakley_do_encrypt(): save IV for next: 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): 4415cf46 541b0fc5 2005-01-07 17:25:49: DEBUG: oakley.c:2872:oakley_do_encrypt(): encrypted. 2005-01-07 17:25:49: DEBUG: sockmisc.c:421:sendfromto(): sockname 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:49: DEBUG: sockmisc.c:423:sendfromto(): send packet from 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:49: DEBUG: sockmisc.c:425:sendfromto(): send packet to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:49: DEBUG: sockmisc.c:481:sendfromto(): src6 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 0 2005-01-07 17:25:49: DEBUG: sockmisc.c:485:sendfromto(): dst6 3ffe:501:ffff:101::11[500] 0 2005-01-07 17:25:49: DEBUG: sockmisc.c:497:sendfromto(): 1 times of 132 bytes message will be sent to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:49: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 08102003 0000ffff 00000084 c603d63b 3627b04d 57d3fa41 d0ca3433 4aa15ff8 976feb49 c4b7d955 e8460d7d e98cfe16 2d131baa 70147011 0de72f36 ee9e8cf7 81addda2 0ec4d834 4fe0f4f6 1d2a4cf7 fba66b76 b3160089 424dfb0c c41fec7f d6ca73b6 8ff4e5f5 624104b9 4415cf46 541b0fc5 2005-01-07 17:25:49: DEBUG: isakmp.c:1497:isakmp_ph2resend(): resend phase2 packet de690b1b87a67361:56d1f16d0f2ccfc8:00000000 2005-01-07 17:25:50: DEBUG: isakmp.c:233:isakmp_handler(): === 2005-01-07 17:25:50: DEBUG: isakmp.c:234:isakmp_handler(): 60 bytes message received from 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 08102003 0000ffff 0000003c d3ed95e2 cd71dc4d 3732c664 2501cefa 655c0252 e4e277ee 2e241648 7a7b5437 2005-01-07 17:25:50: DEBUG: oakley.c:2666:oakley_do_decrypt(): begin decryption. 2005-01-07 17:25:50: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:50: DEBUG: oakley.c:2680:oakley_do_decrypt(): IV was saved for next processing: 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 2e241648 7a7b5437 2005-01-07 17:25:50: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:50: DEBUG: oakley.c:2705:oakley_do_decrypt(): with key: 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 dad94517 2005-01-07 17:25:50: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted payload by IV: 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 2e241648 7a7b5437 2005-01-07 17:25:50: DEBUG: oakley.c:2716:oakley_do_decrypt(): decrypted payload, but not trimed. 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 00000018 519bf679 3e93f9c7 b36d47ac 7b6ea818 0610f36f 00000000 00000007 2005-01-07 17:25:50: DEBUG: oakley.c:2725:oakley_do_decrypt(): padding len=8 2005-01-07 17:25:50: DEBUG: oakley.c:2739:oakley_do_decrypt(): skip to trim padding. 2005-01-07 17:25:50: DEBUG: oakley.c:2754:oakley_do_decrypt(): decrypted. 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 08102003 0000ffff 0000003c 00000018 519bf679 3e93f9c7 b36d47ac 7b6ea818 0610f36f 00000000 00000007 2005-01-07 17:25:50: DEBUG: isakmp.c:1122:isakmp_parsewoh(): begin. 2005-01-07 17:25:50: DEBUG: isakmp.c:1149:isakmp_parsewoh(): seen nptype=8(hash) 2005-01-07 17:25:50: DEBUG: isakmp.c:1188:isakmp_parsewoh(): succeed. 2005-01-07 17:25:50: DEBUG: isakmp_quick.c:1430:quick_r3recv(): HASH(3) validate:2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 519bf679 3e93f9c7 b36d47ac 7b6ea818 0610f36f 2005-01-07 17:25:50: DEBUG: oakley.c:736:oakley_compute_hash3(): HASH with: 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 000000ff ff000000 00000000 00000000 00000000 01a3b030 96b5da69 b37b5d31 bbaea375 3d 2005-01-07 17:25:50: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:50: DEBUG: oakley.c:746:oakley_compute_hash3(): HASH computed: 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 519bf679 3e93f9c7 b36d47ac 7b6ea818 0610f36f 2005-01-07 17:25:50: DEBUG: isakmp.c:746:quick_main(): === 2005-01-07 17:25:50: DEBUG: isakmp_quick.c:1501:quick_r3send(): HASH(4) generate 2005-01-07 17:25:50: DEBUG: oakley.c:792:oakley_compute_hash1(): HASH with: 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 0000ffff 00000010 01000000 03044000 0cf0bcce 2005-01-07 17:25:50: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:25:50: DEBUG: oakley.c:802:oakley_compute_hash1(): HASH computed: 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 97a14b5c 9ecc5b55 ad2ef286 a22043a5 4bfa52eb 2005-01-07 17:25:50: DEBUG: isakmp.c:2159:set_isakmp_payload(): add payload of len 20, next type 11 2005-01-07 17:25:50: DEBUG: oakley.c:2789:oakley_do_encrypt(): begin encryption. 2005-01-07 17:25:50: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:50: DEBUG: oakley.c:2805:oakley_do_encrypt(): pad length = 8 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 0b000018 97a14b5c 9ecc5b55 ad2ef286 a22043a5 4bfa52eb 00000010 01000000 03044000 0cf0bcce 48501e5a d0ba4607 2005-01-07 17:25:50: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:25:50: DEBUG: oakley.c:2840:oakley_do_encrypt(): with key: 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 dad94517 2005-01-07 17:25:50: DEBUG: oakley.c:2848:oakley_do_encrypt(): encrypted payload by IV: 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 60083c1b a97a9a72 2005-01-07 17:25:50: DEBUG: oakley.c:2855:oakley_do_encrypt(): save IV for next: 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): 60083c1b a97a9a72 2005-01-07 17:25:50: DEBUG: oakley.c:2872:oakley_do_encrypt(): encrypted. 2005-01-07 17:25:50: DEBUG: sockmisc.c:421:sendfromto(): sockname 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:50: DEBUG: sockmisc.c:423:sendfromto(): send packet from 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:25:50: DEBUG: sockmisc.c:425:sendfromto(): send packet to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:50: DEBUG: sockmisc.c:481:sendfromto(): src6 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 0 2005-01-07 17:25:50: DEBUG: sockmisc.c:485:sendfromto(): dst6 3ffe:501:ffff:101::11[500] 0 2005-01-07 17:25:50: DEBUG: sockmisc.c:497:sendfromto(): 1 times of 76 bytes message will be sent to 3ffe:501:ffff:101::11[500] 2005-01-07 17:25:50: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 08102003 0000ffff 0000004c 88e3a2c5 28c2f6f6 bd9d1426 f217a40b 944b5246 392c13d6 2d4ff13a 7038a41c b11b11a9 eea7b1f3 60083c1b a97a9a72 2005-01-07 17:25:58: DEBUG: pfkey.c:197:pfkey_handler(): get pfkey REGISTER message 2005-01-07 17:25:58: DEBUG: pfkey.c:236:pfkey_handler(): not supported command REGISTER 2005-01-07 17:25:58: DEBUG: pfkey.c:197:pfkey_handler(): get pfkey FLUSH message 2005-01-07 17:25:58: DEBUG: schedule.c:210:sched_scrub_param(): an undead schedule has been deleted. 2005-01-07 17:26:04: INFO: session.c:299:check_sigreq(): caught signal 15 2005-01-07 17:26:04: DEBUG: pfkey.c:197:pfkey_handler(): get pfkey FLUSH message 2005-01-07 17:26:05: DEBUG: pfkey.c:333:pfkey_dump_sadb(): call pfkey_send_dump 2005-01-07 17:26:05: DEBUG: oakley.c:2590:oakley_newiv2(): compute IV for phase2 2005-01-07 17:26:05: DEBUG: oakley.c:2591:oakley_newiv2(): phase1 last IV: 2005-01-07 17:26:05: DEBUG: plog.c:193:plogdump(): 243d3277 ac28553f d3e55507 2005-01-07 17:26:05: DEBUG: algorithm.c:256:alg_oakley_hashdef(): hash(sha1) 2005-01-07 17:26:05: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:26:05: DEBUG: oakley.c:2623:oakley_newiv2(): phase2 IV computed: 2005-01-07 17:26:05: DEBUG: plog.c:193:plogdump(): c810da86 499140ad 2005-01-07 17:26:05: DEBUG: oakley.c:792:oakley_compute_hash1(): HASH with: 2005-01-07 17:26:05: DEBUG: plog.c:193:plogdump(): d3e55507 0000001c 00000001 01100001 de690b1b 87a67361 56d1f16d 0f2ccfc8 2005-01-07 17:26:05: DEBUG: algorithm.c:326:alg_oakley_hmacdef(): hmac(hmac_sha1) 2005-01-07 17:26:05: DEBUG: oakley.c:802:oakley_compute_hash1(): HASH computed: 2005-01-07 17:26:05: DEBUG: plog.c:193:plogdump(): f52fee42 274bec09 bf331d51 8aa0046c e56feb83 2005-01-07 17:26:05: DEBUG: oakley.c:2789:oakley_do_encrypt(): begin encryption. 2005-01-07 17:26:05: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:26:05: DEBUG: oakley.c:2805:oakley_do_encrypt(): pad length = 4 2005-01-07 17:26:05: DEBUG: plog.c:193:plogdump(): 0c000018 f52fee42 274bec09 bf331d51 8aa0046c e56feb83 0000001c 00000001 01100001 de690b1b 87a67361 56d1f16d 0f2ccfc8 a6048c03 2005-01-07 17:26:05: DEBUG: algorithm.c:386:alg_oakley_encdef(): encription(3des) 2005-01-07 17:26:05: DEBUG: oakley.c:2840:oakley_do_encrypt(): with key: 2005-01-07 17:26:05: DEBUG: plog.c:193:plogdump(): b45c616f a1d381e0 1b5c98e9 1acefc17 989bda52 dad94517 2005-01-07 17:26:05: DEBUG: oakley.c:2848:oakley_do_encrypt(): encrypted payload by IV: 2005-01-07 17:26:05: DEBUG: plog.c:193:plogdump(): 2bfcd848 8a71ecfb 2005-01-07 17:26:05: DEBUG: oakley.c:2855:oakley_do_encrypt(): save IV for next: 2005-01-07 17:26:05: DEBUG: plog.c:193:plogdump(): 2bfcd848 8a71ecfb 2005-01-07 17:26:05: DEBUG: oakley.c:2872:oakley_do_encrypt(): encrypted. 2005-01-07 17:26:05: DEBUG: sockmisc.c:421:sendfromto(): sockname 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:26:05: DEBUG: sockmisc.c:423:sendfromto(): send packet from 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 2005-01-07 17:26:05: DEBUG: sockmisc.c:425:sendfromto(): send packet to 3ffe:501:ffff:101::11[500] 2005-01-07 17:26:05: DEBUG: sockmisc.c:481:sendfromto(): src6 3ffe:501:ffff:100:240:26ff:fed2:d2e9[500] 0 2005-01-07 17:26:05: DEBUG: sockmisc.c:485:sendfromto(): dst6 3ffe:501:ffff:101::11[500] 0 2005-01-07 17:26:05: DEBUG: sockmisc.c:497:sendfromto(): 1 times of 84 bytes message will be sent to 3ffe:501:ffff:101::11[500] 2005-01-07 17:26:05: DEBUG: plog.c:193:plogdump(): de690b1b 87a67361 56d1f16d 0f2ccfc8 08100501 d3e55507 00000054 5f15dc67 bdc1a619 cfce75ac a20a97c0 a1ec6743 bc763b4a b366d44d f53177c0 52b27371 e462a1e4 674726af 9ab69aff 2bfcd848 8a71ecfb 2005-01-07 17:26:05: DEBUG: isakmp_inf.c:699:isakmp_info_send_common(): sendto Information delete. 2005-01-07 17:26:05: DEBUG: schedule.c:210:sched_scrub_param(): an undead schedule has been deleted. 2005-01-07 17:26:05: INFO: session.c:180:close_session(): racoon shutdown