2003-09-19 16:16:56: INFO: main.c:172:main(): @(#)package version netbsd-pkgsrc-20021120a 2003-09-19 16:16:56: INFO: main.c:174:main(): @(#)internal version 20001216 sakane@kame.net 2003-09-19 16:16:56: INFO: main.c:175:main(): @(#)This product linked OpenSSL 0.9.6g 9 Aug 2002 (http://www.openssl.org/) 2003-09-19 16:16:56: DEBUG: pfkey.c:371:pfkey_init(): call pfkey_send_register for AH 2003-09-19 16:16:56: DEBUG: pfkey.c:371:pfkey_init(): call pfkey_send_register for ESP 2003-09-19 16:16:56: DEBUG: pfkey.c:371:pfkey_init(): call pfkey_send_register for IPCOMP 2003-09-19 16:16:56: DEBUG2: cftoken.l:115:yylex(): begin <5>path 2003-09-19 16:16:56: DEBUG2: cftoken.l:118:yylex(): <5> 2003-09-19 16:16:56: DEBUG2: cftoken.l:386:yylex(): <5> 2003-09-19 16:16:56: DEBUG2: cftoken.l:124:yylex(): begin <3>; 2003-09-19 16:16:56: DEBUG2: cftoken.l:115:yylex(): begin <5>path 2003-09-19 16:16:56: DEBUG2: cftoken.l:120:yylex(): <5> 2003-09-19 16:16:56: DEBUG2: cftoken.l:386:yylex(): <5> 2003-09-19 16:16:56: DEBUG2: cftoken.l:124:yylex(): begin <3>; 2003-09-19 16:16:56: DEBUG2: cftoken.l:157:yylex(): begin <13>listen 2003-09-19 16:16:56: DEBUG2: cftoken.l:159:yylex(): <13> 2003-09-19 16:16:56: DEBUG2: cftoken.l:401:yylex(): <13> 2003-09-19 16:16:56: DEBUG2: cftoken.l:265:yylex(): <13> 2003-09-19 16:16:56: DEBUG2: cftoken.l:161:yylex(): <13> 2003-09-19 16:16:56: DEBUG2: cftoken.l:195:yylex(): begin <25>remote 2003-09-19 16:16:56: DEBUG2: cftoken.l:196:yylex(): <25> 2003-09-19 16:16:56: DEBUG2: cftoken.l:200:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:203:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:227:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:229:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:215:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:216:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:386:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:386:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:212:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:336:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:386:yylex(): <27> 2003-09-19 16:16:56: DEBUG2: cftoken.l:238:yylex(): begin <29>proposal 2003-09-19 16:16:56: DEBUG2: cftoken.l:244:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:285:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:246:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:306:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:245:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:324:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:247:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:315:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:238:yylex(): begin <29>proposal 2003-09-19 16:16:56: DEBUG2: cftoken.l:244:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:285:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:246:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:306:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:245:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:325:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:247:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cftoken.l:315:yylex(): <29> 2003-09-19 16:16:56: DEBUG2: cfparse.y:1172:set_isakmp_proposal(): lifetime = 28800 2003-09-19 16:16:56: DEBUG2: cfparse.y:1175:set_isakmp_proposal(): lifebyte = 0 2003-09-19 16:16:56: DEBUG2: cfparse.y:1178:set_isakmp_proposal(): encklen=0 2003-09-19 16:16:56: DEBUG2: cfparse.y:1241:expand_isakmpspec(): p:1 t:1 2003-09-19 16:16:56: DEBUG2: cfparse.y:1245:expand_isakmpspec(): 3DES-CBC(5) 2003-09-19 16:16:56: DEBUG2: cfparse.y:1245:expand_isakmpspec(): SHA(2) 2003-09-19 16:16:56: DEBUG2: cfparse.y:1245:expand_isakmpspec(): 1024-bit MODP group(2) 2003-09-19 16:16:56: DEBUG2: cfparse.y:1245:expand_isakmpspec(): pre-shared key(1) 2003-09-19 16:16:56: DEBUG2: cfparse.y:1252:expand_isakmpspec(): 2003-09-19 16:16:56: DEBUG2: cfparse.y:1172:set_isakmp_proposal(): lifetime = 28800 2003-09-19 16:16:56: DEBUG2: cfparse.y:1175:set_isakmp_proposal(): lifebyte = 0 2003-09-19 16:16:56: DEBUG2: cfparse.y:1178:set_isakmp_proposal(): encklen=0 2003-09-19 16:16:56: DEBUG2: cfparse.y:1241:expand_isakmpspec(): p:1 t:2 2003-09-19 16:16:56: DEBUG2: cfparse.y:1245:expand_isakmpspec(): 3DES-CBC(5) 2003-09-19 16:16:56: DEBUG2: cfparse.y:1245:expand_isakmpspec(): SHA(2) 2003-09-19 16:16:56: DEBUG2: cfparse.y:1245:expand_isakmpspec(): 1024-bit MODP group(2) 2003-09-19 16:16:56: DEBUG2: cfparse.y:1245:expand_isakmpspec(): RSA signatures(3) 2003-09-19 16:16:56: DEBUG2: cfparse.y:1252:expand_isakmpspec(): 2003-09-19 16:16:56: DEBUG2: cftoken.l:175:yylex(): begin <21>sainfo 2003-09-19 16:16:56: DEBUG2: cftoken.l:176:yylex(): <21> 2003-09-19 16:16:56: DEBUG2: cftoken.l:189:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:285:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:190:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:299:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:186:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:187:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:356:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:345:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:183:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:315:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:191:yylex(): <23> 2003-09-19 16:16:56: DEBUG2: cftoken.l:312:yylex(): <23> 2003-09-19 16:16:56: DEBUG: pfkey.c:2243:pk_checkalg(): compression algorithm can not be checked because sadb message doesn't support it. 2003-09-19 16:16:56: DEBUG2: cfparse.y:1354:cfparse(): parse successed. 2003-09-19 16:16:57: INFO: isakmp.c:1358:isakmp_open(): 63.202.92.133[500] used as isakmp port (fd=10) 2003-09-19 16:16:57: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey X_SPDDUMP message 2003-09-19 16:16:57: DEBUG2: plog.c:193:plogdump(): 02120000 17000100 01000000 dd170000 03000500 ff200000 10020000 c0a865fd 00000000 00000000 03000600 ff200000 10020000 0a080807 00000000 00000000 07001200 02000100 17000000 00000000 28003200 02020000 10020000 41dc4547 00000000 00000000 10020000 3fca5c85 00000000 00000000 04000200 00000000 00000000 00000000 688e6b3f 00000000 688e6b3f 00000000 04000300 00000000 00000000 00000000 00000000 00000000 00000000 00000000 2003-09-19 16:16:57: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey X_SPDDUMP message 2003-09-19 16:16:57: DEBUG2: plog.c:193:plogdump(): 02120000 17000100 00000000 dd170000 03000500 ff200000 10020000 0a080807 00000000 00000000 03000600 ff200000 10020000 c0a865fd 00000000 00000000 07001200 02000200 18000000 00000000 28003200 02020000 10020000 3fca5c85 00000000 00000000 10020000 41dc4547 00000000 00000000 04000200 00000000 00000000 00000000 688e6b3f 00000000 688e6b3f 00000000 04000300 00000000 00000000 00000000 00000000 00000000 00000000 00000000 2003-09-19 16:16:57: DEBUG: policy.c:184:cmpspidxstrict(): sub:0xbfbfd53c: 10.8.8.7/32[0] 192.168.101.253/32[0] proto=any dir=out 2003-09-19 16:16:57: DEBUG: policy.c:185:cmpspidxstrict(): db :0x80a2c08: 192.168.101.253/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-09-19 16:17:06: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:17:06: DEBUG: isakmp.c:222:isakmp_handler(): 372 bytes message received from 65.220.69.71[500] 2003-09-19 16:17:06: DEBUG: plog.c:193:plogdump(): b964fc2e 295d46a6 7042efa5 c82df37e 08102001 72ffd628 00000174 7b9e3788 b186981d fca4906d 4c2c4248 817cd44b 7ba7c170 6069e5eb 7eac27e5 894cecd4 930ff7d7 684ff2bf 5c00338c f45178d1 3c73db4d 4676fc53 c1df9bfe 9e20aabf 5ac49a8a dffa3057 25849b51 55b9aba2 8e13e280 879cdfe0 064c0a6f c5adcac9 40656886 4bf4b983 400aee6c b6c0896a 4b456ece 3d0cb30d ce896446 3496de90 97fd5b9e 36c33f23 a51ae558 f077207c 39ebd1d9 11adbe17 34fee8bb c9d63460 e7c070ed 8f9783a6 08e08554 38bfe8bc 32b84edf fa31080a 912ffa80 ab7afad4 898c1203 a4b60331 9d6c5fdb cc90c916 f57596f4 d37a0bc0 ab83fab5 e6b279fc 58f10ce2 0d4692f2 05571484 25e96160 713e6a33 d7da956c 3fa4be4f bcd0a45a f9da8d44 ec3bb64a 7f47f40a 6d04ba55 4c69ce89 83f40fcf 968a5c60 42ab15ec c65d6a8a 26e8d555 7535c6d3 e3ada330 1a73c0d0 af91dd43 31a69373 c7069bed 851d7733 9e5a125b bf8990cd e55b2f5e 96c41c9a 2003-09-19 16:17:06: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:17:06: DEBUG: remoteconf.c:129:getrmconf(): anonymous configuration selected for 65.220.69.71[500]. 2003-09-19 16:17:06: DEBUG: isakmp.c:1996:isakmp_newcookie(): new cookie: 0ce37d52adc1f4ac 2003-09-19 16:17:06: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:17:06: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-09-19 16:17:06: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-09-19 16:17:06: DEBUG: sockmisc.c:425:sendfromto(): send packet to 65.220.69.71[500] 2003-09-19 16:17:06: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 40 bytes message will be sent to 63.202.92.133[500] 2003-09-19 16:17:06: DEBUG: plog.c:193:plogdump(): b964fc2e 295d46a6 0ce37d52 adc1f4ac 0b100500 246ff69f 00000028 0000000c 00000001 01000004 2003-09-19 16:17:06: DEBUG: isakmp_inf.c:634:isakmp_info_send_common(): sendto Information notify. 2003-09-19 16:17:06: ERROR: isakmp.c:490:isakmp_main(): can't start the quick mode, there is no ISAKMP-SA, b964fc2e295d46a6:7042efa5c82df37e:000072ff 2003-09-19 16:17:16: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:17:16: DEBUG: isakmp.c:222:isakmp_handler(): 372 bytes message received from 65.220.69.71[500] 2003-09-19 16:17:16: DEBUG: plog.c:193:plogdump(): b964fc2e 295d46a6 7042efa5 c82df37e 08102001 72ffd628 00000174 7b9e3788 b186981d fca4906d 4c2c4248 817cd44b 7ba7c170 6069e5eb 7eac27e5 894cecd4 930ff7d7 684ff2bf 5c00338c f45178d1 3c73db4d 4676fc53 c1df9bfe 9e20aabf 5ac49a8a dffa3057 25849b51 55b9aba2 8e13e280 879cdfe0 064c0a6f c5adcac9 40656886 4bf4b983 400aee6c b6c0896a 4b456ece 3d0cb30d ce896446 3496de90 97fd5b9e 36c33f23 a51ae558 f077207c 39ebd1d9 11adbe17 34fee8bb c9d63460 e7c070ed 8f9783a6 08e08554 38bfe8bc 32b84edf fa31080a 912ffa80 ab7afad4 898c1203 a4b60331 9d6c5fdb cc90c916 f57596f4 d37a0bc0 ab83fab5 e6b279fc 58f10ce2 0d4692f2 05571484 25e96160 713e6a33 d7da956c 3fa4be4f bcd0a45a f9da8d44 ec3bb64a 7f47f40a 6d04ba55 4c69ce89 83f40fcf 968a5c60 42ab15ec c65d6a8a 26e8d555 7535c6d3 e3ada330 1a73c0d0 af91dd43 31a69373 c7069bed 851d7733 9e5a125b bf8990cd e55b2f5e 96c41c9a 2003-09-19 16:17:16: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:17:16: DEBUG: remoteconf.c:129:getrmconf(): anonymous configuration selected for 65.220.69.71[500]. 2003-09-19 16:17:16: DEBUG: isakmp.c:1996:isakmp_newcookie(): new cookie: 7e7ab18cb3cbe0be 2003-09-19 16:17:16: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:17:16: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-09-19 16:17:16: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-09-19 16:17:16: DEBUG: sockmisc.c:425:sendfromto(): send packet to 65.220.69.71[500] 2003-09-19 16:17:16: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 40 bytes message will be sent to 63.202.92.133[500] 2003-09-19 16:17:16: DEBUG: plog.c:193:plogdump(): b964fc2e 295d46a6 7e7ab18c b3cbe0be 0b100500 5ef42f86 00000028 0000000c 00000001 01000004 2003-09-19 16:17:16: DEBUG: isakmp_inf.c:634:isakmp_info_send_common(): sendto Information notify. 2003-09-19 16:17:16: ERROR: isakmp.c:490:isakmp_main(): can't start the quick mode, there is no ISAKMP-SA, b964fc2e295d46a6:7042efa5c82df37e:000072ff 2003-09-19 16:17:26: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:17:26: DEBUG: isakmp.c:222:isakmp_handler(): 372 bytes message received from 65.220.69.71[500] 2003-09-19 16:17:26: DEBUG: plog.c:193:plogdump(): b964fc2e 295d46a6 7042efa5 c82df37e 08102001 72ffd628 00000174 7b9e3788 b186981d fca4906d 4c2c4248 817cd44b 7ba7c170 6069e5eb 7eac27e5 894cecd4 930ff7d7 684ff2bf 5c00338c f45178d1 3c73db4d 4676fc53 c1df9bfe 9e20aabf 5ac49a8a dffa3057 25849b51 55b9aba2 8e13e280 879cdfe0 064c0a6f c5adcac9 40656886 4bf4b983 400aee6c b6c0896a 4b456ece 3d0cb30d ce896446 3496de90 97fd5b9e 36c33f23 a51ae558 f077207c 39ebd1d9 11adbe17 34fee8bb c9d63460 e7c070ed 8f9783a6 08e08554 38bfe8bc 32b84edf fa31080a 912ffa80 ab7afad4 898c1203 a4b60331 9d6c5fdb cc90c916 f57596f4 d37a0bc0 ab83fab5 e6b279fc 58f10ce2 0d4692f2 05571484 25e96160 713e6a33 d7da956c 3fa4be4f bcd0a45a f9da8d44 ec3bb64a 7f47f40a 6d04ba55 4c69ce89 83f40fcf 968a5c60 42ab15ec c65d6a8a 26e8d555 7535c6d3 e3ada330 1a73c0d0 af91dd43 31a69373 c7069bed 851d7733 9e5a125b bf8990cd e55b2f5e 96c41c9a 2003-09-19 16:17:26: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:17:26: DEBUG: remoteconf.c:129:getrmconf(): anonymous configuration selected for 65.220.69.71[500]. 2003-09-19 16:17:26: DEBUG: isakmp.c:1996:isakmp_newcookie(): new cookie: c63b6f1785d8b875 2003-09-19 16:17:26: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:17:26: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-09-19 16:17:26: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-09-19 16:17:26: DEBUG: sockmisc.c:425:sendfromto(): send packet to 65.220.69.71[500] 2003-09-19 16:17:26: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 40 bytes message will be sent to 63.202.92.133[500] 2003-09-19 16:17:26: DEBUG: plog.c:193:plogdump(): b964fc2e 295d46a6 c63b6f17 85d8b875 0b100500 ea9aa892 00000028 0000000c 00000001 01000004 2003-09-19 16:17:26: DEBUG: isakmp_inf.c:634:isakmp_info_send_common(): sendto Information notify. 2003-09-19 16:17:26: ERROR: isakmp.c:490:isakmp_main(): can't start the quick mode, there is no ISAKMP-SA, b964fc2e295d46a6:7042efa5c82df37e:000072ff 2003-09-19 16:17:36: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:17:36: DEBUG: isakmp.c:222:isakmp_handler(): 84 bytes message received from 65.220.69.71[500] 2003-09-19 16:17:36: DEBUG: plog.c:193:plogdump(): b964fc2e 295d46a6 7042efa5 c82df37e 08100501 777d5822 00000054 d569d4b3 12eb962a 6c550d13 0b6b26fc 777479c9 4340409d c986842f 58008e5e 06a6bdac 6d3404cf 9b41f461 d48b379c 321e8f0c 609c4bff 2003-09-19 16:17:36: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:17:36: ERROR: isakmp.c:466:isakmp_main(): unknown Informational exchange received. 2003-09-19 16:19:02: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:19:02: DEBUG: isakmp.c:222:isakmp_handler(): 192 bytes message received from 65.220.69.71[500] 2003-09-19 16:19:02: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 00000000 00000000 01100200 00000000 000000c0 000000a4 00000001 00000001 00000098 00010004 03000024 00010000 800b0001 000c0004 01e13380 80010005 80020002 80030001 80040002 03000024 01010000 800b0001 000c0004 01e13380 80010005 80020001 80030001 80040002 03000024 02010000 800b0001 000c0004 01e13380 80010001 80020001 80030001 80040002 00000024 03010000 800b0001 000c0004 01e13380 80010001 80020002 80030001 80040002 2003-09-19 16:19:02: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:02: DEBUG: remoteconf.c:129:getrmconf(): anonymous configuration selected for 65.220.69.71[500]. 2003-09-19 16:19:02: DEBUG: isakmp.c:889:isakmp_ph1begin_r(): === 2003-09-19 16:19:02: INFO: isakmp.c:894:isakmp_ph1begin_r(): respond new phase 1 negotiation: 63.202.92.133[500]<=>65.220.69.71[500] 2003-09-19 16:19:02: INFO: isakmp.c:899:isakmp_ph1begin_r(): begin Identity Protection mode. 2003-09-19 16:19:02: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:02: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=1(sa) 2003-09-19 16:19:02: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=160 2003-09-19 16:19:02: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000098 00010004 03000024 00010000 800b0001 000c0004 01e13380 80010005 80020002 80030001 80040002 03000024 01010000 800b0001 000c0004 01e13380 80010005 80020001 80030001 80040002 03000024 02010000 800b0001 000c0004 01e13380 80010001 80020001 80030001 80040002 00000024 03010000 800b0001 000c0004 01e13380 80010001 80020002 80030001 80040002 2003-09-19 16:19:02: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:02: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-09-19 16:19:02: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=152 2003-09-19 16:19:02: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:02: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:19:02: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:19:02: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:19:02: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:19:02: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=36 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Duration, flag=0x0000, lorv=4 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2003-09-19 16:19:02: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2003-09-19 16:19:02: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2003-09-19 16:19:02: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1311:get_transform(): transform #1 len=36 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Duration, flag=0x0000, lorv=4 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2003-09-19 16:19:02: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=MD5 2003-09-19 16:19:02: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(md5) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2003-09-19 16:19:02: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1311:get_transform(): transform #2 len=36 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Duration, flag=0x0000, lorv=4 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=DES-CBC 2003-09-19 16:19:02: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(des) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=MD5 2003-09-19 16:19:02: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(md5) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2003-09-19 16:19:02: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1311:get_transform(): transform #3 len=36 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Duration, flag=0x0000, lorv=4 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=DES-CBC 2003-09-19 16:19:02: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(des) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2003-09-19 16:19:02: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2003-09-19 16:19:02: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-09-19 16:19:02: DEBUG: proposal.c:892:print_proppair0(): 0x80a83b0: next=0x0 tnext=0x80a83c0 2003-09-19 16:19:02: DEBUG: proposal.c:892:print_proppair0(): 0x80a83c0: next=0x0 tnext=0x80a83d0 2003-09-19 16:19:02: DEBUG: proposal.c:892:print_proppair0(): 0x80a83d0: next=0x0 tnext=0x80a83e0 2003-09-19 16:19:02: DEBUG: proposal.c:892:print_proppair0(): 0x80a83e0: next=0x0 tnext=0x0 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 4 transform 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:322:get_ph1approvalx(): prop#=0, prot-id=ISAKMP, spi-size=0, #trns=4 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:327:get_ph1approvalx(): trns#=0, trns-id=IKE 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Life Duration, flag=0x0000, lorv=4 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:338:get_ph1approvalx(): Compared: DB:Peer 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:339:get_ph1approvalx(): (lifetime = 28800:31536000) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:341:get_ph1approvalx(): (lifebyte = 0:0) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:343:get_ph1approvalx(): enctype = 3DES-CBC:3DES-CBC 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:348:get_ph1approvalx(): (encklen = 0:0) 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:350:get_ph1approvalx(): hashtype = SHA:SHA 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:355:get_ph1approvalx(): authmethod = pre-shared key:pre-shared key 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:360:get_ph1approvalx(): dh_group = 1024-bit MODP group:1024-bit MODP group 2003-09-19 16:19:02: DEBUG: ipsec_doi.c:248:get_ph1approval(): an acceptable proposal found. 2003-09-19 16:19:02: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:02: DEBUG: isakmp.c:1996:isakmp_newcookie(): new cookie: 397e4308c83f6d63 2003-09-19 16:19:02: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 52, next type 13 2003-09-19 16:19:02: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 0 2003-09-19 16:19:02: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:02: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-09-19 16:19:02: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-09-19 16:19:02: DEBUG: sockmisc.c:425:sendfromto(): send packet to 65.220.69.71[500] 2003-09-19 16:19:02: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 104 bytes message will be sent to 63.202.92.133[500] 2003-09-19 16:19:02: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 01100200 00000000 00000068 0d000038 00000001 00000001 0000002c 00010001 00000024 00010000 800b0001 000c0004 01e13380 80010005 80020002 80030001 80040002 00000014 7003cbc1 097dbe9c 2600ba69 83bc8b35 2003-09-19 16:19:02: DEBUG: isakmp.c:1449:isakmp_ph1resend(): resend phase1 packet e4eb5e590b9b7f8b:397e4308c83f6d63 2003-09-19 16:19:03: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:19:03: DEBUG: isakmp.c:222:isakmp_handler(): 180 bytes message received from 65.220.69.71[500] 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 04100200 00000000 000000b4 0a000084 5996b265 f71edbf4 d5bb6235 f21925bf 4732bfb8 962f6faf 77337cfe 21095f74 f9b0df42 ccd11eaf c44a779e c530a887 b5764d89 f304bf5a 0668bf0b 2c29cccb bfac9097 1d88bca5 f86c77cf f2abb68c 397e7091 ccea7214 0b0e9d09 0f785456 ef1ddd9b 89513560 f085515b ded58335 29f8a4da a35d2340 4ac9010c 67e7e268 00000014 d8233877 95afd550 f75ffabb 5aac3e86 2003-09-19 16:19:03: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:03: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:03: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=4(ke) 2003-09-19 16:19:03: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=10(nonce) 2003-09-19 16:19:03: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:03: DEBUG: isakmp.c:622:ph1_main(): === 2003-09-19 16:19:03: DEBUG: oakley.c:256:oakley_dh_generate(): compute DH's private. 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 58ea2cdc 2e81854d d9ee0a25 0ed9c36d 689155c3 b6ba01ce b811ea5c 97d50f56 e03eaca3 75dd8435 6e2a2c93 504797ad eb3c7a86 d34d04b9 5e06cfeb 09ea822b 55a00bdd 6df0d869 15488f14 d860572c 22a247ba 03666697 98726a6b e7eb4ef4 653e6de4 54dc5a1c e65cc95d 0d749293 5ce84945 77be66a4 b899b147 52071536 2003-09-19 16:19:03: DEBUG: oakley.c:258:oakley_dh_generate(): compute DH's public. 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 56a6fffd 7d03a89f 72a95b0c 4e5d317c 9bafa982 f6b862ed e2421631 36de16f2 f22d92e9 b9b1bc0c 6ec05bb7 148fa200 3bfc0b39 9bd282cf 011424d2 9ee3e4b7 bf2ec5c8 30dadf8e d88fbe0b 3bcc04c2 aad50a3b 6a627ec5 50f10204 b080af7f 6a21dcd2 3e2d3864 1afad816 82c1d899 775ade20 d8ab07e3 6c661363 0c89797c 2003-09-19 16:19:03: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 128, next type 10 2003-09-19 16:19:03: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 13 2003-09-19 16:19:03: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 0 2003-09-19 16:19:03: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:03: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-09-19 16:19:03: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-09-19 16:19:03: DEBUG: sockmisc.c:425:sendfromto(): send packet to 65.220.69.71[500] 2003-09-19 16:19:03: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 200 bytes message will be sent to 63.202.92.133[500] 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 04100200 00000000 000000c8 0a000084 56a6fffd 7d03a89f 72a95b0c 4e5d317c 9bafa982 f6b862ed e2421631 36de16f2 f22d92e9 b9b1bc0c 6ec05bb7 148fa200 3bfc0b39 9bd282cf 011424d2 9ee3e4b7 bf2ec5c8 30dadf8e d88fbe0b 3bcc04c2 aad50a3b 6a627ec5 50f10204 b080af7f 6a21dcd2 3e2d3864 1afad816 82c1d899 775ade20 d8ab07e3 6c661363 0c89797c 0d000014 c084fce1 7161ae23 6cfb8686 e3274669 00000014 7003cbc1 097dbe9c 2600ba69 83bc8b35 2003-09-19 16:19:03: DEBUG: isakmp.c:1449:isakmp_ph1resend(): resend phase1 packet e4eb5e590b9b7f8b:397e4308c83f6d63 2003-09-19 16:19:03: DEBUG: oakley.c:210:oakley_dh_compute(): compute DH's shared. 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): a34ff2c9 7ffb2971 a766e37e 80921fc0 8505b85c 0cebe288 8061a87c d769b84e 2aaa5fe3 10721e74 b00b07ee b66fb4d7 f5aaa75e 65e17570 90c2113c 1edced97 63db3868 bbab0220 470ab961 0ad38c3f d4178e12 7d67fdfb 71b79a01 602309d4 2bb9cd99 5b51c58e 8e20caa5 ccbaefd0 0128c53a 15068173 2de571af 1d484c73 2003-09-19 16:19:03: DEBUG: oakley.c:2063:oakley_skeyid(): the psk found. 2003-09-19 16:19:03: DEBUG2: oakley.c:2065:oakley_skeyid(): psk: 2003-09-19 16:19:03: DEBUG2: plog.c:193:plogdump(): 6d656b6d 69746173 6469676f 6174 2003-09-19 16:19:03: DEBUG: oakley.c:2078:oakley_skeyid(): nonce 1: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): d8233877 95afd550 f75ffabb 5aac3e86 2003-09-19 16:19:03: DEBUG: oakley.c:2084:oakley_skeyid(): nonce 2: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): c084fce1 7161ae23 6cfb8686 e3274669 2003-09-19 16:19:03: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:03: DEBUG: oakley.c:2137:oakley_skeyid(): SKEYID computed: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 2ba9a941 2b415dac 6eebb1fd d599f09b 3e0b8d00 2003-09-19 16:19:03: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:03: DEBUG: oakley.c:2194:oakley_skeyid_dae(): SKEYID_d computed: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): f22feaa6 98c9fdce 1944f5fd ffca24f0 ff93a958 2003-09-19 16:19:03: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:03: DEBUG: oakley.c:2223:oakley_skeyid_dae(): SKEYID_a computed: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): dec4b3bc 36f6e4e8 c41e571a 55f154aa 477f3b63 2003-09-19 16:19:03: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:03: DEBUG: oakley.c:2252:oakley_skeyid_dae(): SKEYID_e computed: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 8c7102f3 d800596c 10e44370 14bf37c2 b742ecb4 2003-09-19 16:19:03: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:03: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-09-19 16:19:03: DEBUG: oakley.c:2321:oakley_compute_enckey(): len(SKEYID_e) < len(Ka) (20 < 24), generating long key (Ka = K1 | K2 | ...) 2003-09-19 16:19:03: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:03: DEBUG: oakley.c:2346:oakley_compute_enckey(): compute intermediate encryption key K1 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 00 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 2003-09-19 16:19:03: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:03: DEBUG: oakley.c:2346:oakley_compute_enckey(): compute intermediate encryption key K2 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 50f7b8c9 4b3b3050 ce479f2a 2edafdee f8d98743 2003-09-19 16:19:03: DEBUG: oakley.c:2394:oakley_compute_enckey(): final encryption key computed: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 50f7b8c9 2003-09-19 16:19:03: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-09-19 16:19:03: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:03: DEBUG: oakley.c:2505:oakley_newiv(): IV computed: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 39bd0f21 f92828b4 2003-09-19 16:19:03: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:19:03: DEBUG: isakmp.c:222:isakmp_handler(): 68 bytes message received from 65.220.69.71[500] 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 05100201 00000000 00000044 d3e5726f c04cab76 71234f19 4eac4f7c 694401df 931c00d1 5253850f 590bb58a ffa99382 18f08210 2003-09-19 16:19:03: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:03: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-09-19 16:19:03: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:03: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): ffa99382 18f08210 2003-09-19 16:19:03: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:03: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 50f7b8c9 2003-09-19 16:19:03: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): ffa99382 18f08210 2003-09-19 16:19:03: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 0800000c 01000000 41dc4547 00000018 9ff34efc 43f72b34 89426588 0390c4a8 cea799d7 00000000 2003-09-19 16:19:03: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=1 2003-09-19 16:19:03: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-09-19 16:19:03: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 05100201 00000000 00000044 0800000c 01000000 41dc4547 00000018 9ff34efc 43f72b34 89426588 0390c4a8 cea799d7 00000000 2003-09-19 16:19:03: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:03: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:03: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-09-19 16:19:03: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-09-19 16:19:03: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:03: DEBUG: oakley.c:1162:oakley_validate_auth(): HASH received:2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 9ff34efc 43f72b34 89426588 0390c4a8 cea799d7 2003-09-19 16:19:03: DEBUG: oakley.c:867:oakley_ph1hash_common(): HASH with: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 5996b265 f71edbf4 d5bb6235 f21925bf 4732bfb8 962f6faf 77337cfe 21095f74 f9b0df42 ccd11eaf c44a779e c530a887 b5764d89 f304bf5a 0668bf0b 2c29cccb bfac9097 1d88bca5 f86c77cf f2abb68c 397e7091 ccea7214 0b0e9d09 0f785456 ef1ddd9b 89513560 f085515b ded58335 29f8a4da a35d2340 4ac9010c 67e7e268 56a6fffd 7d03a89f 72a95b0c 4e5d317c 9bafa982 f6b862ed e2421631 36de16f2 f22d92e9 b9b1bc0c 6ec05bb7 148fa200 3bfc0b39 9bd282cf 011424d2 9ee3e4b7 bf2ec5c8 30dadf8e d88fbe0b 3bcc04c2 aad50a3b 6a627ec5 50f10204 b080af7f 6a21dcd2 3e2d3864 1afad816 82c1d899 775ade20 d8ab07e3 6c661363 0c89797c e4eb5e59 0b9b7f8b 397e4308 c83f6d63 00000001 00000001 00000098 00010004 03000024 00010000 800b0001 000c0004 01e13380 80010005 80020002 80030001 80040002 03000024 01010000 800b0001 000c0004 01e13380 80010005 80020001 80030001 80040002 03000024 02010000 800b0001 000c0004 01e13380 80010001 80020001 80030001 80040002 00000024 03010000 800b0001 000c0004 01e13380 80010001 80020002 80030001 80040002 01000000 41dc4547 2003-09-19 16:19:03: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:03: DEBUG: oakley.c:877:oakley_ph1hash_common(): HASH computed: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 9ff34efc 43f72b34 89426588 0390c4a8 cea799d7 2003-09-19 16:19:03: DEBUG: oakley.c:1193:oakley_validate_auth(): HASH for PSK validated. 2003-09-19 16:19:03: DEBUG: isakmp_ident.c:1250:ident_r3recv(): peer's ID 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 01000000 41dc4547 2003-09-19 16:19:03: DEBUG: isakmp.c:622:ph1_main(): === 2003-09-19 16:19:03: DEBUG: ipsec_doi.c:3185:ipsecdoi_setid1(): use ID type of IPv4_address 2003-09-19 16:19:03: DEBUG: isakmp_ident.c:1327:ident_r3send(): generate HASH_R 2003-09-19 16:19:03: DEBUG: oakley.c:867:oakley_ph1hash_common(): HASH with: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 56a6fffd 7d03a89f 72a95b0c 4e5d317c 9bafa982 f6b862ed e2421631 36de16f2 f22d92e9 b9b1bc0c 6ec05bb7 148fa200 3bfc0b39 9bd282cf 011424d2 9ee3e4b7 bf2ec5c8 30dadf8e d88fbe0b 3bcc04c2 aad50a3b 6a627ec5 50f10204 b080af7f 6a21dcd2 3e2d3864 1afad816 82c1d899 775ade20 d8ab07e3 6c661363 0c89797c 5996b265 f71edbf4 d5bb6235 f21925bf 4732bfb8 962f6faf 77337cfe 21095f74 f9b0df42 ccd11eaf c44a779e c530a887 b5764d89 f304bf5a 0668bf0b 2c29cccb bfac9097 1d88bca5 f86c77cf f2abb68c 397e7091 ccea7214 0b0e9d09 0f785456 ef1ddd9b 89513560 f085515b ded58335 29f8a4da a35d2340 4ac9010c 67e7e268 397e4308 c83f6d63 e4eb5e59 0b9b7f8b 00000001 00000001 00000098 00010004 03000024 00010000 800b0001 000c0004 01e13380 80010005 80020002 80030001 80040002 03000024 01010000 800b0001 000c0004 01e13380 80010005 80020001 80030001 80040002 03000024 02010000 800b0001 000c0004 01e13380 80010001 80020001 80030001 80040002 00000024 03010000 800b0001 000c0004 01e13380 80010001 80020002 80030001 80040002 011101f4 3fca5c85 2003-09-19 16:19:03: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:03: DEBUG: oakley.c:877:oakley_ph1hash_common(): HASH computed: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 6a937505 50ab78f3 4475ce82 10723bb9 ec6940db 2003-09-19 16:19:03: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 8, next type 8 2003-09-19 16:19:03: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 20, next type 0 2003-09-19 16:19:03: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:03: DEBUG: oakley.c:2748:oakley_do_encrypt(): begin encryption. 2003-09-19 16:19:03: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:03: DEBUG: oakley.c:2764:oakley_do_encrypt(): pad length = 4 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 0800000c 011101f4 3fca5c85 00000018 6a937505 50ab78f3 4475ce82 10723bb9 ec6940db 4da4b203 2003-09-19 16:19:03: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:03: DEBUG: oakley.c:2799:oakley_do_encrypt(): with key: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 50f7b8c9 2003-09-19 16:19:03: DEBUG: oakley.c:2807:oakley_do_encrypt(): encrypted payload by IV: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): d6a6198b 29d70c6f 2003-09-19 16:19:03: DEBUG: oakley.c:2814:oakley_do_encrypt(): save IV for next: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): d6a6198b 29d70c6f 2003-09-19 16:19:03: DEBUG: oakley.c:2831:oakley_do_encrypt(): encrypted. 2003-09-19 16:19:03: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-09-19 16:19:03: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-09-19 16:19:03: DEBUG: sockmisc.c:425:sendfromto(): send packet to 65.220.69.71[500] 2003-09-19 16:19:03: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 68 bytes message will be sent to 63.202.92.133[500] 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 05100201 00000000 00000044 044b2a7f 83578d62 469020f7 a0fd798f 7a95b199 f8c615a1 fbbf0edd 688d90ea d6a6198b 29d70c6f 2003-09-19 16:19:03: DEBUG: oakley.c:2549:oakley_newiv2(): compute IV for phase2 2003-09-19 16:19:03: DEBUG: oakley.c:2550:oakley_newiv2(): phase1 last IV: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): d6a6198b 29d70c6f ca48c81a 2003-09-19 16:19:03: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-09-19 16:19:03: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:03: DEBUG: oakley.c:2582:oakley_newiv2(): phase2 IV computed: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): e3ef0b9d 300963aa 2003-09-19 16:19:03: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): ca48c81a 0000001c 00000001 01106002 e4eb5e59 0b9b7f8b 397e4308 c83f6d63 2003-09-19 16:19:03: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:03: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 36ab44d3 2214a373 d9d68b4c b5c4451d 83f73c36 2003-09-19 16:19:03: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:03: DEBUG: oakley.c:2748:oakley_do_encrypt(): begin encryption. 2003-09-19 16:19:03: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:03: DEBUG: oakley.c:2764:oakley_do_encrypt(): pad length = 4 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 0b000018 36ab44d3 2214a373 d9d68b4c b5c4451d 83f73c36 0000001c 00000001 01106002 e4eb5e59 0b9b7f8b 397e4308 c83f6d63 c3b63e03 2003-09-19 16:19:03: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:03: DEBUG: oakley.c:2799:oakley_do_encrypt(): with key: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 50f7b8c9 2003-09-19 16:19:03: DEBUG: oakley.c:2807:oakley_do_encrypt(): encrypted payload by IV: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 262646df 07ce3a53 2003-09-19 16:19:03: DEBUG: oakley.c:2814:oakley_do_encrypt(): save IV for next: 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): 262646df 07ce3a53 2003-09-19 16:19:03: DEBUG: oakley.c:2831:oakley_do_encrypt(): encrypted. 2003-09-19 16:19:03: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-09-19 16:19:03: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-09-19 16:19:03: DEBUG: sockmisc.c:425:sendfromto(): send packet to 65.220.69.71[500] 2003-09-19 16:19:03: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 84 bytes message will be sent to 63.202.92.133[500] 2003-09-19 16:19:03: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08100501 ca48c81a 00000054 21a315d5 a6057e07 0f5b86e3 e36f07aa e744dbf4 0cc2310f e99a2b3f 8f7c50ed 8c928b2f 004bffbb 817a6346 9b37c106 262646df 07ce3a53 2003-09-19 16:19:03: DEBUG: isakmp_inf.c:634:isakmp_info_send_common(): sendto Information notify. 2003-09-19 16:19:03: INFO: isakmp.c:2412:log_ph1established(): ISAKMP-SA established 63.202.92.133[500]-65.220.69.71[500] spi:e4eb5e590b9b7f8b:397e4308c83f6d63 2003-09-19 16:19:03: DEBUG: isakmp.c:669:ph1_main(): === 2003-09-19 16:19:06: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:19:06: DEBUG: isakmp.c:222:isakmp_handler(): 372 bytes message received from 65.220.69.71[500] 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08102001 4983110d 00000174 7a1cee30 16843150 ecb61eec 1c22ef5a 0825caf3 1408ff10 40f19655 53d8d004 814d04bf f8f934b6 be6249a8 a65af487 827f435e a99391b3 90265da6 9ab97b8d 0a4beba7 334d46f1 05553e04 8c8ff313 184e68c7 af50bc4a be5e6528 f1797ea8 d0ffb503 436a01d8 a27b139c b585b6ea 448833a6 ebe3ea4d ca8f16e9 262cd069 f26fef56 85c87497 edfd8fac eefd99e8 a969d0b9 1ae8c5aa d00d4514 5cc540f8 c58fa227 78230291 02a851c7 9df21214 a6e94a67 24e6d5c9 4213cee5 d474d996 ad84d62e e288c7f6 26ad7eca 27e7cc06 73e2fff7 4e6f95d6 f48d3ef1 681945b7 191b71f1 b8af5b35 e08cd972 06289e2b ca3a5a56 1197c91a d980d494 9533963e 1e536f74 cb3f5601 c736b860 427c66f4 9fb8bf96 e06cae0c 7968bb2f b49d3a9a 78c56101 6ba90c3a cb5aebe3 8778395d af2e3a9a bc58700f 72cf20a2 2b2686b3 1358c858 394b93ed 635f0301 67639a09 e2f4a1f3 ec3a1ea5 2003-09-19 16:19:06: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:06: DEBUG: oakley.c:2549:oakley_newiv2(): compute IV for phase2 2003-09-19 16:19:06: DEBUG: oakley.c:2550:oakley_newiv2(): phase1 last IV: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): d6a6198b 29d70c6f 4983110d 2003-09-19 16:19:06: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-09-19 16:19:06: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: oakley.c:2582:oakley_newiv2(): phase2 IV computed: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 159218c0 b7198019 2003-09-19 16:19:06: DEBUG: isakmp.c:1044:isakmp_ph2begin_r(): === 2003-09-19 16:19:06: INFO: isakmp.c:1049:isakmp_ph2begin_r(): respond new phase 2 negotiation: 63.202.92.133[0]<=>65.220.69.71[0] 2003-09-19 16:19:06: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-09-19 16:19:06: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): e2f4a1f3 ec3a1ea5 2003-09-19 16:19:06: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 50f7b8c9 2003-09-19 16:19:06: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): e2f4a1f3 ec3a1ea5 2003-09-19 16:19:06: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 01000018 6919835c f3dbd86e cd4f281b e4154a37 d378753e 0a000088 00000001 00000001 0000007c 00030404 f1d53579 0300001c 00030000 80030002 80040001 80010001 800200b4 80050002 0300001c 01030000 80030002 80040001 80010001 800200b4 80050002 0300001c 02030000 80030002 80040001 80010001 800200b4 80050002 0000001c 03030000 80030002 80040001 80010001 800200b4 80050002 04000014 5da1ffa6 6b845c55 77a10d94 f3482ee8 05000084 7d4feed5 f79aa718 6fc4f178 b8256f15 7184a489 bc2e333b 10933571 fa0948c9 0b9339c4 f014d38b 0e7f842e ffb94bfc 8cb49c70 0733b48a 23b8e76b abb0aefb eb5a6091 4ec0d3fb bb1dc2bd 3bd249ce ad105cf2 6f77c1b2 313654df d3888b80 d5e73c37 133cf121 06c4a52e e3c2b3c0 ce2209d5 1138212b 6d179fca ccb7a59e 05000010 04000000 c0a865fd ffffffff 00000010 04000000 0a080807 ffffffff 2003-09-19 16:19:06: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=0 2003-09-19 16:19:06: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-09-19 16:19:06: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08102001 4983110d 00000174 01000018 6919835c f3dbd86e cd4f281b e4154a37 d378753e 0a000088 00000001 00000001 0000007c 00030404 f1d53579 0300001c 00030000 80030002 80040001 80010001 800200b4 80050002 0300001c 01030000 80030002 80040001 80010001 800200b4 80050002 0300001c 02030000 80030002 80040001 80010001 800200b4 80050002 0000001c 03030000 80030002 80040001 80010001 800200b4 80050002 04000014 5da1ffa6 6b845c55 77a10d94 f3482ee8 05000084 7d4feed5 f79aa718 6fc4f178 b8256f15 7184a489 bc2e333b 10933571 fa0948c9 0b9339c4 f014d38b 0e7f842e ffb94bfc 8cb49c70 0733b48a 23b8e76b abb0aefb eb5a6091 4ec0d3fb bb1dc2bd 3bd249ce ad105cf2 6f77c1b2 313654df d3888b80 d5e73c37 133cf121 06c4a52e e3c2b3c0 ce2209d5 1138212b 6d179fca ccb7a59e 05000010 04000000 c0a865fd ffffffff 00000010 04000000 0a080807 ffffffff 2003-09-19 16:19:06: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:06: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=1(sa) 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=10(nonce) 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=4(ke) 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-09-19 16:19:06: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:1006:quick_r1recv(): received IDci2:2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 04000000 c0a865fd ffffffff 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:1010:quick_r1recv(): received IDcr2:2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 04000000 0a080807 ffffffff 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:1025:quick_r1recv(): HASH(1) validate:2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 6919835c f3dbd86e cd4f281b e4154a37 d378753e 2003-09-19 16:19:06: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 4983110d 0a000088 00000001 00000001 0000007c 00030404 f1d53579 0300001c 00030000 80030002 80040001 80010001 800200b4 80050002 0300001c 01030000 80030002 80040001 80010001 800200b4 80050002 0300001c 02030000 80030002 80040001 80010001 800200b4 80050002 0000001c 03030000 80030002 80040001 80010001 800200b4 80050002 04000014 5da1ffa6 6b845c55 77a10d94 f3482ee8 05000084 7d4feed5 f79aa718 6fc4f178 b8256f15 7184a489 bc2e333b 10933571 fa0948c9 0b9339c4 f014d38b 0e7f842e ffb94bfc 8cb49c70 0733b48a 23b8e76b abb0aefb eb5a6091 4ec0d3fb bb1dc2bd 3bd249ce ad105cf2 6f77c1b2 313654df d3888b80 d5e73c37 133cf121 06c4a52e e3c2b3c0 ce2209d5 1138212b 6d179fca ccb7a59e 05000010 04000000 c0a865fd ffffffff 00000010 04000000 0a080807 ffffffff 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 6919835c f3dbd86e cd4f281b e4154a37 d378753e 2003-09-19 16:19:06: DEBUG: sainfo.c:100:getsainfo(): anonymous sainfo selected. 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:1817:get_sainfo_r(): get sa info: anonymous 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:1995:get_proposal_r(): get a src address from ID payload 192.168.101.253[0] prefixlen=32 ul_proto=255 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:2000:get_proposal_r(): get dst address from ID payload 10.8.8.7[0] prefixlen=32 ul_proto=255 2003-09-19 16:19:06: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd36c: 192.168.101.253/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-09-19 16:19:06: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80a2c08: 192.168.101.253/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-09-19 16:19:06: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfd36c masked with /32: 192.168.101.253[0] 2003-09-19 16:19:06: DEBUG: policy.c:246:cmpspidxwild(): 0x80a2c08 masked with /32: 192.168.101.253[0] 2003-09-19 16:19:06: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfd36c masked with /32: 10.8.8.7[0] 2003-09-19 16:19:06: DEBUG: policy.c:262:cmpspidxwild(): 0x80a2c08 masked with /32: 10.8.8.7[0] 2003-09-19 16:19:06: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd36c: 10.8.8.7/32[0] 192.168.101.253/32[0] proto=any dir=out 2003-09-19 16:19:06: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80a2c08: 192.168.101.253/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-09-19 16:19:06: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd36c: 10.8.8.7/32[0] 192.168.101.253/32[0] proto=any dir=out 2003-09-19 16:19:06: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80ae008: 10.8.8.7/32[0] 192.168.101.253/32[0] proto=any dir=out 2003-09-19 16:19:06: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfd36c masked with /32: 10.8.8.7[0] 2003-09-19 16:19:06: DEBUG: policy.c:246:cmpspidxwild(): 0x80ae008 masked with /32: 10.8.8.7[0] 2003-09-19 16:19:06: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfd36c masked with /32: 192.168.101.253[0] 2003-09-19 16:19:06: DEBUG: policy.c:262:cmpspidxwild(): 0x80ae008 masked with /32: 192.168.101.253[0] 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:2056:get_proposal_r(): suitable SP found:10.8.8.7/32[0] 192.168.101.253/32[0] proto=any dir=out 2003-09-19 16:19:06: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-09-19 16:19:06: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=132 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 00000001 00000001 0000007c 00030404 f1d53579 0300001c 00030000 80030002 80040001 80010001 800200b4 80050002 0300001c 01030000 80030002 80040001 80010001 800200b4 80050002 0300001c 02030000 80030002 80040001 80010001 800200b4 80050002 0000001c 03030000 80030002 80040001 80010001 800200b4 80050002 2003-09-19 16:19:06: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-09-19 16:19:06: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=124 2003-09-19 16:19:06: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:19:06: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=28 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1311:get_transform(): transform #1 len=28 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1311:get_transform(): transform #2 len=28 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1311:get_transform(): transform #3 len=28 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-09-19 16:19:06: DEBUG: proposal.c:892:print_proppair0(): 0x80a8770: next=0x0 tnext=0x80a8780 2003-09-19 16:19:06: DEBUG: proposal.c:892:print_proppair0(): 0x80a8780: next=0x0 tnext=0x80a8790 2003-09-19 16:19:06: DEBUG: proposal.c:892:print_proppair0(): 0x80a8790: next=0x0 tnext=0x80a87a0 2003-09-19 16:19:06: DEBUG: proposal.c:892:print_proppair0(): 0x80a87a0: next=0x0 tnext=0x0 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 4 transform 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:948:get_ph2approval(): begin compare proposals. 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:954:get_ph2approval(): pair[0]: 0x80a8770 2003-09-19 16:19:06: DEBUG: proposal.c:892:print_proppair0(): 0x80a8770: next=0x0 tnext=0x80a8780 2003-09-19 16:19:06: DEBUG: proposal.c:892:print_proppair0(): 0x80a8780: next=0x0 tnext=0x80a8790 2003-09-19 16:19:06: DEBUG: proposal.c:892:print_proppair0(): 0x80a8790: next=0x0 tnext=0x80a87a0 2003-09-19 16:19:06: DEBUG: proposal.c:892:print_proppair0(): 0x80a87a0: next=0x0 tnext=0x0 2003-09-19 16:19:06: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=4 trns#=0 trns-id=3DES 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=4 trns#=1 trns-id=3DES 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=4 trns#=2 trns-id=3DES 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=4 trns#=3 trns-id=3DES 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:990:get_ph2approvalx(): peer's single bundle: 2003-09-19 16:19:06: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=f1d53579 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-09-19 16:19:06: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:19:06: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:19:06: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:19:06: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:993:get_ph2approvalx(): my single bundle: 2003-09-19 16:19:06: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-09-19 16:19:06: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1012:get_ph2approvalx(): matched 2003-09-19 16:19:06: DEBUG: isakmp.c:1078:isakmp_ph2begin_r(): === 2003-09-19 16:19:06: DEBUG: pfkey.c:792:pk_sendgetspi(): call pfkey_send_getspi 2003-09-19 16:19:06: DEBUG: pfkey.c:805:pk_sendgetspi(): pfkey GETSPI sent: ESP/Tunnel 65.220.69.71->63.202.92.133 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:1143:quick_r1prep(): pfkey getspi sent. 2003-09-19 16:19:06: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey GETSPI message 2003-09-19 16:19:06: DEBUG2: plog.c:193:plogdump(): 02010003 0a000000 f67f210c df170000 02000100 053a58b4 00000000 00000000 03000500 ff200000 10020000 41dc4547 00000000 00000000 03000600 ff200000 10020000 3fca5c85 00000000 00000000 2003-09-19 16:19:06: DEBUG: pfkey.c:876:pk_recvgetspi(): pfkey GETSPI succeeded: ESP/Tunnel 65.220.69.71->63.202.92.133 spi=87709876(0x53a58b4) 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=48 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000028 00030401 00000000 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 2003-09-19 16:19:06: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-09-19 16:19:06: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=40 2003-09-19 16:19:06: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:19:06: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=28 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-09-19 16:19:06: DEBUG: proposal.c:892:print_proppair0(): 0x80a86b0: next=0x0 tnext=0x0 2003-09-19 16:19:06: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 1 transform 2003-09-19 16:19:06: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:19:06: DEBUG: oakley.c:256:oakley_dh_generate(): compute DH's private. 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 50963ed2 0beb5a7d 7d519a25 618b5548 02f92581 16fac443 b8918fc0 e4203f01 3daa45b1 e3b92226 a854395c 5bdb487d a40bfc24 dfca5111 018e79f1 aa920f3c 8b3d47e9 9a1e2a2e 2290dbf8 813cfa03 dc199f82 92a09692 9a8d772e eb25088b a0eb185d 5becd9af 892a3acf e80a3ca4 ec0949e0 adad2d14 c5cab63a c4a9a649 2003-09-19 16:19:06: DEBUG: oakley.c:258:oakley_dh_generate(): compute DH's public. 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 71227c91 21169805 78a8802a 194f322a d901745a 66cb2fc0 49a13a7f 68c1ea4b 3c3f23ae e3ab9c26 cee098d5 4b55ed7e 53eecbe2 1f3c8e19 c9d8cd8a d13ab282 89edc892 c7c79dfd 4a8a59e2 05064f26 06500a28 02d272a4 348907cf d484f284 82b929c0 0355e909 031721f1 e0a29690 f36f252a fd282d55 9b1ed34a f737e857 2003-09-19 16:19:06: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 48, next type 10 2003-09-19 16:19:06: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 4 2003-09-19 16:19:06: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 128, next type 5 2003-09-19 16:19:06: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 12, next type 5 2003-09-19 16:19:06: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 12, next type 0 2003-09-19 16:19:06: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 4983110d 5da1ffa6 6b845c55 77a10d94 f3482ee8 0a000034 00000001 00000001 00000028 00030401 053a58b4 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 9ff19689 470ecad2 fbb54700 d8a15e4b 05000084 71227c91 21169805 78a8802a 194f322a d901745a 66cb2fc0 49a13a7f 68c1ea4b 3c3f23ae e3ab9c26 cee098d5 4b55ed7e 53eecbe2 1f3c8e19 c9d8cd8a d13ab282 89edc892 c7c79dfd 4a8a59e2 05064f26 06500a28 02d272a4 348907cf d484f284 82b929c0 0355e909 031721f1 e0a29690 f36f252a fd282d55 9b1ed34a f737e857 05000010 04000000 c0a865fd ffffffff 00000010 04000000 0a080807 ffffffff 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): d4c1998f 52b99487 9a9be25d 4273a33f 6dca575c 2003-09-19 16:19:06: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 20, next type 1 2003-09-19 16:19:06: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:06: DEBUG: oakley.c:2748:oakley_do_encrypt(): begin encryption. 2003-09-19 16:19:06: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: oakley.c:2764:oakley_do_encrypt(): pad length = 4 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 01000018 d4c1998f 52b99487 9a9be25d 4273a33f 6dca575c 0a000034 00000001 00000001 00000028 00030401 053a58b4 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 9ff19689 470ecad2 fbb54700 d8a15e4b 05000084 71227c91 21169805 78a8802a 194f322a d901745a 66cb2fc0 49a13a7f 68c1ea4b 3c3f23ae e3ab9c26 cee098d5 4b55ed7e 53eecbe2 1f3c8e19 c9d8cd8a d13ab282 89edc892 c7c79dfd 4a8a59e2 05064f26 06500a28 02d272a4 348907cf d484f284 82b929c0 0355e909 031721f1 e0a29690 f36f252a fd282d55 9b1ed34a f737e857 05000010 04000000 c0a865fd ffffffff 00000010 04000000 0a080807 ffffffff 75297b03 2003-09-19 16:19:06: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: oakley.c:2799:oakley_do_encrypt(): with key: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 50f7b8c9 2003-09-19 16:19:06: DEBUG: oakley.c:2807:oakley_do_encrypt(): encrypted payload by IV: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 797355cf 74d78880 2003-09-19 16:19:06: DEBUG: oakley.c:2814:oakley_do_encrypt(): save IV for next: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 797355cf 74d78880 2003-09-19 16:19:06: DEBUG: oakley.c:2831:oakley_do_encrypt(): encrypted. 2003-09-19 16:19:06: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-09-19 16:19:06: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-09-19 16:19:06: DEBUG: sockmisc.c:425:sendfromto(): send packet to 65.220.69.71[500] 2003-09-19 16:19:06: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 292 bytes message will be sent to 63.202.92.133[500] 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08102001 4983110d 00000124 57e38d66 44bce3d3 7ceac29f af8de60a c28ea113 df5c8f21 23d9abd7 218e07c6 3bd71a23 40f72711 6b489ac8 fa73b651 79429068 81acdc98 675f67eb 0b1ac8c2 9b1f1d34 a11895b0 3338fab8 01f82346 7d1b1603 e5971f85 60e64161 6ce0ceab 86331994 5e8ff7b6 b022cca3 f59bc468 34f6eb69 14396213 90c960a2 708365a2 020c6ff7 a61c8a6c bedf1fbf abbbc50b 7a469258 f39772e6 5be27981 cc657587 68727e6a c690a80c cdf89f09 a8a41d15 cf83ddfc 68c21b3d 6c961cdd db8e2383 d4e12ac8 a33983b5 e09ab6f6 97eeeb59 31538eef 390f6b9d e9b19365 7a237752 260bad76 336a4679 972531c8 c830a042 ec6427c3 67077653 5221355e 669576c4 797355cf 74d78880 2003-09-19 16:19:06: DEBUG: isakmp.c:1487:isakmp_ph2resend(): resend phase2 packet e4eb5e590b9b7f8b:397e4308c83f6d63:00004983 2003-09-19 16:19:06: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:19:06: DEBUG: isakmp.c:222:isakmp_handler(): 52 bytes message received from 65.220.69.71[500] 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08102001 4983110d 00000034 2a9c4207 29c858f5 a9dc0699 378ae1e4 2643177a 9c025a87 2003-09-19 16:19:06: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:06: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-09-19 16:19:06: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 2643177a 9c025a87 2003-09-19 16:19:06: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 50f7b8c9 2003-09-19 16:19:06: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 2643177a 9c025a87 2003-09-19 16:19:06: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 00000018 71c0472b dc56e850 638d9ec1 888f1fe9 91a6cccd 2003-09-19 16:19:06: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=206 2003-09-19 16:19:06: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-09-19 16:19:06: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08102001 4983110d 00000034 00000018 71c0472b dc56e850 638d9ec1 888f1fe9 91a6cccd 2003-09-19 16:19:06: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:19:06: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:19:06: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-09-19 16:19:06: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:1430:quick_r3recv(): HASH(3) validate:2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 71c0472b dc56e850 638d9ec1 888f1fe9 91a6cccd 2003-09-19 16:19:06: DEBUG: oakley.c:692:oakley_compute_hash3(): HASH with: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 00498311 0d5da1ff a66b845c 5577a10d 94f3482e e89ff196 89470eca d2fbb547 00d8a15e 4b 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: oakley.c:702:oakley_compute_hash3(): HASH computed: 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 71c0472b dc56e850 638d9ec1 888f1fe9 91a6cccd 2003-09-19 16:19:06: DEBUG: isakmp.c:736:quick_main(): === 2003-09-19 16:19:06: DEBUG: oakley.c:210:oakley_dh_compute(): compute DH's shared. 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 3d8532ad c2c16cbf cf3f1816 3e339ab3 96e54fdd f5f2d4e1 bd291e18 f95ef0a1 7816f446 1307677c 696c691f 60b3800d d2b3a85e e15f558a b1e13434 39a5f3ea 4282c8d3 74c12fdc 9208c1ca fcc59052 c2c8efd8 4d250f99 3dc63147 3619d71f cde37111 692dff2c 85b34572 d79b6056 fed62bf8 742962da 082516ad afe21730 2003-09-19 16:19:06: DEBUG: oakley.c:464:oakley_compute_keymat_x(): KEYMAT compute with 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 3d8532ad c2c16cbf cf3f1816 3e339ab3 96e54fdd f5f2d4e1 bd291e18 f95ef0a1 7816f446 1307677c 696c691f 60b3800d d2b3a85e e15f558a b1e13434 39a5f3ea 4282c8d3 74c12fdc 9208c1ca fcc59052 c2c8efd8 4d250f99 3dc63147 3619d71f cde37111 692dff2c 85b34572 d79b6056 fed62bf8 742962da 082516ad afe21730 03053a58 b45da1ff a66b845c 5577a10d 94f3482e e89ff196 89470eca d2fbb547 00d8a15e 4b 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: oakley.c:497:oakley_compute_keymat_x(): encklen=192 authklen=160 2003-09-19 16:19:06: DEBUG: oakley.c:504:oakley_compute_keymat_x(): generating 640 bits of key (dupkeymat=4) 2003-09-19 16:19:06: DEBUG: oakley.c:522:oakley_compute_keymat_x(): generating K1...K4 for KEYMAT. 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 55029892 f7bb5087 2042d29c e477ef08 7d3e8651 e3136ab7 62218792 152ea9c5 bd389c72 89bcf1ac 58489310 08c1a94a 47627e56 8bf37510 1d3ef9ac f968eca5 6dd73f4e 4dca85ca 22d48025 e95136d7 2003-09-19 16:19:06: DEBUG: oakley.c:464:oakley_compute_keymat_x(): KEYMAT compute with 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 3d8532ad c2c16cbf cf3f1816 3e339ab3 96e54fdd f5f2d4e1 bd291e18 f95ef0a1 7816f446 1307677c 696c691f 60b3800d d2b3a85e e15f558a b1e13434 39a5f3ea 4282c8d3 74c12fdc 9208c1ca fcc59052 c2c8efd8 4d250f99 3dc63147 3619d71f cde37111 692dff2c 85b34572 d79b6056 fed62bf8 742962da 082516ad afe21730 03f1d535 795da1ff a66b845c 5577a10d 94f3482e e89ff196 89470eca d2fbb547 00d8a15e 4b 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: oakley.c:497:oakley_compute_keymat_x(): encklen=192 authklen=160 2003-09-19 16:19:06: DEBUG: oakley.c:504:oakley_compute_keymat_x(): generating 640 bits of key (dupkeymat=4) 2003-09-19 16:19:06: DEBUG: oakley.c:522:oakley_compute_keymat_x(): generating K1...K4 for KEYMAT. 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: plog.c:193:plogdump(): 6d16bc99 eb6fe71d 33d40178 894bde36 2bb14066 1d2dc4d0 6ea8d073 02f99eef c940b2a0 a3845fde 7e3b4fae 5f6d1e3b 03a08546 518bfea0 bf4cf371 3071762c 2f47090d de9ace84 2e7035f2 226c0fc8 2003-09-19 16:19:06: DEBUG: oakley.c:392:oakley_compute_keymat(): KEYMAT computed. 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:1613:quick_r3prep(): call pk_sendupdate 2003-09-19 16:19:06: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: pfkey.c:974:pk_sendupdate(): call pfkey_send_update 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:1618:quick_r3prep(): pfkey update sent. 2003-09-19 16:19:06: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-09-19 16:19:06: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:19:06: DEBUG: pfkey.c:1215:pk_sendadd(): call pfkey_send_add 2003-09-19 16:19:06: DEBUG: isakmp_quick.c:1625:quick_r3prep(): pfkey add sent. 2003-09-19 16:19:06: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey UPDATE message 2003-09-19 16:19:06: DEBUG2: plog.c:193:plogdump(): 02020003 14000000 f67f210c df170000 02000100 053a58b4 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 41dc4547 00000000 00000000 03000600 ff200000 10020000 3fca5c85 00000000 00000000 04000300 00000000 00000000 00000000 b4000000 00000000 00000000 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 2003-09-19 16:19:06: DEBUG: pfkey.c:1103:pk_recvupdate(): pfkey UPDATE succeeded: ESP/Tunnel 65.220.69.71->63.202.92.133 spi=87709876(0x53a58b4) 2003-09-19 16:19:06: INFO: pfkey.c:1110:pk_recvupdate(): IPsec-SA established: ESP/Tunnel 65.220.69.71->63.202.92.133 spi=87709876(0x53a58b4) 2003-09-19 16:19:06: DEBUG: pfkey.c:1148:pk_recvupdate(): === 2003-09-19 16:19:06: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey ADD message 2003-09-19 16:19:06: DEBUG2: plog.c:193:plogdump(): 02030003 14000000 f67f210c df170000 02000100 f1d53579 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 3fca5c85 00000000 00000000 03000600 ff200000 10020000 41dc4547 00000000 00000000 04000300 00000000 00000000 00000000 b4000000 00000000 00000000 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 2003-09-19 16:19:06: INFO: pfkey.c:1322:pk_recvadd(): IPsec-SA established: ESP/Tunnel 63.202.92.133->65.220.69.71 spi=4057281913(0xf1d53579) 2003-09-19 16:19:06: DEBUG: pfkey.c:1327:pk_recvadd(): === 2003-09-19 16:21:31: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey EXPIRE message 2003-09-19 16:21:31: DEBUG2: plog.c:193:plogdump(): 02080003 14000200 f67f210c 00000000 02000100 f1d53579 04020303 00000000 02001300 02000000 04000000 00000000 04000200 04000000 c0040000 00000000 ea8e6b3f 00000000 fe8e6b3f 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 03000500 ff200000 10020000 3fca5c85 00000000 00000000 03000600 ff200000 10020000 41dc4547 00000000 00000000 2003-09-19 16:21:31: INFO: pfkey.c:1368:pk_recvexpire(): IPsec-SA expired: ESP/Tunnel 63.202.92.133->65.220.69.71 spi=4057281913(0xf1d53579) 2003-09-19 16:21:31: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey EXPIRE message 2003-09-19 16:21:31: DEBUG2: plog.c:193:plogdump(): 02080003 14000100 f67f210c 00000000 02000100 053a58b4 04020303 00000000 02001300 02000000 06000000 00000000 04000200 06000000 65020000 00000000 ea8e6b3f 00000000 008f6b3f 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 03000500 ff200000 10020000 41dc4547 00000000 00000000 03000600 ff200000 10020000 3fca5c85 00000000 00000000 2003-09-19 16:21:31: INFO: pfkey.c:1368:pk_recvexpire(): IPsec-SA expired: ESP/Tunnel 65.220.69.71->63.202.92.133 spi=87709876(0x53a58b4) 2003-09-19 16:21:31: DEBUG: pfkey.c:1380:pk_recvexpire(): no such a SA found: ESP/Tunnel 65.220.69.71->63.202.92.133 spi=87709876(0x53a58b4) 2003-09-19 16:22:44: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:22:44: DEBUG: isakmp.c:222:isakmp_handler(): 372 bytes message received from 65.220.69.71[500] 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08102001 e463dc65 00000174 4b9c6538 393c1ef4 634c5973 d78673e8 85640bc8 b0a346cc afc93b62 02bcc3c3 b5eb32ff 0bcf2a44 519499f1 38280837 d1623f7b 452b0b5e 2507ca1e 53162090 c1819069 18ff9b53 ae72895d 2364b738 f16f634c f6f34a43 5be56814 d6ec8b26 2358dd0b 5c59f1dd 5148465e d8931b33 f00d233a 96dd9402 d674b736 c9f0e902 d31866ea 2757d040 7d092c89 b73705ce 3a1864b1 879425d6 2b111418 2dabd19f ae751acf 05340159 7783070a 8d469f7e 7cfbcf06 afeb7800 9660c703 c9743e9d 3807eac1 5e4d3e1f 41479124 273398c2 403f46dd 67adfc23 bf4bfb52 598e6801 afaa732d ae68923f 0860ab34 9f282b79 958baa7a 173b42b8 eb078783 c1ac756b 3e59e0d9 a0717b5b 55615ae8 e056e4a0 2b3f9906 321a92a0 7da04eb1 d2632119 518ab9bc df10dba3 a8fc5c31 6db222cf b9b18fb5 da7d4870 206439b8 9a54a3eb cfdc56fc aa572662 2fc4aa1d 7f36ae85 9f27c222 71771a23 2003-09-19 16:22:44: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:22:44: DEBUG: oakley.c:2549:oakley_newiv2(): compute IV for phase2 2003-09-19 16:22:44: DEBUG: oakley.c:2550:oakley_newiv2(): phase1 last IV: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): d6a6198b 29d70c6f e463dc65 2003-09-19 16:22:44: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-09-19 16:22:44: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:22:44: DEBUG: oakley.c:2582:oakley_newiv2(): phase2 IV computed: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 2893a80a 8a94125d 2003-09-19 16:22:44: DEBUG: isakmp.c:1044:isakmp_ph2begin_r(): === 2003-09-19 16:22:44: INFO: isakmp.c:1049:isakmp_ph2begin_r(): respond new phase 2 negotiation: 63.202.92.133[0]<=>65.220.69.71[0] 2003-09-19 16:22:44: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-09-19 16:22:44: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:22:44: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 9f27c222 71771a23 2003-09-19 16:22:44: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:22:44: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 50f7b8c9 2003-09-19 16:22:44: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 9f27c222 71771a23 2003-09-19 16:22:44: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 01000018 c37c30a1 8d677787 e57d18d7 05439940 1bf69e5b 0a000088 00000001 00000001 0000007c 00030404 26410969 0300001c 00030000 80030002 80040001 80010001 800200b4 80050002 0300001c 01030000 80030002 80040001 80010001 800200b4 80050002 0300001c 02030000 80030002 80040001 80010001 800200b4 80050002 0000001c 03030000 80030002 80040001 80010001 800200b4 80050002 04000014 66eb200d 3826fcc8 088f6a65 ef4a09ea 05000084 cd708514 9ee678e5 21d80a41 7b18f522 9be2f626 5e82f6b6 1d12967c 34716ca4 b27737f7 8fe2c8a3 30b3b63c 7b9fa409 6821532c f89fac5b 4dc9a90f a5f4e7a2 4fcaa0dd c7356f2b 83be6f10 6bace184 bde5fec6 3f2af5d7 5d22b7d6 82807bca d4e597df 1be89faf 685e640d 2567302d aae5c8e1 f97355a4 de869dae 3eeac88c 05000010 04000000 c0a865fd ffffffff 00000010 04000000 0a080807 ffffffff 2003-09-19 16:22:44: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=0 2003-09-19 16:22:44: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-09-19 16:22:44: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08102001 e463dc65 00000174 01000018 c37c30a1 8d677787 e57d18d7 05439940 1bf69e5b 0a000088 00000001 00000001 0000007c 00030404 26410969 0300001c 00030000 80030002 80040001 80010001 800200b4 80050002 0300001c 01030000 80030002 80040001 80010001 800200b4 80050002 0300001c 02030000 80030002 80040001 80010001 800200b4 80050002 0000001c 03030000 80030002 80040001 80010001 800200b4 80050002 04000014 66eb200d 3826fcc8 088f6a65 ef4a09ea 05000084 cd708514 9ee678e5 21d80a41 7b18f522 9be2f626 5e82f6b6 1d12967c 34716ca4 b27737f7 8fe2c8a3 30b3b63c 7b9fa409 6821532c f89fac5b 4dc9a90f a5f4e7a2 4fcaa0dd c7356f2b 83be6f10 6bace184 bde5fec6 3f2af5d7 5d22b7d6 82807bca d4e597df 1be89faf 685e640d 2567302d aae5c8e1 f97355a4 de869dae 3eeac88c 05000010 04000000 c0a865fd ffffffff 00000010 04000000 0a080807 ffffffff 2003-09-19 16:22:44: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:22:44: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=1(sa) 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=10(nonce) 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=4(ke) 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-09-19 16:22:44: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:22:44: DEBUG: isakmp_quick.c:1006:quick_r1recv(): received IDci2:2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 04000000 c0a865fd ffffffff 2003-09-19 16:22:44: DEBUG: isakmp_quick.c:1010:quick_r1recv(): received IDcr2:2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 04000000 0a080807 ffffffff 2003-09-19 16:22:44: DEBUG: isakmp_quick.c:1025:quick_r1recv(): HASH(1) validate:2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): c37c30a1 8d677787 e57d18d7 05439940 1bf69e5b 2003-09-19 16:22:44: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): e463dc65 0a000088 00000001 00000001 0000007c 00030404 26410969 0300001c 00030000 80030002 80040001 80010001 800200b4 80050002 0300001c 01030000 80030002 80040001 80010001 800200b4 80050002 0300001c 02030000 80030002 80040001 80010001 800200b4 80050002 0000001c 03030000 80030002 80040001 80010001 800200b4 80050002 04000014 66eb200d 3826fcc8 088f6a65 ef4a09ea 05000084 cd708514 9ee678e5 21d80a41 7b18f522 9be2f626 5e82f6b6 1d12967c 34716ca4 b27737f7 8fe2c8a3 30b3b63c 7b9fa409 6821532c f89fac5b 4dc9a90f a5f4e7a2 4fcaa0dd c7356f2b 83be6f10 6bace184 bde5fec6 3f2af5d7 5d22b7d6 82807bca d4e597df 1be89faf 685e640d 2567302d aae5c8e1 f97355a4 de869dae 3eeac88c 05000010 04000000 c0a865fd ffffffff 00000010 04000000 0a080807 ffffffff 2003-09-19 16:22:44: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:44: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): c37c30a1 8d677787 e57d18d7 05439940 1bf69e5b 2003-09-19 16:22:44: DEBUG: sainfo.c:100:getsainfo(): anonymous sainfo selected. 2003-09-19 16:22:44: DEBUG: isakmp_quick.c:1817:get_sainfo_r(): get sa info: anonymous 2003-09-19 16:22:44: DEBUG: isakmp_quick.c:1995:get_proposal_r(): get a src address from ID payload 192.168.101.253[0] prefixlen=32 ul_proto=255 2003-09-19 16:22:44: DEBUG: isakmp_quick.c:2000:get_proposal_r(): get dst address from ID payload 10.8.8.7[0] prefixlen=32 ul_proto=255 2003-09-19 16:22:44: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd36c: 192.168.101.253/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-09-19 16:22:44: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80a2c08: 192.168.101.253/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-09-19 16:22:44: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfd36c masked with /32: 192.168.101.253[0] 2003-09-19 16:22:44: DEBUG: policy.c:246:cmpspidxwild(): 0x80a2c08 masked with /32: 192.168.101.253[0] 2003-09-19 16:22:44: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfd36c masked with /32: 10.8.8.7[0] 2003-09-19 16:22:44: DEBUG: policy.c:262:cmpspidxwild(): 0x80a2c08 masked with /32: 10.8.8.7[0] 2003-09-19 16:22:44: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd36c: 10.8.8.7/32[0] 192.168.101.253/32[0] proto=any dir=out 2003-09-19 16:22:44: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80a2c08: 192.168.101.253/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-09-19 16:22:44: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd36c: 10.8.8.7/32[0] 192.168.101.253/32[0] proto=any dir=out 2003-09-19 16:22:44: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80ae008: 10.8.8.7/32[0] 192.168.101.253/32[0] proto=any dir=out 2003-09-19 16:22:44: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfd36c masked with /32: 10.8.8.7[0] 2003-09-19 16:22:44: DEBUG: policy.c:246:cmpspidxwild(): 0x80ae008 masked with /32: 10.8.8.7[0] 2003-09-19 16:22:44: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfd36c masked with /32: 192.168.101.253[0] 2003-09-19 16:22:44: DEBUG: policy.c:262:cmpspidxwild(): 0x80ae008 masked with /32: 192.168.101.253[0] 2003-09-19 16:22:44: DEBUG: isakmp_quick.c:2056:get_proposal_r(): suitable SP found:10.8.8.7/32[0] 192.168.101.253/32[0] proto=any dir=out 2003-09-19 16:22:44: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-09-19 16:22:44: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=132 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 00000001 00000001 0000007c 00030404 26410969 0300001c 00030000 80030002 80040001 80010001 800200b4 80050002 0300001c 01030000 80030002 80040001 80010001 800200b4 80050002 0300001c 02030000 80030002 80040001 80010001 800200b4 80050002 0000001c 03030000 80030002 80040001 80010001 800200b4 80050002 2003-09-19 16:22:44: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-09-19 16:22:44: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=124 2003-09-19 16:22:44: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:22:44: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=28 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1311:get_transform(): transform #1 len=28 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1311:get_transform(): transform #2 len=28 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1311:get_transform(): transform #3 len=28 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-09-19 16:22:44: DEBUG: proposal.c:892:print_proppair0(): 0x80a8680: next=0x0 tnext=0x80a8690 2003-09-19 16:22:44: DEBUG: proposal.c:892:print_proppair0(): 0x80a8690: next=0x0 tnext=0x80a86a0 2003-09-19 16:22:44: DEBUG: proposal.c:892:print_proppair0(): 0x80a86a0: next=0x0 tnext=0x80a86b0 2003-09-19 16:22:44: DEBUG: proposal.c:892:print_proppair0(): 0x80a86b0: next=0x0 tnext=0x0 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 4 transform 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:948:get_ph2approval(): begin compare proposals. 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:954:get_ph2approval(): pair[0]: 0x80a8680 2003-09-19 16:22:44: DEBUG: proposal.c:892:print_proppair0(): 0x80a8680: next=0x0 tnext=0x80a8690 2003-09-19 16:22:44: DEBUG: proposal.c:892:print_proppair0(): 0x80a8690: next=0x0 tnext=0x80a86a0 2003-09-19 16:22:44: DEBUG: proposal.c:892:print_proppair0(): 0x80a86a0: next=0x0 tnext=0x80a86b0 2003-09-19 16:22:44: DEBUG: proposal.c:892:print_proppair0(): 0x80a86b0: next=0x0 tnext=0x0 2003-09-19 16:22:44: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=4 trns#=0 trns-id=3DES 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=4 trns#=1 trns-id=3DES 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=4 trns#=2 trns-id=3DES 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=4 trns#=3 trns-id=3DES 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:990:get_ph2approvalx(): peer's single bundle: 2003-09-19 16:22:44: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=26410969 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-09-19 16:22:44: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:22:44: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:22:44: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:22:44: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:993:get_ph2approvalx(): my single bundle: 2003-09-19 16:22:44: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-09-19 16:22:44: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1012:get_ph2approvalx(): matched 2003-09-19 16:22:44: DEBUG: isakmp.c:1078:isakmp_ph2begin_r(): === 2003-09-19 16:22:44: DEBUG: pfkey.c:792:pk_sendgetspi(): call pfkey_send_getspi 2003-09-19 16:22:44: DEBUG: pfkey.c:805:pk_sendgetspi(): pfkey GETSPI sent: ESP/Tunnel 65.220.69.71->63.202.92.133 2003-09-19 16:22:44: DEBUG: isakmp_quick.c:1143:quick_r1prep(): pfkey getspi sent. 2003-09-19 16:22:44: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey GETSPI message 2003-09-19 16:22:44: DEBUG2: plog.c:193:plogdump(): 02010003 0a000000 dcc842f6 df170000 02000100 0dcd9ad1 3f763fca 5c853fca 03000500 ff200000 10020000 41dc4547 00000000 00000000 03000600 ff200000 10020000 3fca5c85 00000000 00000000 2003-09-19 16:22:44: DEBUG: pfkey.c:876:pk_recvgetspi(): pfkey GETSPI succeeded: ESP/Tunnel 65.220.69.71->63.202.92.133 spi=231578321(0xdcd9ad1) 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=48 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000028 00030401 00000000 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 2003-09-19 16:22:44: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-09-19 16:22:44: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=40 2003-09-19 16:22:44: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-09-19 16:22:44: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=28 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-09-19 16:22:44: DEBUG: proposal.c:892:print_proppair0(): 0x80a8480: next=0x0 tnext=0x0 2003-09-19 16:22:44: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 1 transform 2003-09-19 16:22:44: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-09-19 16:22:44: DEBUG: oakley.c:256:oakley_dh_generate(): compute DH's private. 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 7d92988d 4bdfb3f0 c8607f7f 4d7e5a94 60d0a698 8a1ac9bb 6c33a60f 0bde7bca 0e6a34a0 813620c9 e8a37281 96905fdd a0b2acb5 0a3a5b29 cd2ab7a3 c10c0c24 ac87b465 080d74b2 f131c850 9c53de66 87b6d3d8 888d802a 8c6b0549 99494999 8275a818 1df0e2f0 c0cf7b78 3421b0f4 15e6159c 4e102471 3c67633b 20feca69 2003-09-19 16:22:44: DEBUG: oakley.c:258:oakley_dh_generate(): compute DH's public. 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): d203895c 3b2fcfa3 fb5d1a43 43e47304 18df678a 1d27e8d3 cf6de356 f77f217c 5217e070 ea1899be b5ba8f37 1a216a4f e384c2d3 de36ae36 13d29fc0 6b82465d 93adcf7c 064763a1 682621f7 e0455724 43453d65 10669c91 10ef16ad e701deef 8235283d 710565d5 f935b575 1ddc247c a2062fdf ce6068b5 17ad9c8b a68b502e 2003-09-19 16:22:44: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 48, next type 10 2003-09-19 16:22:44: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 4 2003-09-19 16:22:44: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 128, next type 5 2003-09-19 16:22:44: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 12, next type 5 2003-09-19 16:22:44: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 12, next type 0 2003-09-19 16:22:44: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): e463dc65 66eb200d 3826fcc8 088f6a65 ef4a09ea 0a000034 00000001 00000001 00000028 00030401 0dcd9ad1 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 a0ca1acd a7a4cb65 c7d00ec6 e9f62f5c 05000084 d203895c 3b2fcfa3 fb5d1a43 43e47304 18df678a 1d27e8d3 cf6de356 f77f217c 5217e070 ea1899be b5ba8f37 1a216a4f e384c2d3 de36ae36 13d29fc0 6b82465d 93adcf7c 064763a1 682621f7 e0455724 43453d65 10669c91 10ef16ad e701deef 8235283d 710565d5 f935b575 1ddc247c a2062fdf ce6068b5 17ad9c8b a68b502e 05000010 04000000 c0a865fd ffffffff 00000010 04000000 0a080807 ffffffff 2003-09-19 16:22:44: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:44: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 8e21cb28 0af6c8dc 1fc581bf 8f5c6a4e 551711d6 2003-09-19 16:22:44: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 20, next type 1 2003-09-19 16:22:44: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:22:44: DEBUG: oakley.c:2748:oakley_do_encrypt(): begin encryption. 2003-09-19 16:22:44: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:22:44: DEBUG: oakley.c:2764:oakley_do_encrypt(): pad length = 4 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 01000018 8e21cb28 0af6c8dc 1fc581bf 8f5c6a4e 551711d6 0a000034 00000001 00000001 00000028 00030401 0dcd9ad1 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 a0ca1acd a7a4cb65 c7d00ec6 e9f62f5c 05000084 d203895c 3b2fcfa3 fb5d1a43 43e47304 18df678a 1d27e8d3 cf6de356 f77f217c 5217e070 ea1899be b5ba8f37 1a216a4f e384c2d3 de36ae36 13d29fc0 6b82465d 93adcf7c 064763a1 682621f7 e0455724 43453d65 10669c91 10ef16ad e701deef 8235283d 710565d5 f935b575 1ddc247c a2062fdf ce6068b5 17ad9c8b a68b502e 05000010 04000000 c0a865fd ffffffff 00000010 04000000 0a080807 ffffffff c049d603 2003-09-19 16:22:44: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:22:44: DEBUG: oakley.c:2799:oakley_do_encrypt(): with key: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 50f7b8c9 2003-09-19 16:22:44: DEBUG: oakley.c:2807:oakley_do_encrypt(): encrypted payload by IV: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 1549b7b3 58f66a37 2003-09-19 16:22:44: DEBUG: oakley.c:2814:oakley_do_encrypt(): save IV for next: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 1549b7b3 58f66a37 2003-09-19 16:22:44: DEBUG: oakley.c:2831:oakley_do_encrypt(): encrypted. 2003-09-19 16:22:44: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-09-19 16:22:44: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-09-19 16:22:44: DEBUG: sockmisc.c:425:sendfromto(): send packet to 65.220.69.71[500] 2003-09-19 16:22:44: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 292 bytes message will be sent to 63.202.92.133[500] 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08102001 e463dc65 00000124 7ad852ec 84e85026 2c3de18b b92e18cc ec061df8 8d0df353 8bbd46b8 15f0296f 6a158a2d d2b5d224 c47366b4 25b755c5 237ecba9 456ad22e 1d529db6 67a5e68b 437051ef ae5815b4 b2077163 d32d22a8 aa1034ae 662b3000 1dce3d01 76b58a9c cc8dbc70 cfd48b18 cb7cfdb5 b1d687ca a848d329 a0d54fcf dbaffe62 c213df47 63f70ccd 515d3b61 3f3cc8ae 4ccf6edf 1a07e899 4e308bad 416950da 9626f0f7 b8b3493c 47bc2bc6 36ff4e1e e97338b0 995ec279 6aa8eda2 86431da9 4e945282 8506ec5a ab440c2f ee98e002 74166bfe e1c0aad5 c2f0e978 861f6996 510488a6 346472fe b6d11490 3b24ba73 02b720a7 f1cf69a4 f07a3b7d 942a566c 1c87115a 1549b7b3 58f66a37 2003-09-19 16:22:44: DEBUG: isakmp.c:1487:isakmp_ph2resend(): resend phase2 packet e4eb5e590b9b7f8b:397e4308c83f6d63:0000e463 2003-09-19 16:22:44: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-09-19 16:22:44: DEBUG: isakmp.c:222:isakmp_handler(): 52 bytes message received from 65.220.69.71[500] 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08102001 e463dc65 00000034 368a4e23 7a121753 f053b71a 1a4a0383 341683c2 cae8353a 2003-09-19 16:22:44: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:22:44: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-09-19 16:22:44: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:22:44: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 341683c2 cae8353a 2003-09-19 16:22:44: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-09-19 16:22:44: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 353ae16f a9103cac a7a6db1f e083bee5 19286ada 50f7b8c9 2003-09-19 16:22:44: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 341683c2 cae8353a 2003-09-19 16:22:44: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 00000018 3ad4f732 8bf4fbbc 8948846f 6521c269 326ebb90 2003-09-19 16:22:44: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=145 2003-09-19 16:22:44: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-09-19 16:22:44: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): e4eb5e59 0b9b7f8b 397e4308 c83f6d63 08102001 e463dc65 00000034 00000018 3ad4f732 8bf4fbbc 8948846f 6521c269 326ebb90 2003-09-19 16:22:44: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-09-19 16:22:44: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-09-19 16:22:44: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-09-19 16:22:44: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-09-19 16:22:44: DEBUG: isakmp_quick.c:1430:quick_r3recv(): HASH(3) validate:2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 3ad4f732 8bf4fbbc 8948846f 6521c269 326ebb90 2003-09-19 16:22:44: DEBUG: oakley.c:692:oakley_compute_hash3(): HASH with: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 00e463dc 6566eb20 0d3826fc c8088f6a 65ef4a09 eaa0ca1a cda7a4cb 65c7d00e c6e9f62f 5c 2003-09-19 16:22:44: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:44: DEBUG: oakley.c:702:oakley_compute_hash3(): HASH computed: 2003-09-19 16:22:44: DEBUG: plog.c:193:plogdump(): 3ad4f732 8bf4fbbc 8948846f 6521c269 326ebb90 2003-09-19 16:22:44: DEBUG: isakmp.c:736:quick_main(): === 2003-09-19 16:22:45: DEBUG: oakley.c:210:oakley_dh_compute(): compute DH's shared. 2003-09-19 16:22:45: DEBUG: plog.c:193:plogdump(): ee7dadaa 1479f25a c2d78250 c0f1f9f3 8690c77c 9161d914 be29c68e f2c12f5d b3211328 4a95759c 7a82f52b 81c7e551 6528925f 1045edbb b841f903 e1c5e3f2 d6be3b73 e8e10fe6 f39803ef abb131a5 c413be46 19f0ee84 d6652123 31800732 156566d8 2d75a6cd ac35b750 e764d7a1 068345eb d721404d 46238e97 e4378a7b 2003-09-19 16:22:45: DEBUG: oakley.c:464:oakley_compute_keymat_x(): KEYMAT compute with 2003-09-19 16:22:45: DEBUG: plog.c:193:plogdump(): ee7dadaa 1479f25a c2d78250 c0f1f9f3 8690c77c 9161d914 be29c68e f2c12f5d b3211328 4a95759c 7a82f52b 81c7e551 6528925f 1045edbb b841f903 e1c5e3f2 d6be3b73 e8e10fe6 f39803ef abb131a5 c413be46 19f0ee84 d6652123 31800732 156566d8 2d75a6cd ac35b750 e764d7a1 068345eb d721404d 46238e97 e4378a7b 030dcd9a d166eb20 0d3826fc c8088f6a 65ef4a09 eaa0ca1a cda7a4cb 65c7d00e c6e9f62f 5c 2003-09-19 16:22:45: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-09-19 16:22:45: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: oakley.c:497:oakley_compute_keymat_x(): encklen=192 authklen=160 2003-09-19 16:22:45: DEBUG: oakley.c:504:oakley_compute_keymat_x(): generating 640 bits of key (dupkeymat=4) 2003-09-19 16:22:45: DEBUG: oakley.c:522:oakley_compute_keymat_x(): generating K1...K4 for KEYMAT. 2003-09-19 16:22:45: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: plog.c:193:plogdump(): fc2ae847 382c7898 548f31bd b8b4687e d6f827dd 9ff825ed 0fe87284 c486b31d a3850ee4 1a44c4b1 96504378 7203e1ad 32c7f655 459ef76e 82bcdce7 0db2aff4 5bb40438 c9e645b3 167a209d 705957cb 2003-09-19 16:22:45: DEBUG: oakley.c:464:oakley_compute_keymat_x(): KEYMAT compute with 2003-09-19 16:22:45: DEBUG: plog.c:193:plogdump(): ee7dadaa 1479f25a c2d78250 c0f1f9f3 8690c77c 9161d914 be29c68e f2c12f5d b3211328 4a95759c 7a82f52b 81c7e551 6528925f 1045edbb b841f903 e1c5e3f2 d6be3b73 e8e10fe6 f39803ef abb131a5 c413be46 19f0ee84 d6652123 31800732 156566d8 2d75a6cd ac35b750 e764d7a1 068345eb d721404d 46238e97 e4378a7b 03264109 6966eb20 0d3826fc c8088f6a 65ef4a09 eaa0ca1a cda7a4cb 65c7d00e c6e9f62f 5c 2003-09-19 16:22:45: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-09-19 16:22:45: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: oakley.c:497:oakley_compute_keymat_x(): encklen=192 authklen=160 2003-09-19 16:22:45: DEBUG: oakley.c:504:oakley_compute_keymat_x(): generating 640 bits of key (dupkeymat=4) 2003-09-19 16:22:45: DEBUG: oakley.c:522:oakley_compute_keymat_x(): generating K1...K4 for KEYMAT. 2003-09-19 16:22:45: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: plog.c:193:plogdump(): 73ece955 a911ebad d34ed2ef b4cff26a 2de4bb57 1b289421 8898116a 2551902e 158e20eb f7e43ded 59f2acda 05acf952 e755895b af08ed84 b7bc1a30 5d2b1509 ef5f8bee 5f168edf 514036c5 33665f9d 2003-09-19 16:22:45: DEBUG: oakley.c:392:oakley_compute_keymat(): KEYMAT computed. 2003-09-19 16:22:45: DEBUG: isakmp_quick.c:1613:quick_r3prep(): call pk_sendupdate 2003-09-19 16:22:45: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-09-19 16:22:45: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: pfkey.c:974:pk_sendupdate(): call pfkey_send_update 2003-09-19 16:22:45: DEBUG: isakmp_quick.c:1618:quick_r3prep(): pfkey update sent. 2003-09-19 16:22:45: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-09-19 16:22:45: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-09-19 16:22:45: DEBUG: pfkey.c:1215:pk_sendadd(): call pfkey_send_add 2003-09-19 16:22:45: DEBUG: isakmp_quick.c:1625:quick_r3prep(): pfkey add sent. 2003-09-19 16:22:45: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey UPDATE message 2003-09-19 16:22:45: DEBUG2: plog.c:193:plogdump(): 02020003 14000000 dcc842f6 df170000 02000100 0dcd9ad1 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 41dc4547 00000000 00000000 03000600 ff200000 10020000 3fca5c85 00000000 00000000 04000300 00000000 00000000 00000000 b4000000 00000000 00000000 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 2003-09-19 16:22:45: DEBUG: pfkey.c:1103:pk_recvupdate(): pfkey UPDATE succeeded: ESP/Tunnel 65.220.69.71->63.202.92.133 spi=231578321(0xdcd9ad1) 2003-09-19 16:22:45: INFO: pfkey.c:1110:pk_recvupdate(): IPsec-SA established: ESP/Tunnel 65.220.69.71->63.202.92.133 spi=231578321(0xdcd9ad1) 2003-09-19 16:22:45: DEBUG: pfkey.c:1148:pk_recvupdate(): === 2003-09-19 16:22:45: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey ADD message 2003-09-19 16:22:45: DEBUG2: plog.c:193:plogdump(): 02030003 14000000 dcc842f6 df170000 02000100 26410969 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 3fca5c85 00000000 00000000 03000600 ff200000 10020000 41dc4547 00000000 00000000 04000300 00000000 00000000 00000000 b4000000 00000000 00000000 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 2003-09-19 16:22:45: INFO: pfkey.c:1322:pk_recvadd(): IPsec-SA established: ESP/Tunnel 63.202.92.133->65.220.69.71 spi=641796457(0x26410969) 2003-09-19 16:22:45: DEBUG: pfkey.c:1327:pk_recvadd(): === 2003-09-19 16:25:10: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey EXPIRE message 2003-09-19 16:25:10: DEBUG2: plog.c:193:plogdump(): 02080003 14000200 dcc842f6 00000000 02000100 26410969 04020303 00000000 02001300 02000000 04000000 00000000 04000200 04000000 c0040000 00000000 c58f6b3f 00000000 d68f6b3f 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 03000500 ff200000 10020000 3fca5c85 00000000 00000000 03000600 ff200000 10020000 41dc4547 00000000 00000000 2003-09-19 16:25:10: INFO: pfkey.c:1368:pk_recvexpire(): IPsec-SA expired: ESP/Tunnel 63.202.92.133->65.220.69.71 spi=641796457(0x26410969) 2003-09-19 16:25:10: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey EXPIRE message 2003-09-19 16:25:10: DEBUG2: plog.c:193:plogdump(): 02080003 14000100 dcc842f6 00000000 02000100 0dcd9ad1 04020303 00000000 02001300 02000000 06000000 00000000 04000200 06000000 b3010000 00000000 c58f6b3f 00000000 d78f6b3f 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 03000500 ff200000 10020000 41dc4547 00000000 00000000 03000600 ff200000 10020000 3fca5c85 00000000 00000000 2003-09-19 16:25:10: INFO: pfkey.c:1368:pk_recvexpire(): IPsec-SA expired: ESP/Tunnel 65.220.69.71->63.202.92.133 spi=231578321(0xdcd9ad1) 2003-09-19 16:25:10: DEBUG: pfkey.c:1380:pk_recvexpire(): no such a SA found: ESP/Tunnel 65.220.69.71->63.202.92.133 spi=231578321(0xdcd9ad1) 2003-09-19 16:50:00: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey FLUSH message 2003-09-19 16:50:00: DEBUG2: plog.c:193:plogdump(): 02090000 02000000 00000000 0c180000 2003-09-19 16:50:00: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey X_SPDFLUSH message 2003-09-19 16:50:00: DEBUG2: plog.c:193:plogdump(): 02130000 02000000 00000000 0d180000 2003-09-19 16:50:01: DEBUG: grabmyaddr.c:438:update_myaddrs(): msg 1 not interesting