2003-04-07 10:49:06: INFO: main.c:172:main(): @(#)package version netbsd-pkgsrc-20021120a 2003-04-07 10:49:06: INFO: main.c:174:main(): @(#)internal version 20001216 sakane@kame.net 2003-04-07 10:49:06: INFO: main.c:175:main(): @(#)This product linked OpenSSL 0.9.6g 9 Aug 2002 (http://www.openssl.org/) 2003-04-07 10:49:06: DEBUG: pfkey.c:371:pfkey_init(): call pfkey_send_register for AH 2003-04-07 10:49:06: DEBUG: pfkey.c:371:pfkey_init(): call pfkey_send_register for ESP 2003-04-07 10:49:06: DEBUG: pfkey.c:371:pfkey_init(): call pfkey_send_register for IPCOMP 2003-04-07 10:49:06: DEBUG2: cftoken.l:115:yylex(): begin <5>path 2003-04-07 10:49:06: DEBUG2: cftoken.l:118:yylex(): <5> 2003-04-07 10:49:06: DEBUG2: cftoken.l:386:yylex(): <5> 2003-04-07 10:49:06: DEBUG2: cftoken.l:124:yylex(): begin <3>; 2003-04-07 10:49:06: DEBUG2: cftoken.l:115:yylex(): begin <5>path 2003-04-07 10:49:06: DEBUG2: cftoken.l:120:yylex(): <5> 2003-04-07 10:49:06: DEBUG2: cftoken.l:386:yylex(): <5> 2003-04-07 10:49:06: DEBUG2: cftoken.l:124:yylex(): begin <3>; 2003-04-07 10:49:06: DEBUG2: cftoken.l:157:yylex(): begin <13>listen 2003-04-07 10:49:06: DEBUG2: cftoken.l:159:yylex(): <13> 2003-04-07 10:49:06: DEBUG2: cftoken.l:401:yylex(): <13> 2003-04-07 10:49:06: DEBUG2: cftoken.l:265:yylex(): <13> 2003-04-07 10:49:06: DEBUG2: cftoken.l:161:yylex(): <13> 2003-04-07 10:49:06: DEBUG2: cftoken.l:195:yylex(): begin <25>remote 2003-04-07 10:49:06: DEBUG2: cftoken.l:196:yylex(): <25> 2003-04-07 10:49:06: DEBUG2: cftoken.l:200:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:203:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:227:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:229:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:215:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:216:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:386:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:386:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:212:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:336:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:386:yylex(): <27> 2003-04-07 10:49:06: DEBUG2: cftoken.l:238:yylex(): begin <29>proposal 2003-04-07 10:49:06: DEBUG2: cftoken.l:244:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:285:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:246:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:306:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:245:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:324:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:247:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:315:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:238:yylex(): begin <29>proposal 2003-04-07 10:49:06: DEBUG2: cftoken.l:244:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:285:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:246:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:306:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:245:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:325:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:247:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cftoken.l:315:yylex(): <29> 2003-04-07 10:49:06: DEBUG2: cfparse.y:1172:set_isakmp_proposal(): lifetime = 28800 2003-04-07 10:49:06: DEBUG2: cfparse.y:1175:set_isakmp_proposal(): lifebyte = 0 2003-04-07 10:49:06: DEBUG2: cfparse.y:1178:set_isakmp_proposal(): encklen=0 2003-04-07 10:49:06: DEBUG2: cfparse.y:1241:expand_isakmpspec(): p:1 t:1 2003-04-07 10:49:06: DEBUG2: cfparse.y:1245:expand_isakmpspec(): 3DES-CBC(5) 2003-04-07 10:49:06: DEBUG2: cfparse.y:1245:expand_isakmpspec(): SHA(2) 2003-04-07 10:49:06: DEBUG2: cfparse.y:1245:expand_isakmpspec(): 1024-bit MODP group(2) 2003-04-07 10:49:06: DEBUG2: cfparse.y:1245:expand_isakmpspec(): pre-shared key(1) 2003-04-07 10:49:06: DEBUG2: cfparse.y:1252:expand_isakmpspec(): 2003-04-07 10:49:06: DEBUG2: cfparse.y:1172:set_isakmp_proposal(): lifetime = 28800 2003-04-07 10:49:06: DEBUG2: cfparse.y:1175:set_isakmp_proposal(): lifebyte = 0 2003-04-07 10:49:06: DEBUG2: cfparse.y:1178:set_isakmp_proposal(): encklen=0 2003-04-07 10:49:06: DEBUG2: cfparse.y:1241:expand_isakmpspec(): p:1 t:2 2003-04-07 10:49:06: DEBUG2: cfparse.y:1245:expand_isakmpspec(): 3DES-CBC(5) 2003-04-07 10:49:06: DEBUG2: cfparse.y:1245:expand_isakmpspec(): SHA(2) 2003-04-07 10:49:06: DEBUG2: cfparse.y:1245:expand_isakmpspec(): 1024-bit MODP group(2) 2003-04-07 10:49:06: DEBUG2: cfparse.y:1245:expand_isakmpspec(): RSA signatures(3) 2003-04-07 10:49:06: DEBUG2: cfparse.y:1252:expand_isakmpspec(): 2003-04-07 10:49:06: DEBUG2: cftoken.l:175:yylex(): begin <21>sainfo 2003-04-07 10:49:06: DEBUG2: cftoken.l:176:yylex(): <21> 2003-04-07 10:49:06: DEBUG2: cftoken.l:189:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:285:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:190:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:299:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:186:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:187:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:356:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:345:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:183:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:315:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:191:yylex(): <23> 2003-04-07 10:49:06: DEBUG2: cftoken.l:312:yylex(): <23> 2003-04-07 10:49:06: DEBUG: pfkey.c:2243:pk_checkalg(): compression algorithm can not be checked because sadb message doesn't support it. 2003-04-07 10:49:06: DEBUG2: cfparse.y:1354:cfparse(): parse successed. 2003-04-07 10:49:06: INFO: isakmp.c:1358:isakmp_open(): 63.202.92.133[500] used as isakmp port (fd=10) 2003-04-07 10:49:06: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey X_SPDDUMP message 2003-04-07 10:49:06: DEBUG2: plog.c:193:plogdump(): 02120000 17000100 01000000 43020000 03000500 ff200000 10020000 0a000562 00000000 00000000 03000600 ff200000 10020000 0a080807 00000000 00000000 07001200 02000100 01000000 00000000 28003200 02020000 10020000 d890b807 00000000 00000000 10020000 3fca5c85 00000000 00000000 04000200 00000000 00000000 00000000 12ba913e 00000000 12ba913e 00000000 04000300 00000000 00000000 00000000 00000000 00000000 00000000 00000000 2003-04-07 10:49:06: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey X_SPDDUMP message 2003-04-07 10:49:06: DEBUG2: plog.c:193:plogdump(): 02120000 17000100 00000000 43020000 03000500 ff200000 10020000 0a080807 00000000 00000000 03000600 ff200000 10020000 0a000562 00000000 00000000 07001200 02000200 02000000 00000000 28003200 02020000 10020000 3fca5c85 00000000 00000000 10020000 d890b807 00000000 00000000 04000200 00000000 00000000 00000000 12ba913e 00000000 12ba913e 00000000 04000300 00000000 00000000 00000000 00000000 00000000 00000000 00000000 2003-04-07 10:49:06: DEBUG: policy.c:184:cmpspidxstrict(): sub:0xbfbfd54c: 10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:49:06: DEBUG: policy.c:185:cmpspidxstrict(): db :0x80a2c08: 10.0.5.98/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-04-07 10:49:37: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:49:37: DEBUG: isakmp.c:222:isakmp_handler(): 204 bytes message received from 216.144.184.7[500] 2003-04-07 10:49:37: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 00000000 00000000 01100200 00000000 000000cc 0d000074 00000001 00000001 00000068 00010003 03000020 00010000 800b0001 800c0e10 80010005 80020002 80030001 80040005 03000020 01010000 800b0001 800c0e10 80010005 80020002 80030001 80040002 00000020 02010000 800b0001 800c0e10 80010005 80020002 80030001 80040001 0d000014 7d9419a6 5310ca6f 2c179d92 15529d56 0d000014 cd604643 35df21f8 7cfdb2fc 68b6a448 00000014 4485152d 18b6bbcd 0be8a846 9579ddcc 2003-04-07 10:49:37: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:37: DEBUG: remoteconf.c:129:getrmconf(): anonymous configuration selected for 216.144.184.7[500]. 2003-04-07 10:49:37: DEBUG: isakmp.c:889:isakmp_ph1begin_r(): === 2003-04-07 10:49:37: INFO: isakmp.c:894:isakmp_ph1begin_r(): respond new phase 1 negotiation: 63.202.92.133[500]<=>216.144.184.7[500] 2003-04-07 10:49:37: INFO: isakmp.c:899:isakmp_ph1begin_r(): begin Identity Protection mode. 2003-04-07 10:49:37: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:37: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=1(sa) 2003-04-07 10:49:37: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=13(vid) 2003-04-07 10:49:37: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=13(vid) 2003-04-07 10:49:37: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=13(vid) 2003-04-07 10:49:37: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:37: DEBUG: vendorid.c:137:check_vendorid(): received unknown Vendor ID 2003-04-07 10:49:37: DEBUG: vendorid.c:137:check_vendorid(): received unknown Vendor ID 2003-04-07 10:49:37: DEBUG: vendorid.c:137:check_vendorid(): received unknown Vendor ID 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=112 2003-04-07 10:49:37: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000068 00010003 03000020 00010000 800b0001 800c0e10 80010005 80020002 80030001 80040005 03000020 01010000 800b0001 800c0e10 80010005 80020002 80030001 80040002 00000020 02010000 800b0001 800c0e10 80010005 80020002 80030001 80040001 2003-04-07 10:49:37: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:37: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-04-07 10:49:37: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=104 2003-04-07 10:49:37: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:37: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-04-07 10:49:37: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-04-07 10:49:37: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-04-07 10:49:37: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=32 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Duration, flag=0x8000, lorv=3600 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2003-04-07 10:49:37: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2003-04-07 10:49:37: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1536-bit MODP group 2003-04-07 10:49:37: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1536) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1311:get_transform(): transform #1 len=32 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Duration, flag=0x8000, lorv=3600 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2003-04-07 10:49:37: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2003-04-07 10:49:37: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2003-04-07 10:49:37: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1311:get_transform(): transform #2 len=32 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Life Duration, flag=0x8000, lorv=3600 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2003-04-07 10:49:37: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2003-04-07 10:49:37: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1870:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=768-bit MODP group 2003-04-07 10:49:37: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp768) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-04-07 10:49:37: DEBUG: proposal.c:892:print_proppair0(): 0x80a83b0: next=0x0 tnext=0x80a83c0 2003-04-07 10:49:37: DEBUG: proposal.c:892:print_proppair0(): 0x80a83c0: next=0x0 tnext=0x80a83d0 2003-04-07 10:49:37: DEBUG: proposal.c:892:print_proppair0(): 0x80a83d0: next=0x0 tnext=0x0 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 3 transform 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:322:get_ph1approvalx(): prop#=0, prot-id=ISAKMP, spi-size=0, #trns=3 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:327:get_ph1approvalx(): trns#=0, trns-id=IKE 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Life Duration, flag=0x8000, lorv=3600 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Group Description, flag=0x8000, lorv=1536-bit MODP group 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:338:get_ph1approvalx(): Compared: DB:Peer 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:339:get_ph1approvalx(): (lifetime = 28800:3600) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:341:get_ph1approvalx(): (lifebyte = 0:0) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:343:get_ph1approvalx(): enctype = 3DES-CBC:3DES-CBC 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:348:get_ph1approvalx(): (encklen = 0:0) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:350:get_ph1approvalx(): hashtype = SHA:SHA 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:355:get_ph1approvalx(): authmethod = pre-shared key:pre-shared key 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:360:get_ph1approvalx(): dh_group = 1024-bit MODP group:1536-bit MODP group 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:338:get_ph1approvalx(): Compared: DB:Peer 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:339:get_ph1approvalx(): (lifetime = 28800:3600) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:341:get_ph1approvalx(): (lifebyte = 0:0) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:343:get_ph1approvalx(): enctype = 3DES-CBC:3DES-CBC 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:348:get_ph1approvalx(): (encklen = 0:0) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:350:get_ph1approvalx(): hashtype = SHA:SHA 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:355:get_ph1approvalx(): authmethod = RSA signatures:pre-shared key 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:360:get_ph1approvalx(): dh_group = 1024-bit MODP group:1536-bit MODP group 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:322:get_ph1approvalx(): prop#=0, prot-id=ISAKMP, spi-size=0, #trns=3 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:327:get_ph1approvalx(): trns#=1, trns-id=IKE 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Life Duration, flag=0x8000, lorv=3600 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:491:t2isakmpsa(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:338:get_ph1approvalx(): Compared: DB:Peer 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:339:get_ph1approvalx(): (lifetime = 28800:3600) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:341:get_ph1approvalx(): (lifebyte = 0:0) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:343:get_ph1approvalx(): enctype = 3DES-CBC:3DES-CBC 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:348:get_ph1approvalx(): (encklen = 0:0) 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:350:get_ph1approvalx(): hashtype = SHA:SHA 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:355:get_ph1approvalx(): authmethod = pre-shared key:pre-shared key 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:360:get_ph1approvalx(): dh_group = 1024-bit MODP group:1024-bit MODP group 2003-04-07 10:49:37: DEBUG: ipsec_doi.c:248:get_ph1approval(): an acceptable proposal found. 2003-04-07 10:49:37: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:49:37: DEBUG: isakmp.c:1996:isakmp_newcookie(): new cookie: 351fa4e0b6e19438 2003-04-07 10:49:37: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 48, next type 13 2003-04-07 10:49:37: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 0 2003-04-07 10:49:37: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:37: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-04-07 10:49:37: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-04-07 10:49:37: DEBUG: sockmisc.c:425:sendfromto(): send packet to 216.144.184.7[500] 2003-04-07 10:49:37: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 100 bytes message will be sent to 63.202.92.133[500] 2003-04-07 10:49:37: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 01100200 00000000 00000064 0d000034 00000001 00000001 00000028 00010001 00000020 01010000 800b0001 800c0e10 80010005 80020002 80030001 80040002 00000014 7003cbc1 097dbe9c 2600ba69 83bc8b35 2003-04-07 10:49:37: DEBUG: isakmp.c:1449:isakmp_ph1resend(): resend phase1 packet 2c917f7d76020366:351fa4e0b6e19438 2003-04-07 10:49:37: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:49:37: DEBUG: isakmp.c:222:isakmp_handler(): 180 bytes message received from 216.144.184.7[500] 2003-04-07 10:49:37: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 04100200 00000000 000000b4 0a000084 0b09f9d6 1a687303 a16b0a61 72c1af04 7ca47e62 a392913e c7a7e817 da03026e aa91ce10 a53fbb9f 67c965cc d29a3fcf a1b094e2 6457a0a8 81f0ff36 5ce3241f 415750cf 580f5d75 1b9361b5 b68d5641 f8674869 915d9995 7ae20df5 fbab84fa e7e76acf 9db98c37 90de7503 af446968 c4c2e7c8 0e6a3421 ccb7893f 96f64754 00000014 460b986f 604db93f faed492d 7f21861b 2003-04-07 10:49:37: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:37: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:37: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=4(ke) 2003-04-07 10:49:37: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=10(nonce) 2003-04-07 10:49:37: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:37: DEBUG: isakmp.c:622:ph1_main(): === 2003-04-07 10:49:37: DEBUG: oakley.c:256:oakley_dh_generate(): compute DH's private. 2003-04-07 10:49:37: DEBUG: plog.c:193:plogdump(): 58913cce 8e6af2cf fc835c83 4182be11 37bd69bd ff000865 6e026718 f2d2b57a abee4a85 fbc36532 404e3ce5 2cdfb6d4 d5c19d87 3f95fbe5 c877a875 507e2194 a884ac30 646fd464 c5faf5dd e21f739a 8c497159 e8f84e8b cd8c9995 39b3c0ae dec44a94 7d97f85d 6de279e0 99193eb2 64f4cbe8 dda157ff 066973b7 3b706802 2003-04-07 10:49:37: DEBUG: oakley.c:258:oakley_dh_generate(): compute DH's public. 2003-04-07 10:49:37: DEBUG: plog.c:193:plogdump(): 17017936 1154ecc5 15e70fa2 ceacc421 12255d8c 167ce076 bf61eb3e 54014ee7 92523d27 98b88a97 6dda8953 edf9d209 1095ea54 3c07f628 3e53177b f8ec36bc 09075d0d d903c2b6 824969aa 8d3d807a 8bc4350e 749f6046 0d630602 637beb38 3ab6ed0a c9a23d9d 9d915f1b 03fd8fd4 cfa5b8fc f3458283 6a6230a5 48fec945 2003-04-07 10:49:37: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 128, next type 10 2003-04-07 10:49:37: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 13 2003-04-07 10:49:37: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 0 2003-04-07 10:49:37: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:37: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-04-07 10:49:37: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-04-07 10:49:37: DEBUG: sockmisc.c:425:sendfromto(): send packet to 216.144.184.7[500] 2003-04-07 10:49:37: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 200 bytes message will be sent to 63.202.92.133[500] 2003-04-07 10:49:37: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 04100200 00000000 000000c8 0a000084 17017936 1154ecc5 15e70fa2 ceacc421 12255d8c 167ce076 bf61eb3e 54014ee7 92523d27 98b88a97 6dda8953 edf9d209 1095ea54 3c07f628 3e53177b f8ec36bc 09075d0d d903c2b6 824969aa 8d3d807a 8bc4350e 749f6046 0d630602 637beb38 3ab6ed0a c9a23d9d 9d915f1b 03fd8fd4 cfa5b8fc f3458283 6a6230a5 48fec945 0d000014 ca62c732 5f9c56a9 20f6684e 311de3f6 00000014 7003cbc1 097dbe9c 2600ba69 83bc8b35 2003-04-07 10:49:37: DEBUG: isakmp.c:1449:isakmp_ph1resend(): resend phase1 packet 2c917f7d76020366:351fa4e0b6e19438 2003-04-07 10:49:37: DEBUG: oakley.c:210:oakley_dh_compute(): compute DH's shared. 2003-04-07 10:49:37: DEBUG: plog.c:193:plogdump(): 9784566f 48304aa5 37f9baae ca6d2ee4 e891cae8 ae591a14 73922651 b8264a3e ed05db00 09fcc90b 820ab604 17ada316 d0268c64 5640137f 209edf69 71d075b3 7d85c3ff 81f66055 eff9b33b 4be39665 d8e97884 52aab722 c3a5818c 83958233 5feb4de5 118b33ed 2f5df7ff ff91491c 5b5644eb 439d81d2 13b4947f d60b04f9 2003-04-07 10:49:37: DEBUG: oakley.c:2063:oakley_skeyid(): the psk found. 2003-04-07 10:49:37: DEBUG2: oakley.c:2065:oakley_skeyid(): psk: 2003-04-07 10:49:37: DEBUG2: plog.c:193:plogdump(): 6d656b6d 69746173 6469676f 6174 2003-04-07 10:49:37: DEBUG: oakley.c:2078:oakley_skeyid(): nonce 1: 2003-04-07 10:49:37: DEBUG: plog.c:193:plogdump(): 460b986f 604db93f faed492d 7f21861b 2003-04-07 10:49:37: DEBUG: oakley.c:2084:oakley_skeyid(): nonce 2: 2003-04-07 10:49:37: DEBUG: plog.c:193:plogdump(): ca62c732 5f9c56a9 20f6684e 311de3f6 2003-04-07 10:49:37: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:2137:oakley_skeyid(): SKEYID computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 1f33c5a6 0298b2a5 829b22e4 e906824a c5f0efd9 2003-04-07 10:49:38: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:2194:oakley_skeyid_dae(): SKEYID_d computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): bd28239d 983c79d4 ae5997cc 0d84fe54 d49e80b7 2003-04-07 10:49:38: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:2223:oakley_skeyid_dae(): SKEYID_a computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 86a7f128 68300d29 68bd6ae4 25c751d7 0d291bd5 2003-04-07 10:49:38: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:2252:oakley_skeyid_dae(): SKEYID_e computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 02a00dae 613e90ef 3a3175f5 7edd0b3b 78621b59 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:2321:oakley_compute_enckey(): len(SKEYID_e) < len(Ka) (20 < 24), generating long key (Ka = K1 | K2 | ...) 2003-04-07 10:49:38: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:2346:oakley_compute_enckey(): compute intermediate encryption key K1 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 00 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 2003-04-07 10:49:38: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:2346:oakley_compute_enckey(): compute intermediate encryption key K2 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 82963f1e cd89bf3d b604a7d8 6081ecab fde9af2b 2003-04-07 10:49:38: DEBUG: oakley.c:2394:oakley_compute_enckey(): final encryption key computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:49:38: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2505:oakley_newiv(): IV computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 0b8ef510 68564f10 2003-04-07 10:49:38: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:49:38: DEBUG: isakmp.c:222:isakmp_handler(): 68 bytes message received from 216.144.184.7[500] 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 05100201 00000000 00000044 887ae761 75c5c2f0 afa2cc90 9302c4a7 718f1d49 fe78f43b 8e714045 3b966fb8 ddc7339a 0a3f1006 2003-04-07 10:49:38: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:38: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): ddc7339a 0a3f1006 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:49:38: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): ddc7339a 0a3f1006 2003-04-07 10:49:38: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 0800000c 01000000 d890b807 00000018 8bea0110 ea34d065 d657397c ea88cd77 d5addf7c 00000000 2003-04-07 10:49:38: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=1 2003-04-07 10:49:38: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-04-07 10:49:38: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 05100201 00000000 00000044 0800000c 01000000 d890b807 00000018 8bea0110 ea34d065 d657397c ea88cd77 d5addf7c 00000000 2003-04-07 10:49:38: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:38: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-04-07 10:49:38: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:38: DEBUG: oakley.c:1162:oakley_validate_auth(): HASH received:2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 8bea0110 ea34d065 d657397c ea88cd77 d5addf7c 2003-04-07 10:49:38: DEBUG: oakley.c:867:oakley_ph1hash_common(): HASH with: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 0b09f9d6 1a687303 a16b0a61 72c1af04 7ca47e62 a392913e c7a7e817 da03026e aa91ce10 a53fbb9f 67c965cc d29a3fcf a1b094e2 6457a0a8 81f0ff36 5ce3241f 415750cf 580f5d75 1b9361b5 b68d5641 f8674869 915d9995 7ae20df5 fbab84fa e7e76acf 9db98c37 90de7503 af446968 c4c2e7c8 0e6a3421 ccb7893f 96f64754 17017936 1154ecc5 15e70fa2 ceacc421 12255d8c 167ce076 bf61eb3e 54014ee7 92523d27 98b88a97 6dda8953 edf9d209 1095ea54 3c07f628 3e53177b f8ec36bc 09075d0d d903c2b6 824969aa 8d3d807a 8bc4350e 749f6046 0d630602 637beb38 3ab6ed0a c9a23d9d 9d915f1b 03fd8fd4 cfa5b8fc f3458283 6a6230a5 48fec945 2c917f7d 76020366 351fa4e0 b6e19438 00000001 00000001 00000068 00010003 03000020 00010000 800b0001 800c0e10 80010005 80020002 80030001 80040005 03000020 01010000 800b0001 800c0e10 80010005 80020002 80030001 80040002 00000020 02010000 800b0001 800c0e10 80010005 80020002 80030001 80040001 01000000 d890b807 2003-04-07 10:49:38: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:877:oakley_ph1hash_common(): HASH computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 8bea0110 ea34d065 d657397c ea88cd77 d5addf7c 2003-04-07 10:49:38: DEBUG: oakley.c:1193:oakley_validate_auth(): HASH for PSK validated. 2003-04-07 10:49:38: DEBUG: isakmp_ident.c:1250:ident_r3recv(): peer's ID 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 01000000 d890b807 2003-04-07 10:49:38: DEBUG: isakmp.c:622:ph1_main(): === 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:3185:ipsecdoi_setid1(): use ID type of IPv4_address 2003-04-07 10:49:38: DEBUG: isakmp_ident.c:1327:ident_r3send(): generate HASH_R 2003-04-07 10:49:38: DEBUG: oakley.c:867:oakley_ph1hash_common(): HASH with: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 17017936 1154ecc5 15e70fa2 ceacc421 12255d8c 167ce076 bf61eb3e 54014ee7 92523d27 98b88a97 6dda8953 edf9d209 1095ea54 3c07f628 3e53177b f8ec36bc 09075d0d d903c2b6 824969aa 8d3d807a 8bc4350e 749f6046 0d630602 637beb38 3ab6ed0a c9a23d9d 9d915f1b 03fd8fd4 cfa5b8fc f3458283 6a6230a5 48fec945 0b09f9d6 1a687303 a16b0a61 72c1af04 7ca47e62 a392913e c7a7e817 da03026e aa91ce10 a53fbb9f 67c965cc d29a3fcf a1b094e2 6457a0a8 81f0ff36 5ce3241f 415750cf 580f5d75 1b9361b5 b68d5641 f8674869 915d9995 7ae20df5 fbab84fa e7e76acf 9db98c37 90de7503 af446968 c4c2e7c8 0e6a3421 ccb7893f 96f64754 351fa4e0 b6e19438 2c917f7d 76020366 00000001 00000001 00000068 00010003 03000020 00010000 800b0001 800c0e10 80010005 80020002 80030001 80040005 03000020 01010000 800b0001 800c0e10 80010005 80020002 80030001 80040002 00000020 02010000 800b0001 800c0e10 80010005 80020002 80030001 80040001 011101f4 3fca5c85 2003-04-07 10:49:38: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:877:oakley_ph1hash_common(): HASH computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): b833e0db d2fe6e8f 90ae88da 8d9c39e9 14c772f3 2003-04-07 10:49:38: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 8, next type 8 2003-04-07 10:49:38: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 20, next type 0 2003-04-07 10:49:38: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:38: DEBUG: oakley.c:2748:oakley_do_encrypt(): begin encryption. 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2764:oakley_do_encrypt(): pad length = 4 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 0800000c 011101f4 3fca5c85 00000018 b833e0db d2fe6e8f 90ae88da 8d9c39e9 14c772f3 4dfc9f03 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2799:oakley_do_encrypt(): with key: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:49:38: DEBUG: oakley.c:2807:oakley_do_encrypt(): encrypted payload by IV: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): d3786398 6fe2afb6 2003-04-07 10:49:38: DEBUG: oakley.c:2814:oakley_do_encrypt(): save IV for next: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): d3786398 6fe2afb6 2003-04-07 10:49:38: DEBUG: oakley.c:2831:oakley_do_encrypt(): encrypted. 2003-04-07 10:49:38: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-04-07 10:49:38: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-04-07 10:49:38: DEBUG: sockmisc.c:425:sendfromto(): send packet to 216.144.184.7[500] 2003-04-07 10:49:38: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 68 bytes message will be sent to 63.202.92.133[500] 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 05100201 00000000 00000044 b126b346 bd12864a b630de53 8b1dd232 476f954f a573d152 a4d1216e 87766b25 d3786398 6fe2afb6 2003-04-07 10:49:38: DEBUG: oakley.c:2549:oakley_newiv2(): compute IV for phase2 2003-04-07 10:49:38: DEBUG: oakley.c:2550:oakley_newiv2(): phase1 last IV: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): d3786398 6fe2afb6 ec55552c 2003-04-07 10:49:38: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2582:oakley_newiv2(): phase2 IV computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 35af5e4d 5b2cbed1 2003-04-07 10:49:38: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): ec55552c 0000001c 00000001 01106002 2c917f7d 76020366 351fa4e0 b6e19438 2003-04-07 10:49:38: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): b6636a54 9fa5a673 532b2179 80cc1166 803dd1ea 2003-04-07 10:49:38: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:38: DEBUG: oakley.c:2748:oakley_do_encrypt(): begin encryption. 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2764:oakley_do_encrypt(): pad length = 4 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 0b000018 b6636a54 9fa5a673 532b2179 80cc1166 803dd1ea 0000001c 00000001 01106002 2c917f7d 76020366 351fa4e0 b6e19438 b7081503 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2799:oakley_do_encrypt(): with key: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:49:38: DEBUG: oakley.c:2807:oakley_do_encrypt(): encrypted payload by IV: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 04cc0ee9 1adf969f 2003-04-07 10:49:38: DEBUG: oakley.c:2814:oakley_do_encrypt(): save IV for next: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 04cc0ee9 1adf969f 2003-04-07 10:49:38: DEBUG: oakley.c:2831:oakley_do_encrypt(): encrypted. 2003-04-07 10:49:38: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-04-07 10:49:38: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-04-07 10:49:38: DEBUG: sockmisc.c:425:sendfromto(): send packet to 216.144.184.7[500] 2003-04-07 10:49:38: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 84 bytes message will be sent to 63.202.92.133[500] 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08100501 ec55552c 00000054 fde8ea24 d531de9f 41c2fb47 a83139cc fb725f55 b9ee4138 37ba4e50 c00fefb9 8edf5a57 55ae5598 86074bab bee01667 04cc0ee9 1adf969f 2003-04-07 10:49:38: DEBUG: isakmp_inf.c:634:isakmp_info_send_common(): sendto Information notify. 2003-04-07 10:49:38: INFO: isakmp.c:2412:log_ph1established(): ISAKMP-SA established 63.202.92.133[500]-216.144.184.7[500] spi:2c917f7d76020366:351fa4e0b6e19438 2003-04-07 10:49:38: DEBUG: isakmp.c:669:ph1_main(): === 2003-04-07 10:49:38: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:49:38: DEBUG: isakmp.c:222:isakmp_handler(): 292 bytes message received from 216.144.184.7[500] 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 835a7e30 00000124 67d0c127 f0fa6fa6 cd3eb611 9390c77c 54c02a7e edf3b74f a8f1195b 01f78481 1bacbcf8 4f230f46 dd2e66fe 9197196d 4a19e4f2 d7b9acc1 5d4a47db f74c4ed8 fe34a0d6 612c90ee d508daa5 7e70c4ce 8d8ae65a e7c34c62 d2ca657e e67c24c3 98de72f6 b8f47738 bb7b48c0 d65e0ae8 0e52ab50 c3e96866 b8b7ce9d 09304eae 4119a3dd 623f0c99 1879e320 f791f846 0efb666e cd40d181 a1110d6e 0185ab19 1d0bda5e 1b2e501f 07026264 5ebcc4e8 462d62a4 20887702 408236c5 cdf7231c 39c0bf8b 86cb7e97 57ff4761 f29b4243 03039651 34d58ad1 55547fa8 128cc637 135bca7d 5054d1de 07f1dba4 b85cd187 13bd0174 a77ad9dc 75b6ef9a dec8aefd 014430f7 03df19d9 2003-04-07 10:49:38: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:38: DEBUG: oakley.c:2549:oakley_newiv2(): compute IV for phase2 2003-04-07 10:49:38: DEBUG: oakley.c:2550:oakley_newiv2(): phase1 last IV: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): d3786398 6fe2afb6 835a7e30 2003-04-07 10:49:38: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2582:oakley_newiv2(): phase2 IV computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 258a2d32 a29a162b 2003-04-07 10:49:38: DEBUG: isakmp.c:1044:isakmp_ph2begin_r(): === 2003-04-07 10:49:38: INFO: isakmp.c:1049:isakmp_ph2begin_r(): respond new phase 2 negotiation: 63.202.92.133[0]<=>216.144.184.7[0] 2003-04-07 10:49:38: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 014430f7 03df19d9 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:49:38: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 014430f7 03df19d9 2003-04-07 10:49:38: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 01000018 97f27960 19f23b8f d01362d7 8dbcfed8 0beff1cb 0a000034 00000001 00000001 00000028 00030401 5da78fa9 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 c30cea1e dfd6ebbd f50ed385 cd1b48ad 05000084 8f35ddb9 3ac556c7 e888ddca a93d790c 9dc188f3 1fb34368 69844501 303e779c 1ed8a8cd 20976381 2f527e99 7530a8de e8fba69b bc6763d3 7cdd6260 ff5008dc d8f77d17 86fa6ab0 e62aafd8 d1b58bbe 3297e42f 91d7e20c 3511d6a2 a859d9b9 c3ee8761 c6c9e98a 8e5086ed 70ca9818 54956ba6 515489af 7a85b90d b9ec31ed 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 00000000 2003-04-07 10:49:38: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=1 2003-04-07 10:49:38: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-04-07 10:49:38: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 835a7e30 00000124 01000018 97f27960 19f23b8f d01362d7 8dbcfed8 0beff1cb 0a000034 00000001 00000001 00000028 00030401 5da78fa9 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 c30cea1e dfd6ebbd f50ed385 cd1b48ad 05000084 8f35ddb9 3ac556c7 e888ddca a93d790c 9dc188f3 1fb34368 69844501 303e779c 1ed8a8cd 20976381 2f527e99 7530a8de e8fba69b bc6763d3 7cdd6260 ff5008dc d8f77d17 86fa6ab0 e62aafd8 d1b58bbe 3297e42f 91d7e20c 3511d6a2 a859d9b9 c3ee8761 c6c9e98a 8e5086ed 70ca9818 54956ba6 515489af 7a85b90d b9ec31ed 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 00000000 2003-04-07 10:49:38: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:38: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=1(sa) 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=10(nonce) 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=4(ke) 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-04-07 10:49:38: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:38: DEBUG: isakmp_quick.c:1006:quick_r1recv(): received IDci2:2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 04000000 0a000562 ffffffff 2003-04-07 10:49:38: DEBUG: isakmp_quick.c:1010:quick_r1recv(): received IDcr2:2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 04000000 0a080807 ffffffff 2003-04-07 10:49:38: DEBUG: isakmp_quick.c:1025:quick_r1recv(): HASH(1) validate:2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 97f27960 19f23b8f d01362d7 8dbcfed8 0beff1cb 2003-04-07 10:49:38: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 835a7e30 0a000034 00000001 00000001 00000028 00030401 5da78fa9 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 c30cea1e dfd6ebbd f50ed385 cd1b48ad 05000084 8f35ddb9 3ac556c7 e888ddca a93d790c 9dc188f3 1fb34368 69844501 303e779c 1ed8a8cd 20976381 2f527e99 7530a8de e8fba69b bc6763d3 7cdd6260 ff5008dc d8f77d17 86fa6ab0 e62aafd8 d1b58bbe 3297e42f 91d7e20c 3511d6a2 a859d9b9 c3ee8761 c6c9e98a 8e5086ed 70ca9818 54956ba6 515489af 7a85b90d b9ec31ed 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 2003-04-07 10:49:38: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 97f27960 19f23b8f d01362d7 8dbcfed8 0beff1cb 2003-04-07 10:49:38: DEBUG: sainfo.c:100:getsainfo(): anonymous sainfo selected. 2003-04-07 10:49:38: DEBUG: isakmp_quick.c:1817:get_sainfo_r(): get sa info: anonymous 2003-04-07 10:49:38: DEBUG: isakmp_quick.c:1995:get_proposal_r(): get a src address from ID payload 10.0.5.98[0] prefixlen=32 ul_proto=255 2003-04-07 10:49:38: 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-04-07 10:49:38: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd37c: 10.0.5.98/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-04-07 10:49:38: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80a2c08: 10.0.5.98/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-04-07 10:49:38: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.0.5.98[0] 2003-04-07 10:49:38: DEBUG: policy.c:246:cmpspidxwild(): 0x80a2c08 masked with /32: 10.0.5.98[0] 2003-04-07 10:49:38: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.8.8.7[0] 2003-04-07 10:49:38: DEBUG: policy.c:262:cmpspidxwild(): 0x80a2c08 masked with /32: 10.8.8.7[0] 2003-04-07 10:49:38: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd37c: 10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:49:38: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80a2c08: 10.0.5.98/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-04-07 10:49:38: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd37c: 10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:49:38: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80ae008: 10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:49:38: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.8.8.7[0] 2003-04-07 10:49:38: DEBUG: policy.c:246:cmpspidxwild(): 0x80ae008 masked with /32: 10.8.8.7[0] 2003-04-07 10:49:38: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.0.5.98[0] 2003-04-07 10:49:38: DEBUG: policy.c:262:cmpspidxwild(): 0x80ae008 masked with /32: 10.0.5.98[0] 2003-04-07 10:49:38: DEBUG: isakmp_quick.c:2056:get_proposal_r(): suitable SP found:10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:49:38: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-04-07 10:49:38: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=48 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000028 00030401 5da78fa9 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 2003-04-07 10:49:38: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-04-07 10:49:38: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=40 2003-04-07 10:49:38: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-04-07 10:49:38: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=28 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-04-07 10:49:38: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-04-07 10:49:38: DEBUG: proposal.c:892:print_proppair0(): 0x80a8770: next=0x0 tnext=0x0 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 1 transform 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:948:get_ph2approval(): begin compare proposals. 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:954:get_ph2approval(): pair[0]: 0x80a8770 2003-04-07 10:49:38: DEBUG: proposal.c:892:print_proppair0(): 0x80a8770: next=0x0 tnext=0x0 2003-04-07 10:49:38: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=1 trns#=0 trns-id=3DES 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:990:get_ph2approvalx(): peer's single bundle: 2003-04-07 10:49:38: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=5da78fa9 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-04-07 10:49:38: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:993:get_ph2approvalx(): my single bundle: 2003-04-07 10:49:38: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-04-07 10:49:38: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1012:get_ph2approvalx(): matched 2003-04-07 10:49:38: DEBUG: isakmp.c:1078:isakmp_ph2begin_r(): === 2003-04-07 10:49:38: DEBUG: pfkey.c:792:pk_sendgetspi(): call pfkey_send_getspi 2003-04-07 10:49:38: DEBUG: pfkey.c:805:pk_sendgetspi(): pfkey GETSPI sent: ESP/Tunnel 216.144.184.7->63.202.92.133 2003-04-07 10:49:38: DEBUG: isakmp_quick.c:1143:quick_r1prep(): pfkey getspi sent. 2003-04-07 10:49:38: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey GETSPI message 2003-04-07 10:49:38: DEBUG2: plog.c:193:plogdump(): 02010003 0a000000 35d41073 45020000 02000100 04c8623d 00000000 00000000 03000500 ff200000 10020000 d890b807 00000000 00000000 03000600 ff200000 10020000 3fca5c85 00000000 00000000 2003-04-07 10:49:38: DEBUG: pfkey.c:876:pk_recvgetspi(): pfkey GETSPI succeeded: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=80241213(0x4c8623d) 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=48 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000028 00030401 00000000 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 2003-04-07 10:49:38: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-04-07 10:49:38: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=40 2003-04-07 10:49:38: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-04-07 10:49:38: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=28 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-04-07 10:49:38: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-04-07 10:49:38: DEBUG: proposal.c:892:print_proppair0(): 0x80a86b0: next=0x0 tnext=0x0 2003-04-07 10:49:38: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 1 transform 2003-04-07 10:49:38: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:49:38: DEBUG: oakley.c:256:oakley_dh_generate(): compute DH's private. 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 500a3741 f2414e87 309e22eb 14f18a4d f44861a7 849b9462 ce4ce296 1c77523c b6f91c29 c258514f 4580c998 4a90a54f 93dbb3f0 b019d000 10e0afd1 d2d39a93 2ec7fd71 46d81ddb 0063bc21 b4139c6d 799f0aa0 ad68ea01 b5536585 7884a91f 35d4657f 0c6059e0 6a8dd4d6 9ef4bb96 424fcb67 ae7a408f 29a9819f 67260dd7 2003-04-07 10:49:38: DEBUG: oakley.c:258:oakley_dh_generate(): compute DH's public. 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 6fcc1e94 82dae517 7b424ae3 cf92acec c7ee1bc5 19c0c2ca 3d72df3c 663b5377 4c071549 3320ed63 db0695a1 eff5e629 35c57aab 8d46e45b f2eb967a c71744db 79d0d46c 0761eb97 08b035a7 dc6b72e6 2dcfbd9d f22dd86b 621f2321 79f3de45 6328d11c be7fc1a4 41f00fe2 05256372 ece0a9e6 0785a30c 7360861e 0e40bee1 2003-04-07 10:49:38: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 48, next type 10 2003-04-07 10:49:38: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 4 2003-04-07 10:49:38: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 128, next type 5 2003-04-07 10:49:38: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 12, next type 5 2003-04-07 10:49:38: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 12, next type 0 2003-04-07 10:49:38: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 835a7e30 c30cea1e dfd6ebbd f50ed385 cd1b48ad 0a000034 00000001 00000001 00000028 00030401 04c8623d 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 b6177d9b 180b5f04 952a098e bafdb9d8 05000084 6fcc1e94 82dae517 7b424ae3 cf92acec c7ee1bc5 19c0c2ca 3d72df3c 663b5377 4c071549 3320ed63 db0695a1 eff5e629 35c57aab 8d46e45b f2eb967a c71744db 79d0d46c 0761eb97 08b035a7 dc6b72e6 2dcfbd9d f22dd86b 621f2321 79f3de45 6328d11c be7fc1a4 41f00fe2 05256372 ece0a9e6 0785a30c 7360861e 0e40bee1 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 2003-04-07 10:49:38: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:38: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 270a6711 7cd1b5b0 5e6aa5f4 f0f82df0 5a6c1250 2003-04-07 10:49:38: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 20, next type 1 2003-04-07 10:49:38: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:38: DEBUG: oakley.c:2748:oakley_do_encrypt(): begin encryption. 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2764:oakley_do_encrypt(): pad length = 4 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 01000018 270a6711 7cd1b5b0 5e6aa5f4 f0f82df0 5a6c1250 0a000034 00000001 00000001 00000028 00030401 04c8623d 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 b6177d9b 180b5f04 952a098e bafdb9d8 05000084 6fcc1e94 82dae517 7b424ae3 cf92acec c7ee1bc5 19c0c2ca 3d72df3c 663b5377 4c071549 3320ed63 db0695a1 eff5e629 35c57aab 8d46e45b f2eb967a c71744db 79d0d46c 0761eb97 08b035a7 dc6b72e6 2dcfbd9d f22dd86b 621f2321 79f3de45 6328d11c be7fc1a4 41f00fe2 05256372 ece0a9e6 0785a30c 7360861e 0e40bee1 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff ba2f4d03 2003-04-07 10:49:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:38: DEBUG: oakley.c:2799:oakley_do_encrypt(): with key: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:49:38: DEBUG: oakley.c:2807:oakley_do_encrypt(): encrypted payload by IV: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 8e4f7552 56e354d8 2003-04-07 10:49:38: DEBUG: oakley.c:2814:oakley_do_encrypt(): save IV for next: 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 8e4f7552 56e354d8 2003-04-07 10:49:38: DEBUG: oakley.c:2831:oakley_do_encrypt(): encrypted. 2003-04-07 10:49:38: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-04-07 10:49:38: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-04-07 10:49:38: DEBUG: sockmisc.c:425:sendfromto(): send packet to 216.144.184.7[500] 2003-04-07 10:49:38: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 292 bytes message will be sent to 63.202.92.133[500] 2003-04-07 10:49:38: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 835a7e30 00000124 dc8c6c5f 816b69ee 21baaae1 3546f6e5 71820793 15127729 939acb59 7cdc4b1d 2be94962 308258a8 e9e0e550 07356034 ef667f67 52abbd0f 6da0a0db fa62d83b 0d3c6ab6 07a35d41 6d8eaadf 1dad1d30 0bf311de fb170c84 4439ec52 c204a14a 494c80ed 97648bcf 1b2e2bc3 be8e9ed6 e98c2652 7285abfb ea745dd3 828cd456 f7aa6b31 ba01a4a6 d12f662c a36f0adb 1e046bfe 4ab3ae15 bf60c6eb ff201d11 adae873f 63a1df9a 6edd906d 0ff5410f 09bbf753 91aaa1a1 f7e74258 470ef2ea 380077aa 29e2ab7d dd30ee76 335f1122 b58b73cd 765e9ab4 5bf2ea6e 2a94180e 994d86df d7f7d4cd 94c03a6b 2d662b28 2f45e96c d9efc2da 3fe8fb2e 59c89f87 8e4f7552 56e354d8 2003-04-07 10:49:38: DEBUG: isakmp.c:1487:isakmp_ph2resend(): resend phase2 packet 2c917f7d76020366:351fa4e0b6e19438:0000835a 2003-04-07 10:49:39: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:49:39: DEBUG: isakmp.c:222:isakmp_handler(): 52 bytes message received from 216.144.184.7[500] 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 835a7e30 00000034 410da826 fe6b5f91 17b8884e 346f31a3 4d111fe6 87f6aae3 2003-04-07 10:49:39: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:39: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-04-07 10:49:39: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:39: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 4d111fe6 87f6aae3 2003-04-07 10:49:39: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:49:39: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:49:39: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 4d111fe6 87f6aae3 2003-04-07 10:49:39: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 00000018 46a63d7a 3e23f2fb 318bbe51 f56826f3 bbd68011 2003-04-07 10:49:39: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=18 2003-04-07 10:49:39: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-04-07 10:49:39: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 835a7e30 00000034 00000018 46a63d7a 3e23f2fb 318bbe51 f56826f3 bbd68011 2003-04-07 10:49:39: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:49:39: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:49:39: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-04-07 10:49:39: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:49:39: DEBUG: isakmp_quick.c:1430:quick_r3recv(): HASH(3) validate:2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 46a63d7a 3e23f2fb 318bbe51 f56826f3 bbd68011 2003-04-07 10:49:39: DEBUG: oakley.c:692:oakley_compute_hash3(): HASH with: 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 00835a7e 30c30cea 1edfd6eb bdf50ed3 85cd1b48 adb6177d 9b180b5f 04952a09 8ebafdb9 d8 2003-04-07 10:49:39: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: oakley.c:702:oakley_compute_hash3(): HASH computed: 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 46a63d7a 3e23f2fb 318bbe51 f56826f3 bbd68011 2003-04-07 10:49:39: DEBUG: isakmp.c:736:quick_main(): === 2003-04-07 10:49:39: DEBUG: oakley.c:210:oakley_dh_compute(): compute DH's shared. 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 3af5026a ab9f250c cc6badc2 f2579743 3c567ea7 4857d7c3 9c844906 9edfab99 39b940d8 154f296d 1b70b66b aec373c2 366305d6 1e1322f9 102c65c0 a0114589 886cdeeb 9483a92a a9a7f568 344c8614 58f9a0b6 ae04220e fb32f534 64497afb 798e54a6 623b76a4 039da8aa d64dca58 357e781d c8332dfb 62639062 059b78e0 2003-04-07 10:49:39: DEBUG: oakley.c:464:oakley_compute_keymat_x(): KEYMAT compute with 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 3af5026a ab9f250c cc6badc2 f2579743 3c567ea7 4857d7c3 9c844906 9edfab99 39b940d8 154f296d 1b70b66b aec373c2 366305d6 1e1322f9 102c65c0 a0114589 886cdeeb 9483a92a a9a7f568 344c8614 58f9a0b6 ae04220e fb32f534 64497afb 798e54a6 623b76a4 039da8aa d64dca58 357e781d c8332dfb 62639062 059b78e0 0304c862 3dc30cea 1edfd6eb bdf50ed3 85cd1b48 adb6177d 9b180b5f 04952a09 8ebafdb9 d8 2003-04-07 10:49:39: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:49:39: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: oakley.c:497:oakley_compute_keymat_x(): encklen=192 authklen=160 2003-04-07 10:49:39: DEBUG: oakley.c:504:oakley_compute_keymat_x(): generating 640 bits of key (dupkeymat=4) 2003-04-07 10:49:39: DEBUG: oakley.c:522:oakley_compute_keymat_x(): generating K1...K4 for KEYMAT. 2003-04-07 10:49:39: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 12221307 3de35d78 b6e022a2 66b3b28a 6b14b213 d64060ea d79ff128 30e26c28 5981cb73 83d5ed58 252268b7 253184e7 df38967f 37d6d3f4 7267ecce b0560890 1d7601ce 995b6eee 8969d200 0a8a47e0 2003-04-07 10:49:39: DEBUG: oakley.c:464:oakley_compute_keymat_x(): KEYMAT compute with 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 3af5026a ab9f250c cc6badc2 f2579743 3c567ea7 4857d7c3 9c844906 9edfab99 39b940d8 154f296d 1b70b66b aec373c2 366305d6 1e1322f9 102c65c0 a0114589 886cdeeb 9483a92a a9a7f568 344c8614 58f9a0b6 ae04220e fb32f534 64497afb 798e54a6 623b76a4 039da8aa d64dca58 357e781d c8332dfb 62639062 059b78e0 035da78f a9c30cea 1edfd6eb bdf50ed3 85cd1b48 adb6177d 9b180b5f 04952a09 8ebafdb9 d8 2003-04-07 10:49:39: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:49:39: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: oakley.c:497:oakley_compute_keymat_x(): encklen=192 authklen=160 2003-04-07 10:49:39: DEBUG: oakley.c:504:oakley_compute_keymat_x(): generating 640 bits of key (dupkeymat=4) 2003-04-07 10:49:39: DEBUG: oakley.c:522:oakley_compute_keymat_x(): generating K1...K4 for KEYMAT. 2003-04-07 10:49:39: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: plog.c:193:plogdump(): 8638a063 f5f24e98 4522b986 1b3e927f 53c5952d 065c7a31 44f5ff36 b6f1d359 8096cd40 5fdf4c86 2b90d7d8 f3c19a8e ad0c6612 1c8599c2 2dd92020 691e2842 fa03b10f 04f3c77a 3e8d95c8 76fd1678 2003-04-07 10:49:39: DEBUG: oakley.c:392:oakley_compute_keymat(): KEYMAT computed. 2003-04-07 10:49:39: DEBUG: isakmp_quick.c:1613:quick_r3prep(): call pk_sendupdate 2003-04-07 10:49:39: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:49:39: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: pfkey.c:974:pk_sendupdate(): call pfkey_send_update 2003-04-07 10:49:39: DEBUG: isakmp_quick.c:1618:quick_r3prep(): pfkey update sent. 2003-04-07 10:49:39: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:49:39: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:49:39: DEBUG: pfkey.c:1215:pk_sendadd(): call pfkey_send_add 2003-04-07 10:49:39: DEBUG: isakmp_quick.c:1625:quick_r3prep(): pfkey add sent. 2003-04-07 10:49:39: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey UPDATE message 2003-04-07 10:49:39: DEBUG2: plog.c:193:plogdump(): 02020003 14000000 35d41073 45020000 02000100 04c8623d 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 d890b807 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-04-07 10:49:39: DEBUG: pfkey.c:1103:pk_recvupdate(): pfkey UPDATE succeeded: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=80241213(0x4c8623d) 2003-04-07 10:49:39: INFO: pfkey.c:1110:pk_recvupdate(): IPsec-SA established: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=80241213(0x4c8623d) 2003-04-07 10:49:39: DEBUG: pfkey.c:1148:pk_recvupdate(): === 2003-04-07 10:49:39: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey ADD message 2003-04-07 10:49:39: DEBUG2: plog.c:193:plogdump(): 02030003 14000000 35d41073 45020000 02000100 5da78fa9 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 3fca5c85 00000000 00000000 03000600 ff200000 10020000 d890b807 00000000 00000000 04000300 00000000 00000000 00000000 b4000000 00000000 00000000 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 2003-04-07 10:49:39: INFO: pfkey.c:1322:pk_recvadd(): IPsec-SA established: ESP/Tunnel 63.202.92.133->216.144.184.7 spi=1571262377(0x5da78fa9) 2003-04-07 10:49:39: DEBUG: pfkey.c:1327:pk_recvadd(): === 2003-04-07 10:50:51: DEBUG: grabmyaddr.c:438:update_myaddrs(): msg 1 not interesting 2003-04-07 10:51:09: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:51:09: DEBUG: isakmp.c:222:isakmp_handler(): 292 bytes message received from 216.144.184.7[500] 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 1164855b 00000124 fd43f6a7 79bea7cb 31219938 ec9a4000 aaa1dc65 68c7e2d3 fc9503f8 f8fad081 536e5bcc 64f42401 5970aad3 bdd652e1 c3f1129c 40220d3c ebb704b4 5c16931d b8055f0f ee709563 17d73b71 d7f1f421 09b99e38 0dbcf881 f8661a14 5ad86de3 1800454f 7696fbb3 7d97b670 33de5274 d004d023 2de29cc2 dfc24e73 19c8fabe f624a4ac 9bfbb4bb 46a2547b 75e8b8f2 9d4da7ed 0db16abc 7b20b4e0 f842ce0e ea9abaef 796e25df 1c7d682b 271cd296 6c73627c 36cc0061 6bea3bb9 7fefb690 00bc3193 7af5cd2a 3eaada94 a6f1bfa5 aba7347a a365f5cb 88598c76 231596fc 0aa78c75 93eb3b90 67baf4b8 1ec416d2 38797957 f5be0414 938ae766 649fd4ef 62dfc037 cc9bffb2 2003-04-07 10:51:09: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:51:09: DEBUG: oakley.c:2549:oakley_newiv2(): compute IV for phase2 2003-04-07 10:51:09: DEBUG: oakley.c:2550:oakley_newiv2(): phase1 last IV: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): d3786398 6fe2afb6 1164855b 2003-04-07 10:51:09: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:51:09: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:51:09: DEBUG: oakley.c:2582:oakley_newiv2(): phase2 IV computed: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): db45bb50 7e3318bf 2003-04-07 10:51:09: DEBUG: isakmp.c:1044:isakmp_ph2begin_r(): === 2003-04-07 10:51:09: INFO: isakmp.c:1049:isakmp_ph2begin_r(): respond new phase 2 negotiation: 63.202.92.133[0]<=>216.144.184.7[0] 2003-04-07 10:51:09: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-04-07 10:51:09: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:51:09: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 62dfc037 cc9bffb2 2003-04-07 10:51:09: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:51:09: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:51:09: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 62dfc037 cc9bffb2 2003-04-07 10:51:09: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 01000018 b824aa23 fa33b1be 7e3a6bfc 9ff6a367 2b65a6c1 0a000034 00000001 00000001 00000028 00030401 5da78faa 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 260f4fdd 97d4e607 b095d69b 96c86a8e 05000084 35c814de 5aed4099 931c129c 9d7a89ba 32a86051 21eb7305 0af45300 196caa27 fde585a2 3fca54dd e6b620a1 6c14cfde be8cb710 bdc8ce0f f61b4363 25185f11 e7a87d1d 7d50e919 11bfd7a6 f6f4aa89 522a916e 479b5798 a72ab9d0 6bd81bab 14dce4b9 d3747d55 3090365e 0a54b530 b02cb0f1 449329e9 b4ffd698 12badf31 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 00000000 2003-04-07 10:51:09: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=1 2003-04-07 10:51:09: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-04-07 10:51:09: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 1164855b 00000124 01000018 b824aa23 fa33b1be 7e3a6bfc 9ff6a367 2b65a6c1 0a000034 00000001 00000001 00000028 00030401 5da78faa 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 260f4fdd 97d4e607 b095d69b 96c86a8e 05000084 35c814de 5aed4099 931c129c 9d7a89ba 32a86051 21eb7305 0af45300 196caa27 fde585a2 3fca54dd e6b620a1 6c14cfde be8cb710 bdc8ce0f f61b4363 25185f11 e7a87d1d 7d50e919 11bfd7a6 f6f4aa89 522a916e 479b5798 a72ab9d0 6bd81bab 14dce4b9 d3747d55 3090365e 0a54b530 b02cb0f1 449329e9 b4ffd698 12badf31 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 00000000 2003-04-07 10:51:09: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:51:09: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:51:09: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-04-07 10:51:09: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=1(sa) 2003-04-07 10:51:09: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=10(nonce) 2003-04-07 10:51:09: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=4(ke) 2003-04-07 10:51:09: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-04-07 10:51:09: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-04-07 10:51:09: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:51:09: DEBUG: isakmp_quick.c:1006:quick_r1recv(): received IDci2:2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 04000000 0a000562 ffffffff 2003-04-07 10:51:09: DEBUG: isakmp_quick.c:1010:quick_r1recv(): received IDcr2:2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 04000000 0a080807 ffffffff 2003-04-07 10:51:09: DEBUG: isakmp_quick.c:1025:quick_r1recv(): HASH(1) validate:2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): b824aa23 fa33b1be 7e3a6bfc 9ff6a367 2b65a6c1 2003-04-07 10:51:09: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 1164855b 0a000034 00000001 00000001 00000028 00030401 5da78faa 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 260f4fdd 97d4e607 b095d69b 96c86a8e 05000084 35c814de 5aed4099 931c129c 9d7a89ba 32a86051 21eb7305 0af45300 196caa27 fde585a2 3fca54dd e6b620a1 6c14cfde be8cb710 bdc8ce0f f61b4363 25185f11 e7a87d1d 7d50e919 11bfd7a6 f6f4aa89 522a916e 479b5798 a72ab9d0 6bd81bab 14dce4b9 d3747d55 3090365e 0a54b530 b02cb0f1 449329e9 b4ffd698 12badf31 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 2003-04-07 10:51:09: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:09: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): b824aa23 fa33b1be 7e3a6bfc 9ff6a367 2b65a6c1 2003-04-07 10:51:09: DEBUG: sainfo.c:100:getsainfo(): anonymous sainfo selected. 2003-04-07 10:51:09: DEBUG: isakmp_quick.c:1817:get_sainfo_r(): get sa info: anonymous 2003-04-07 10:51:09: DEBUG: isakmp_quick.c:1995:get_proposal_r(): get a src address from ID payload 10.0.5.98[0] prefixlen=32 ul_proto=255 2003-04-07 10:51:09: 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-04-07 10:51:09: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd37c: 10.0.5.98/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-04-07 10:51:09: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80a2c08: 10.0.5.98/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-04-07 10:51:09: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.0.5.98[0] 2003-04-07 10:51:09: DEBUG: policy.c:246:cmpspidxwild(): 0x80a2c08 masked with /32: 10.0.5.98[0] 2003-04-07 10:51:09: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.8.8.7[0] 2003-04-07 10:51:09: DEBUG: policy.c:262:cmpspidxwild(): 0x80a2c08 masked with /32: 10.8.8.7[0] 2003-04-07 10:51:09: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd37c: 10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:51:09: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80a2c08: 10.0.5.98/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-04-07 10:51:09: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd37c: 10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:51:09: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80ae008: 10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:51:09: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.8.8.7[0] 2003-04-07 10:51:09: DEBUG: policy.c:246:cmpspidxwild(): 0x80ae008 masked with /32: 10.8.8.7[0] 2003-04-07 10:51:09: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.0.5.98[0] 2003-04-07 10:51:09: DEBUG: policy.c:262:cmpspidxwild(): 0x80ae008 masked with /32: 10.0.5.98[0] 2003-04-07 10:51:09: DEBUG: isakmp_quick.c:2056:get_proposal_r(): suitable SP found:10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:51:09: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-04-07 10:51:09: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=48 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000028 00030401 5da78faa 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 2003-04-07 10:51:09: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:51:09: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-04-07 10:51:09: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=40 2003-04-07 10:51:09: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:51:09: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-04-07 10:51:09: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=28 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-04-07 10:51:09: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-04-07 10:51:09: DEBUG: proposal.c:892:print_proppair0(): 0x80a87e0: next=0x0 tnext=0x0 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 1 transform 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:948:get_ph2approval(): begin compare proposals. 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:954:get_ph2approval(): pair[0]: 0x80a87e0 2003-04-07 10:51:09: DEBUG: proposal.c:892:print_proppair0(): 0x80a87e0: next=0x0 tnext=0x0 2003-04-07 10:51:09: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=1 trns#=0 trns-id=3DES 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:990:get_ph2approvalx(): peer's single bundle: 2003-04-07 10:51:09: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=5da78faa spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-04-07 10:51:09: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:993:get_ph2approvalx(): my single bundle: 2003-04-07 10:51:09: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-04-07 10:51:09: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1012:get_ph2approvalx(): matched 2003-04-07 10:51:09: DEBUG: isakmp.c:1078:isakmp_ph2begin_r(): === 2003-04-07 10:51:09: DEBUG: pfkey.c:792:pk_sendgetspi(): call pfkey_send_getspi 2003-04-07 10:51:09: DEBUG: pfkey.c:805:pk_sendgetspi(): pfkey GETSPI sent: ESP/Tunnel 216.144.184.7->63.202.92.133 2003-04-07 10:51:09: DEBUG: isakmp_quick.c:1143:quick_r1prep(): pfkey getspi sent. 2003-04-07 10:51:09: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey GETSPI message 2003-04-07 10:51:09: DEBUG2: plog.c:193:plogdump(): 02010003 0a000000 40f08b3b 45020000 02000100 00b7c5f7 05060604 51281f68 03000500 ff200000 10020000 d890b807 00000000 00000000 03000600 ff200000 10020000 3fca5c85 00000000 00000000 2003-04-07 10:51:09: DEBUG: pfkey.c:876:pk_recvgetspi(): pfkey GETSPI succeeded: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=12043767(0xb7c5f7) 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=48 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000028 00030401 00000000 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 2003-04-07 10:51:09: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:51:09: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-04-07 10:51:09: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=40 2003-04-07 10:51:09: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:51:09: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-04-07 10:51:09: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=28 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-04-07 10:51:09: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-04-07 10:51:09: DEBUG: proposal.c:892:print_proppair0(): 0x80a8490: next=0x0 tnext=0x0 2003-04-07 10:51:09: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 1 transform 2003-04-07 10:51:09: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:51:09: DEBUG: oakley.c:256:oakley_dh_generate(): compute DH's private. 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 4b71775b 104678c8 879c8efc ebdea20d fa764200 33ab2349 fdc3a18e fb46c67e c3a07703 9463701f 2fb77409 42a6cd1e 1d2041d4 fc257ec0 cffcfb7f 37cfc515 0aa8353f 9f38479a 970c973b 690a41f1 33f28039 2e0f296f 00157e3c ed2fc682 9cf2c3a1 b6d5d035 ded77ebe f9f05849 3ef421c7 21170d79 6b8c24b2 dd88574b 2003-04-07 10:51:09: DEBUG: oakley.c:258:oakley_dh_generate(): compute DH's public. 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 3c425f3a d0034160 85f44623 d50f98f5 146aad2b 952f2df4 9bbfd7ff 6b56dc6a c6f2bf28 fc1f8091 ebb62dd2 7ee0ec11 8a893696 17315ac2 df12ebc7 05b908b9 12afb716 4b9f0a9c 46f4e504 1aef419c 589b0010 f9c0d357 c2a2405d bf101fbb c36d92a8 6fa1b981 cc83b245 a9a98390 a5f72a1e e66eb604 36056260 a00c8695 2003-04-07 10:51:09: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 48, next type 10 2003-04-07 10:51:09: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 4 2003-04-07 10:51:09: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 128, next type 5 2003-04-07 10:51:09: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 12, next type 5 2003-04-07 10:51:09: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 12, next type 0 2003-04-07 10:51:09: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 1164855b 260f4fdd 97d4e607 b095d69b 96c86a8e 0a000034 00000001 00000001 00000028 00030401 00b7c5f7 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 de6c9d3a b8d4d027 1ebd7e93 a8bcd372 05000084 3c425f3a d0034160 85f44623 d50f98f5 146aad2b 952f2df4 9bbfd7ff 6b56dc6a c6f2bf28 fc1f8091 ebb62dd2 7ee0ec11 8a893696 17315ac2 df12ebc7 05b908b9 12afb716 4b9f0a9c 46f4e504 1aef419c 589b0010 f9c0d357 c2a2405d bf101fbb c36d92a8 6fa1b981 cc83b245 a9a98390 a5f72a1e e66eb604 36056260 a00c8695 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 2003-04-07 10:51:09: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:09: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 74d93ee6 2b2b8b5a beb29a51 b374bbdd 57deae14 2003-04-07 10:51:09: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 20, next type 1 2003-04-07 10:51:09: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:51:09: DEBUG: oakley.c:2748:oakley_do_encrypt(): begin encryption. 2003-04-07 10:51:09: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:51:09: DEBUG: oakley.c:2764:oakley_do_encrypt(): pad length = 4 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 01000018 74d93ee6 2b2b8b5a beb29a51 b374bbdd 57deae14 0a000034 00000001 00000001 00000028 00030401 00b7c5f7 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 de6c9d3a b8d4d027 1ebd7e93 a8bcd372 05000084 3c425f3a d0034160 85f44623 d50f98f5 146aad2b 952f2df4 9bbfd7ff 6b56dc6a c6f2bf28 fc1f8091 ebb62dd2 7ee0ec11 8a893696 17315ac2 df12ebc7 05b908b9 12afb716 4b9f0a9c 46f4e504 1aef419c 589b0010 f9c0d357 c2a2405d bf101fbb c36d92a8 6fa1b981 cc83b245 a9a98390 a5f72a1e e66eb604 36056260 a00c8695 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 4eda6c03 2003-04-07 10:51:09: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:51:09: DEBUG: oakley.c:2799:oakley_do_encrypt(): with key: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:51:09: DEBUG: oakley.c:2807:oakley_do_encrypt(): encrypted payload by IV: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 25a8efc1 58270787 2003-04-07 10:51:09: DEBUG: oakley.c:2814:oakley_do_encrypt(): save IV for next: 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 25a8efc1 58270787 2003-04-07 10:51:09: DEBUG: oakley.c:2831:oakley_do_encrypt(): encrypted. 2003-04-07 10:51:09: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-04-07 10:51:09: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-04-07 10:51:09: DEBUG: sockmisc.c:425:sendfromto(): send packet to 216.144.184.7[500] 2003-04-07 10:51:09: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 292 bytes message will be sent to 63.202.92.133[500] 2003-04-07 10:51:09: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 1164855b 00000124 b3a51ae3 a54c2f8d 258de4e3 a3ae483b a642bd12 c9383c1e 6580cfab 4689ec92 0ba0bf8f 19cf9ecb 7c000359 335c4010 84567a4e 4744c345 0722bc69 8ea85d90 c29e3f88 9ac7470b ef1db572 794c8787 96edaf65 eb1a1ae2 f9380827 f77f18c2 837060fe 64b29736 f548710d af44eaa3 28f67d60 0b496f51 58bf209b ff125192 0815fe6b 3fc569de c5371cde be1935da 2f0833b3 446c7221 aadfe123 e3b0c300 77d5517f da627e41 b465c6d1 3ed9cbfc 6548f010 6f129515 15afd33b 4dc320ef 63498840 5421b3ed fac7e169 71b742b4 3029fca4 5931b128 82c83dca d13619fe e7120d89 d43e4302 288165d7 b597ce65 c20b0aa7 caa936fc 421733cf fd4794b0 25a8efc1 58270787 2003-04-07 10:51:09: DEBUG: isakmp.c:1487:isakmp_ph2resend(): resend phase2 packet 2c917f7d76020366:351fa4e0b6e19438:00001164 2003-04-07 10:51:10: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:51:10: DEBUG: isakmp.c:222:isakmp_handler(): 52 bytes message received from 216.144.184.7[500] 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 1164855b 00000034 51fb4ce6 44e77aa3 87fb6ff0 52b7e82c ab4a22e3 731f78c8 2003-04-07 10:51:10: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:51:10: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-04-07 10:51:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:51:10: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): ab4a22e3 731f78c8 2003-04-07 10:51:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:51:10: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:51:10: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): ab4a22e3 731f78c8 2003-04-07 10:51:10: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 00000018 91c49fa0 e525efc6 41062216 5e3201b5 718077ab 2003-04-07 10:51:10: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=172 2003-04-07 10:51:10: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-04-07 10:51:10: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 1164855b 00000034 00000018 91c49fa0 e525efc6 41062216 5e3201b5 718077ab 2003-04-07 10:51:10: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:51:10: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:51:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-04-07 10:51:10: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:51:10: DEBUG: isakmp_quick.c:1430:quick_r3recv(): HASH(3) validate:2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 91c49fa0 e525efc6 41062216 5e3201b5 718077ab 2003-04-07 10:51:10: DEBUG: oakley.c:692:oakley_compute_hash3(): HASH with: 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 00116485 5b260f4f dd97d4e6 07b095d6 9b96c86a 8ede6c9d 3ab8d4d0 271ebd7e 93a8bcd3 72 2003-04-07 10:51:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: oakley.c:702:oakley_compute_hash3(): HASH computed: 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 91c49fa0 e525efc6 41062216 5e3201b5 718077ab 2003-04-07 10:51:10: DEBUG: isakmp.c:736:quick_main(): === 2003-04-07 10:51:10: DEBUG: oakley.c:210:oakley_dh_compute(): compute DH's shared. 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 004dda4d c2511a42 243c0e89 0c0f3072 7a8c4a33 4f421358 f7cdbe21 99987d60 f6a8f4db b167f3e8 6a33fec0 2902e1d9 75dadc5d c7efd374 04f7d478 9bd8776d 3654ae11 f75dee38 1c16e1f6 a989529b 7a20732b 99061d68 63030c32 319cf1f4 c25b5721 61919c3e 5c1514cd ab8e9e45 9d7e6c83 60bc2106 0eae5355 c8bf04af 2003-04-07 10:51:10: DEBUG: oakley.c:464:oakley_compute_keymat_x(): KEYMAT compute with 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 004dda4d c2511a42 243c0e89 0c0f3072 7a8c4a33 4f421358 f7cdbe21 99987d60 f6a8f4db b167f3e8 6a33fec0 2902e1d9 75dadc5d c7efd374 04f7d478 9bd8776d 3654ae11 f75dee38 1c16e1f6 a989529b 7a20732b 99061d68 63030c32 319cf1f4 c25b5721 61919c3e 5c1514cd ab8e9e45 9d7e6c83 60bc2106 0eae5355 c8bf04af 0300b7c5 f7260f4f dd97d4e6 07b095d6 9b96c86a 8ede6c9d 3ab8d4d0 271ebd7e 93a8bcd3 72 2003-04-07 10:51:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:51:10: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: oakley.c:497:oakley_compute_keymat_x(): encklen=192 authklen=160 2003-04-07 10:51:10: DEBUG: oakley.c:504:oakley_compute_keymat_x(): generating 640 bits of key (dupkeymat=4) 2003-04-07 10:51:10: DEBUG: oakley.c:522:oakley_compute_keymat_x(): generating K1...K4 for KEYMAT. 2003-04-07 10:51:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 4bc92cf3 bf03556b 81a79d0c 7455d98d fa868220 28b839e5 604a1e20 ffde8bb4 fb8f9bb2 75cb7164 a096fd41 b79ac576 1039f62b b27ccdbc a703252a a78bc754 2d17136e 6441b3c1 6e699ed0 71c053a6 2003-04-07 10:51:10: DEBUG: oakley.c:464:oakley_compute_keymat_x(): KEYMAT compute with 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): 004dda4d c2511a42 243c0e89 0c0f3072 7a8c4a33 4f421358 f7cdbe21 99987d60 f6a8f4db b167f3e8 6a33fec0 2902e1d9 75dadc5d c7efd374 04f7d478 9bd8776d 3654ae11 f75dee38 1c16e1f6 a989529b 7a20732b 99061d68 63030c32 319cf1f4 c25b5721 61919c3e 5c1514cd ab8e9e45 9d7e6c83 60bc2106 0eae5355 c8bf04af 035da78f aa260f4f dd97d4e6 07b095d6 9b96c86a 8ede6c9d 3ab8d4d0 271ebd7e 93a8bcd3 72 2003-04-07 10:51:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:51:10: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: oakley.c:497:oakley_compute_keymat_x(): encklen=192 authklen=160 2003-04-07 10:51:10: DEBUG: oakley.c:504:oakley_compute_keymat_x(): generating 640 bits of key (dupkeymat=4) 2003-04-07 10:51:10: DEBUG: oakley.c:522:oakley_compute_keymat_x(): generating K1...K4 for KEYMAT. 2003-04-07 10:51:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: plog.c:193:plogdump(): c00891c2 ac233f7d 38c2eaa2 775e2562 12b4c7df c9f3315d 1b036a83 d826a1ee 23c8ffac f7765e99 db677b31 d9f41b40 97123e0e 648fabe4 26309b0c 9b41bb57 a51582cc 3012d2d3 5bbc344b ca5e5ac2 2003-04-07 10:51:10: DEBUG: oakley.c:392:oakley_compute_keymat(): KEYMAT computed. 2003-04-07 10:51:10: DEBUG: isakmp_quick.c:1613:quick_r3prep(): call pk_sendupdate 2003-04-07 10:51:10: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:51:10: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: pfkey.c:974:pk_sendupdate(): call pfkey_send_update 2003-04-07 10:51:10: DEBUG: isakmp_quick.c:1618:quick_r3prep(): pfkey update sent. 2003-04-07 10:51:10: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:51:10: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:51:10: DEBUG: pfkey.c:1215:pk_sendadd(): call pfkey_send_add 2003-04-07 10:51:10: DEBUG: isakmp_quick.c:1625:quick_r3prep(): pfkey add sent. 2003-04-07 10:51:10: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey UPDATE message 2003-04-07 10:51:10: DEBUG2: plog.c:193:plogdump(): 02020003 14000000 40f08b3b 45020000 02000100 00b7c5f7 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 d890b807 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-04-07 10:51:10: DEBUG: pfkey.c:1103:pk_recvupdate(): pfkey UPDATE succeeded: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=12043767(0xb7c5f7) 2003-04-07 10:51:10: INFO: pfkey.c:1110:pk_recvupdate(): IPsec-SA established: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=12043767(0xb7c5f7) 2003-04-07 10:51:10: DEBUG: pfkey.c:1148:pk_recvupdate(): === 2003-04-07 10:51:10: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey ADD message 2003-04-07 10:51:10: DEBUG2: plog.c:193:plogdump(): 02030003 14000000 40f08b3b 45020000 02000100 5da78faa 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 3fca5c85 00000000 00000000 03000600 ff200000 10020000 d890b807 00000000 00000000 04000300 00000000 00000000 00000000 b4000000 00000000 00000000 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 2003-04-07 10:51:10: INFO: pfkey.c:1322:pk_recvadd(): IPsec-SA established: ESP/Tunnel 63.202.92.133->216.144.184.7 spi=1571262378(0x5da78faa) 2003-04-07 10:51:10: DEBUG: pfkey.c:1327:pk_recvadd(): === 2003-04-07 10:52:04: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey EXPIRE message 2003-04-07 10:52:04: DEBUG2: plog.c:193:plogdump(): 02080003 14000200 35d41073 00000000 02000100 5da78fa9 04020303 00000000 02001300 02000000 07000000 00000000 04000200 07000000 28060000 00000000 33ba913e 00000000 7eba913e 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 03000500 ff200000 10020000 3fca5c85 00000000 00000000 03000600 ff200000 10020000 d890b807 00000000 00000000 2003-04-07 10:52:04: INFO: pfkey.c:1368:pk_recvexpire(): IPsec-SA expired: ESP/Tunnel 63.202.92.133->216.144.184.7 spi=1571262377(0x5da78fa9) 2003-04-07 10:52:04: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey EXPIRE message 2003-04-07 10:52:04: DEBUG2: plog.c:193:plogdump(): 02080003 14000100 35d41073 00000000 02000100 04c8623d 04020303 00000000 02001300 02000000 07000000 00000000 04000200 07000000 9a020000 00000000 33ba913e 00000000 7eba913e 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 03000500 ff200000 10020000 d890b807 00000000 00000000 03000600 ff200000 10020000 3fca5c85 00000000 00000000 2003-04-07 10:52:04: INFO: pfkey.c:1368:pk_recvexpire(): IPsec-SA expired: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=80241213(0x4c8623d) 2003-04-07 10:52:04: DEBUG: pfkey.c:1380:pk_recvexpire(): no such a SA found: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=80241213(0x4c8623d) 2003-04-07 10:52:38: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:52:38: DEBUG: isakmp.c:222:isakmp_handler(): 68 bytes message received from 216.144.184.7[500] 2003-04-07 10:52:38: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08100501 2c4f402b 00000044 074c3d83 82c3a582 4334b428 e0f5b11e 03c135b2 57f562b0 ecd21df0 47c565c9 9728715e d7d1facd 2003-04-07 10:52:38: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:52:38: DEBUG: isakmp_inf.c:115:isakmp_info_recv(): receive Information. 2003-04-07 10:52:38: DEBUG: oakley.c:2549:oakley_newiv2(): compute IV for phase2 2003-04-07 10:52:38: DEBUG: oakley.c:2550:oakley_newiv2(): phase1 last IV: 2003-04-07 10:52:38: DEBUG: plog.c:193:plogdump(): d3786398 6fe2afb6 2c4f402b 2003-04-07 10:52:38: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:52:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:52:38: DEBUG: oakley.c:2582:oakley_newiv2(): phase2 IV computed: 2003-04-07 10:52:38: DEBUG: plog.c:193:plogdump(): 01957fb8 9363cce8 2003-04-07 10:52:38: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-04-07 10:52:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:52:38: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-04-07 10:52:38: DEBUG: plog.c:193:plogdump(): 9728715e d7d1facd 2003-04-07 10:52:38: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:52:38: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-04-07 10:52:38: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:52:38: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-04-07 10:52:38: DEBUG: plog.c:193:plogdump(): 9728715e d7d1facd 2003-04-07 10:52:38: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-04-07 10:52:38: DEBUG: plog.c:193:plogdump(): 0c000018 2293631d fa45d15d 5fb97078 e8a59856 e5ec1a68 00000010 00000001 03040001 5da78fa9 2003-04-07 10:52:38: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=170 2003-04-07 10:52:38: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-04-07 10:52:38: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-04-07 10:52:38: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08100501 2c4f402b 00000044 0c000018 2293631d fa45d15d 5fb97078 e8a59856 e5ec1a68 00000010 00000001 03040001 5da78fa9 2003-04-07 10:52:38: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:52:38: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:52:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-04-07 10:52:38: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=12(delete) 2003-04-07 10:52:38: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:52:38: DEBUG: pfkey.c:271:pfkey_dump_sadb(): call pfkey_send_dump 2003-04-07 10:52:38: DEBUG: isakmp_inf.c:902:purge_ipsec_spi(): check spi(packet)=1571262377 spi(db)=1571262378. 2003-04-07 10:52:38: DEBUG: isakmp_inf.c:902:purge_ipsec_spi(): check spi(packet)=1571262377 spi(db)=1571262377. 2003-04-07 10:52:38: INFO: isakmp_inf.c:925:purge_ipsec_spi(): purged IPsec-SA proto_id=ESP spi=1571262377. 2003-04-07 10:52:38: DEBUG: isakmp_inf.c:1249:isakmp_info_recv_d(): purged SAs. 2003-04-07 10:52:38: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey DELETE message 2003-04-07 10:52:38: DEBUG2: plog.c:193:plogdump(): 02040003 0a000000 00000000 45020000 02000100 5da78fa9 00000000 00000000 03000500 ff200000 10020000 3fca5c85 00000000 00000000 03000600 ff200000 10020000 d890b807 00000000 00000000 2003-04-07 10:52:38: DEBUG: pfkey.c:1674:pk_recvdelete(): DELETE message is not interesting because the message was originated by me. 2003-04-07 10:53:10: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:53:10: DEBUG: isakmp.c:222:isakmp_handler(): 292 bytes message received from 216.144.184.7[500] 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 e4f27700 00000124 e8d6f777 181e3d0d b4f1aee4 774891be e682e7f9 64412613 e9eb7b72 a9b3e094 90a15798 55f0ae7a 8e842bda 8953e2db 359fbe4d 6c7e4af2 b9f706d2 32ce13d6 af86d8fd d89ee908 f843ee8b 199713f2 77c68371 a92fed7e 0a0167fb a9ca9bb3 039f9910 e3498737 a33fc68b abe7edbf d0383756 00202eeb 2286f05e 83b5fb8d dc5c5c2f 81b092c4 1f23a16e ef054823 3d0e35c8 1a272e88 7ea34957 8ad5792d 4c9e327a 42bd0916 ad7bc112 6a8d7747 80149f81 8e2314ae 556724e5 0515728c a43f7a1d f91ba3b1 4f9e2dec 2e9dfbe1 021be04a f5c7fe89 481061c8 3530be2b 2d0c2a09 621c22fb 8c48b3b0 cc7c5eea d990c645 e743df0e 6a200e09 9ffb3602 d1ca48c4 6981f38e 2003-04-07 10:53:10: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:53:10: DEBUG: oakley.c:2549:oakley_newiv2(): compute IV for phase2 2003-04-07 10:53:10: DEBUG: oakley.c:2550:oakley_newiv2(): phase1 last IV: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): d3786398 6fe2afb6 e4f27700 2003-04-07 10:53:10: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:53:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: oakley.c:2582:oakley_newiv2(): phase2 IV computed: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): fb2d45d4 ddb0d067 2003-04-07 10:53:10: DEBUG: isakmp.c:1044:isakmp_ph2begin_r(): === 2003-04-07 10:53:10: INFO: isakmp.c:1049:isakmp_ph2begin_r(): respond new phase 2 negotiation: 63.202.92.133[0]<=>216.144.184.7[0] 2003-04-07 10:53:10: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-04-07 10:53:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): d1ca48c4 6981f38e 2003-04-07 10:53:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:53:10: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): d1ca48c4 6981f38e 2003-04-07 10:53:10: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 01000018 03a954de 97ba7417 fd6e024c 0dd822c8 0b378d39 0a000034 00000001 00000001 00000028 00030401 5da78fab 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 46f0d7c0 ba771ea7 aca73b6f 4ee91194 05000084 f0d4b27b e4bc1b8e 24e763a4 fa5b7b53 46f12358 68ff0e3e c408c18a f25b2e14 8b648d71 53628abd d3c9b865 600dc8a5 332d1276 fcf599eb 58566a84 413e75a1 05624bab ff7e535a 1a00a791 e0dbe002 cab9208b 980d1db7 8db5ea49 dd54ec3b f1601707 5afc8a35 46ef8e4e 1fe0c0a4 8fdb8158 14e6cad2 909698c5 aed15df3 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 00000000 2003-04-07 10:53:10: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=1 2003-04-07 10:53:10: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-04-07 10:53:10: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 e4f27700 00000124 01000018 03a954de 97ba7417 fd6e024c 0dd822c8 0b378d39 0a000034 00000001 00000001 00000028 00030401 5da78fab 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 46f0d7c0 ba771ea7 aca73b6f 4ee91194 05000084 f0d4b27b e4bc1b8e 24e763a4 fa5b7b53 46f12358 68ff0e3e c408c18a f25b2e14 8b648d71 53628abd d3c9b865 600dc8a5 332d1276 fcf599eb 58566a84 413e75a1 05624bab ff7e535a 1a00a791 e0dbe002 cab9208b 980d1db7 8db5ea49 dd54ec3b f1601707 5afc8a35 46ef8e4e 1fe0c0a4 8fdb8158 14e6cad2 909698c5 aed15df3 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 00000000 2003-04-07 10:53:10: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:53:10: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=1(sa) 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=10(nonce) 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=4(ke) 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=5(id) 2003-04-07 10:53:10: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:1006:quick_r1recv(): received IDci2:2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 04000000 0a000562 ffffffff 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:1010:quick_r1recv(): received IDcr2:2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 04000000 0a080807 ffffffff 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:1025:quick_r1recv(): HASH(1) validate:2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 03a954de 97ba7417 fd6e024c 0dd822c8 0b378d39 2003-04-07 10:53:10: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): e4f27700 0a000034 00000001 00000001 00000028 00030401 5da78fab 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 46f0d7c0 ba771ea7 aca73b6f 4ee91194 05000084 f0d4b27b e4bc1b8e 24e763a4 fa5b7b53 46f12358 68ff0e3e c408c18a f25b2e14 8b648d71 53628abd d3c9b865 600dc8a5 332d1276 fcf599eb 58566a84 413e75a1 05624bab ff7e535a 1a00a791 e0dbe002 cab9208b 980d1db7 8db5ea49 dd54ec3b f1601707 5afc8a35 46ef8e4e 1fe0c0a4 8fdb8158 14e6cad2 909698c5 aed15df3 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 03a954de 97ba7417 fd6e024c 0dd822c8 0b378d39 2003-04-07 10:53:10: DEBUG: sainfo.c:100:getsainfo(): anonymous sainfo selected. 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:1817:get_sainfo_r(): get sa info: anonymous 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:1995:get_proposal_r(): get a src address from ID payload 10.0.5.98[0] prefixlen=32 ul_proto=255 2003-04-07 10:53:10: 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-04-07 10:53:10: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd37c: 10.0.5.98/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-04-07 10:53:10: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80a2c08: 10.0.5.98/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-04-07 10:53:10: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.0.5.98[0] 2003-04-07 10:53:10: DEBUG: policy.c:246:cmpspidxwild(): 0x80a2c08 masked with /32: 10.0.5.98[0] 2003-04-07 10:53:10: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.8.8.7[0] 2003-04-07 10:53:10: DEBUG: policy.c:262:cmpspidxwild(): 0x80a2c08 masked with /32: 10.8.8.7[0] 2003-04-07 10:53:10: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd37c: 10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:53:10: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80a2c08: 10.0.5.98/32[0] 10.8.8.7/32[0] proto=any dir=in 2003-04-07 10:53:10: DEBUG: policy.c:216:cmpspidxwild(): sub:0xbfbfd37c: 10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:53:10: DEBUG: policy.c:217:cmpspidxwild(): db: 0x80ae008: 10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:53:10: DEBUG: policy.c:244:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.8.8.7[0] 2003-04-07 10:53:10: DEBUG: policy.c:246:cmpspidxwild(): 0x80ae008 masked with /32: 10.8.8.7[0] 2003-04-07 10:53:10: DEBUG: policy.c:260:cmpspidxwild(): 0xbfbfd37c masked with /32: 10.0.5.98[0] 2003-04-07 10:53:10: DEBUG: policy.c:262:cmpspidxwild(): 0x80ae008 masked with /32: 10.0.5.98[0] 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:2056:get_proposal_r(): suitable SP found:10.8.8.7/32[0] 10.0.5.98/32[0] proto=any dir=out 2003-04-07 10:53:10: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-04-07 10:53:10: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=48 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000028 00030401 5da78fab 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 2003-04-07 10:53:10: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-04-07 10:53:10: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=40 2003-04-07 10:53:10: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-04-07 10:53:10: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=28 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-04-07 10:53:10: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-04-07 10:53:10: DEBUG: proposal.c:892:print_proppair0(): 0x80a8770: next=0x0 tnext=0x0 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 1 transform 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:948:get_ph2approval(): begin compare proposals. 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:954:get_ph2approval(): pair[0]: 0x80a8770 2003-04-07 10:53:10: DEBUG: proposal.c:892:print_proppair0(): 0x80a8770: next=0x0 tnext=0x0 2003-04-07 10:53:10: DEBUG: proposal.c:681:aproppair2saprop(): prop#=0 prot-id=ESP spi-size=4 #trns=1 trns#=0 trns-id=3DES 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Group Description, flag=0x8000, lorv=2 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x8000, lorv=180 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:3643:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:990:get_ph2approvalx(): peer's single bundle: 2003-04-07 10:53:10: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=5da78fab spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-04-07 10:53:10: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:993:get_ph2approvalx(): my single bundle: 2003-04-07 10:53:10: DEBUG: proposal.c:825:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2003-04-07 10:53:10: DEBUG: proposal.c:859:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1012:get_ph2approvalx(): matched 2003-04-07 10:53:10: DEBUG: isakmp.c:1078:isakmp_ph2begin_r(): === 2003-04-07 10:53:10: DEBUG: pfkey.c:792:pk_sendgetspi(): call pfkey_send_getspi 2003-04-07 10:53:10: DEBUG: pfkey.c:805:pk_sendgetspi(): pfkey GETSPI sent: ESP/Tunnel 216.144.184.7->63.202.92.133 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:1143:quick_r1prep(): pfkey getspi sent. 2003-04-07 10:53:10: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey GETSPI message 2003-04-07 10:53:10: DEBUG2: plog.c:193:plogdump(): 02010003 0a000000 55f08a97 45020000 02000100 0d1cacf7 3fb999ea 51281f67 03000500 ff200000 10020000 d890b807 00000000 00000000 03000600 ff200000 10020000 3fca5c85 00000000 00000000 2003-04-07 10:53:10: DEBUG: pfkey.c:876:pk_recvgetspi(): pfkey GETSPI succeeded: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=219983095(0xd1cacf7) 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1117:get_proppair(): total SA len=48 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 00000001 00000001 00000028 00030401 00000000 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 2003-04-07 10:53:10: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=2(prop) 2003-04-07 10:53:10: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1170:get_proppair(): proposal #0 len=40 2003-04-07 10:53:10: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=3(trns) 2003-04-07 10:53:10: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1311:get_transform(): transform #0 len=28 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Group Description, flag=0x8000, lorv=2 2003-04-07 10:53:10: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=SA Life Duration, flag=0x8000, lorv=180 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2155:check_attr_ipsec(): life duration was in TLV. 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:2067:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1213:get_proppair(): pair 0: 2003-04-07 10:53:10: DEBUG: proposal.c:892:print_proppair0(): 0x80a86a0: next=0x0 tnext=0x0 2003-04-07 10:53:10: DEBUG: ipsec_doi.c:1248:get_proppair(): proposal #0: 1 transform 2003-04-07 10:53:10: DEBUG: algorithm.c:610:alg_oakley_dhdef(): hmac(modp1024) 2003-04-07 10:53:10: DEBUG: oakley.c:256:oakley_dh_generate(): compute DH's private. 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 7b5acbe0 cef9a941 303a25d6 865762a4 ccefe12c d2c4831a d54e9398 6b8b2c61 a8f7fb10 7f8cff8c fe7d0af3 85828c8c 27442fb9 545fdc91 fc01f574 c873bd74 07f00988 aa9e9908 8208ac11 8c8d22b2 3d8d1b97 45877926 bf4ec3a9 0369a325 88abbd37 b8a07539 2427d28c 564242d8 616fb7d5 671975ff b0aa6704 70ec0ffe 2003-04-07 10:53:10: DEBUG: oakley.c:258:oakley_dh_generate(): compute DH's public. 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): f0bc5858 4d96d33f 97a02208 a50b9cd6 694639cc 7e997261 227192fb a0775caf 50ead651 96ea9858 d61cc54c 0e50aa53 04fb6c4b 86bf231e e6aaa2da 193a698d d5e4deee 0db1db76 47b221d2 af26c5a0 4ddddc66 d2dd3607 31676add 4a386215 1121e5d8 34f8e109 6b7b6ca9 8472bbac 7f254bd0 ca4b7791 b003b647 713493ab 2003-04-07 10:53:10: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 48, next type 10 2003-04-07 10:53:10: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 16, next type 4 2003-04-07 10:53:10: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 128, next type 5 2003-04-07 10:53:10: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 12, next type 5 2003-04-07 10:53:10: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 12, next type 0 2003-04-07 10:53:10: DEBUG: oakley.c:748:oakley_compute_hash1(): HASH with: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): e4f27700 46f0d7c0 ba771ea7 aca73b6f 4ee91194 0a000034 00000001 00000001 00000028 00030401 0d1cacf7 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 e99853dc 71ca9c20 addab64e 40067341 05000084 f0bc5858 4d96d33f 97a02208 a50b9cd6 694639cc 7e997261 227192fb a0775caf 50ead651 96ea9858 d61cc54c 0e50aa53 04fb6c4b 86bf231e e6aaa2da 193a698d d5e4deee 0db1db76 47b221d2 af26c5a0 4ddddc66 d2dd3607 31676add 4a386215 1121e5d8 34f8e109 6b7b6ca9 8472bbac 7f254bd0 ca4b7791 b003b647 713493ab 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: oakley.c:758:oakley_compute_hash1(): HASH computed: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 6e47f653 ad641a62 f50f4dbe 0d41db2b 18b434db 2003-04-07 10:53:10: DEBUG: isakmp.c:2113:set_isakmp_payload(): add payload of len 20, next type 1 2003-04-07 10:53:10: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:53:10: DEBUG: oakley.c:2748:oakley_do_encrypt(): begin encryption. 2003-04-07 10:53:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: oakley.c:2764:oakley_do_encrypt(): pad length = 4 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 01000018 6e47f653 ad641a62 f50f4dbe 0d41db2b 18b434db 0a000034 00000001 00000001 00000028 00030401 0d1cacf7 0000001c 00030000 80030002 80040001 80010001 800200b4 80050002 04000014 e99853dc 71ca9c20 addab64e 40067341 05000084 f0bc5858 4d96d33f 97a02208 a50b9cd6 694639cc 7e997261 227192fb a0775caf 50ead651 96ea9858 d61cc54c 0e50aa53 04fb6c4b 86bf231e e6aaa2da 193a698d d5e4deee 0db1db76 47b221d2 af26c5a0 4ddddc66 d2dd3607 31676add 4a386215 1121e5d8 34f8e109 6b7b6ca9 8472bbac 7f254bd0 ca4b7791 b003b647 713493ab 05000010 04000000 0a000562 ffffffff 00000010 04000000 0a080807 ffffffff ab747603 2003-04-07 10:53:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: oakley.c:2799:oakley_do_encrypt(): with key: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:53:10: DEBUG: oakley.c:2807:oakley_do_encrypt(): encrypted payload by IV: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 11ab83b0 8ffd02a9 2003-04-07 10:53:10: DEBUG: oakley.c:2814:oakley_do_encrypt(): save IV for next: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 11ab83b0 8ffd02a9 2003-04-07 10:53:10: DEBUG: oakley.c:2831:oakley_do_encrypt(): encrypted. 2003-04-07 10:53:10: DEBUG: sockmisc.c:421:sendfromto(): sockname 63.202.92.133[500] 2003-04-07 10:53:10: DEBUG: sockmisc.c:423:sendfromto(): send packet from 63.202.92.133[500] 2003-04-07 10:53:10: DEBUG: sockmisc.c:425:sendfromto(): send packet to 216.144.184.7[500] 2003-04-07 10:53:10: DEBUG: sockmisc.c:570:sendfromto(): 1 times of 292 bytes message will be sent to 63.202.92.133[500] 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 e4f27700 00000124 88768f14 a6b5eb36 79a5f7db 11ed199d 4dc79518 3d1c2f6c bb42c057 e1189bf3 9ae90d08 0628c59d 322469dc 4933c051 6f7947bf 1956c952 4ebbe07a 194c6502 93a0db6e 05040d7d 3b144e44 b152bf2b cfa4f4a1 035fa71c 08a08b88 4f0014b4 d729a020 3ec22392 7d465d26 a3ebd574 38e3fdec d72467c9 0fb42bc1 8259313e 093643b1 291a1a02 b5c96522 c4885d4f ec7f7136 3af9e1cc 91f537e3 8510bae0 e7194f7e 44e31624 872b20d8 ad4dc196 acf6315e bf4470e9 7032bc03 d952005a 20886d58 6046e14f c68b4207 0de90e77 611c5405 c99dcf12 efd0c8f5 619c0b4d 2edede8d e6796f8f e4efda04 f1acde14 09fb2ae4 00a793f1 d68c24ec 7027ce80 11ab83b0 8ffd02a9 2003-04-07 10:53:10: DEBUG: isakmp.c:1487:isakmp_ph2resend(): resend phase2 packet 2c917f7d76020366:351fa4e0b6e19438:0000e4f2 2003-04-07 10:53:10: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:53:10: DEBUG: isakmp.c:222:isakmp_handler(): 52 bytes message received from 216.144.184.7[500] 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 e4f27700 00000034 4cfa38f4 01142937 49803772 61d0a539 0e2e55d0 30ad1297 2003-04-07 10:53:10: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:53:10: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-04-07 10:53:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 0e2e55d0 30ad1297 2003-04-07 10:53:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:53:10: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 0e2e55d0 30ad1297 2003-04-07 10:53:10: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 00000018 fd63b974 2bd34fdf fa0a5822 b9ee4c71 c7707560 2003-04-07 10:53:10: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=97 2003-04-07 10:53:10: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-04-07 10:53:10: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 e4f27700 00000034 00000018 fd63b974 2bd34fdf fa0a5822 b9ee4c71 c7707560 2003-04-07 10:53:10: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:53:10: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:53:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-04-07 10:53:10: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:1430:quick_r3recv(): HASH(3) validate:2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): fd63b974 2bd34fdf fa0a5822 b9ee4c71 c7707560 2003-04-07 10:53:10: DEBUG: oakley.c:692:oakley_compute_hash3(): HASH with: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): 00e4f277 0046f0d7 c0ba771e a7aca73b 6f4ee911 94e99853 dc71ca9c 20addab6 4e400673 41 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: oakley.c:702:oakley_compute_hash3(): HASH computed: 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): fd63b974 2bd34fdf fa0a5822 b9ee4c71 c7707560 2003-04-07 10:53:10: DEBUG: isakmp.c:736:quick_main(): === 2003-04-07 10:53:10: DEBUG: oakley.c:210:oakley_dh_compute(): compute DH's shared. 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): f8bf4d05 a96cf702 00d62c09 533ae7f9 534e6992 dad41137 4586f8cc 5dedaab2 4dcbeac7 bde6bb45 fcb6de58 6b061b23 e93c1ad7 94df32ed 86586857 04eddfec 5c3bc231 66b60f8d b11d288b 65ab8e60 c388e699 73ddb4b7 89e9ccbb 5bd61282 8ce89ef6 29e67f94 db4b39f1 2d7f9317 e78952e8 1075d6bd 96d996da 97c459a0 2003-04-07 10:53:10: DEBUG: oakley.c:464:oakley_compute_keymat_x(): KEYMAT compute with 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): f8bf4d05 a96cf702 00d62c09 533ae7f9 534e6992 dad41137 4586f8cc 5dedaab2 4dcbeac7 bde6bb45 fcb6de58 6b061b23 e93c1ad7 94df32ed 86586857 04eddfec 5c3bc231 66b60f8d b11d288b 65ab8e60 c388e699 73ddb4b7 89e9ccbb 5bd61282 8ce89ef6 29e67f94 db4b39f1 2d7f9317 e78952e8 1075d6bd 96d996da 97c459a0 030d1cac f746f0d7 c0ba771e a7aca73b 6f4ee911 94e99853 dc71ca9c 20addab6 4e400673 41 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: oakley.c:497:oakley_compute_keymat_x(): encklen=192 authklen=160 2003-04-07 10:53:10: DEBUG: oakley.c:504:oakley_compute_keymat_x(): generating 640 bits of key (dupkeymat=4) 2003-04-07 10:53:10: DEBUG: oakley.c:522:oakley_compute_keymat_x(): generating K1...K4 for KEYMAT. 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): ae7b9b2c d0e9de1d fc383fab b23e4773 6a4470af ab167011 2a7263f8 4fa96ca2 496559e7 62cd86c4 a61d57e9 c21ab797 118274f3 4c1eabb9 39821b8f 944a6dd3 d6913d47 ef94a82e 2ba783fa 2546e9e8 2003-04-07 10:53:10: DEBUG: oakley.c:464:oakley_compute_keymat_x(): KEYMAT compute with 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): f8bf4d05 a96cf702 00d62c09 533ae7f9 534e6992 dad41137 4586f8cc 5dedaab2 4dcbeac7 bde6bb45 fcb6de58 6b061b23 e93c1ad7 94df32ed 86586857 04eddfec 5c3bc231 66b60f8d b11d288b 65ab8e60 c388e699 73ddb4b7 89e9ccbb 5bd61282 8ce89ef6 29e67f94 db4b39f1 2d7f9317 e78952e8 1075d6bd 96d996da 97c459a0 035da78f ab46f0d7 c0ba771e a7aca73b 6f4ee911 94e99853 dc71ca9c 20addab6 4e400673 41 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: oakley.c:497:oakley_compute_keymat_x(): encklen=192 authklen=160 2003-04-07 10:53:10: DEBUG: oakley.c:504:oakley_compute_keymat_x(): generating 640 bits of key (dupkeymat=4) 2003-04-07 10:53:10: DEBUG: oakley.c:522:oakley_compute_keymat_x(): generating K1...K4 for KEYMAT. 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: algorithm.c:322:alg_oakley_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: plog.c:193:plogdump(): a88a905f cdc7e220 8a12f1f3 9bb8a6de 6536afeb a84d5d2e 962b3531 9f988b60 6f6e8112 386f307e 90812f26 6aa19075 bc354dfd e592d471 af152729 6c56cd82 d84b60cb 67b01bcc 9822a1b3 9f10cbfb 2003-04-07 10:53:10: DEBUG: oakley.c:392:oakley_compute_keymat(): KEYMAT computed. 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:1613:quick_r3prep(): call pk_sendupdate 2003-04-07 10:53:10: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: pfkey.c:974:pk_sendupdate(): call pfkey_send_update 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:1618:quick_r3prep(): pfkey update sent. 2003-04-07 10:53:10: DEBUG: algorithm.c:509:alg_ipsec_encdef(): encription(3des) 2003-04-07 10:53:10: DEBUG: algorithm.c:552:alg_ipsec_hmacdef(): hmac(hmac_sha1) 2003-04-07 10:53:10: DEBUG: pfkey.c:1215:pk_sendadd(): call pfkey_send_add 2003-04-07 10:53:10: DEBUG: isakmp_quick.c:1625:quick_r3prep(): pfkey add sent. 2003-04-07 10:53:10: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey UPDATE message 2003-04-07 10:53:10: DEBUG2: plog.c:193:plogdump(): 02020003 14000000 55f08a97 45020000 02000100 0d1cacf7 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 d890b807 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-04-07 10:53:10: DEBUG: pfkey.c:1103:pk_recvupdate(): pfkey UPDATE succeeded: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=219983095(0xd1cacf7) 2003-04-07 10:53:10: INFO: pfkey.c:1110:pk_recvupdate(): IPsec-SA established: ESP/Tunnel 216.144.184.7->63.202.92.133 spi=219983095(0xd1cacf7) 2003-04-07 10:53:10: DEBUG: pfkey.c:1148:pk_recvupdate(): === 2003-04-07 10:53:10: DEBUG: pfkey.c:195:pfkey_handler(): get pfkey ADD message 2003-04-07 10:53:10: DEBUG2: plog.c:193:plogdump(): 02030003 14000000 55f08a97 45020000 02000100 5da78fab 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 3fca5c85 00000000 00000000 03000600 ff200000 10020000 d890b807 00000000 00000000 04000300 00000000 00000000 00000000 b4000000 00000000 00000000 00000000 04000400 00000000 00000000 00000000 90000000 00000000 00000000 00000000 2003-04-07 10:53:10: INFO: pfkey.c:1322:pk_recvadd(): IPsec-SA established: ESP/Tunnel 63.202.92.133->216.144.184.7 spi=1571262379(0x5da78fab) 2003-04-07 10:53:10: DEBUG: pfkey.c:1327:pk_recvadd(): === 2003-04-07 10:54:10: INFO: isakmp.c:1600:isakmp_ph2expire(): phase2 sa expired 63.202.92.133-216.144.184.7 2003-04-07 10:54:10: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:54:10: DEBUG: isakmp.c:222:isakmp_handler(): 68 bytes message received from 216.144.184.7[500] 2003-04-07 10:54:10: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08100501 17e4be02 00000044 0c2549c8 c843f118 7893d2e6 f072bbd0 41f5f1e5 891b8ef6 34333be9 ec2915e5 aed5eee9 8de431d1 2003-04-07 10:54:10: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:54:10: DEBUG: isakmp_inf.c:115:isakmp_info_recv(): receive Information. 2003-04-07 10:54:10: DEBUG: oakley.c:2549:oakley_newiv2(): compute IV for phase2 2003-04-07 10:54:10: DEBUG: oakley.c:2550:oakley_newiv2(): phase1 last IV: 2003-04-07 10:54:10: DEBUG: plog.c:193:plogdump(): d3786398 6fe2afb6 17e4be02 2003-04-07 10:54:10: DEBUG: algorithm.c:252:alg_oakley_hashdef(): hash(sha1) 2003-04-07 10:54:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:54:10: DEBUG: oakley.c:2582:oakley_newiv2(): phase2 IV computed: 2003-04-07 10:54:10: DEBUG: plog.c:193:plogdump(): 3d11de5f da0c8057 2003-04-07 10:54:10: DEBUG: oakley.c:2625:oakley_do_decrypt(): begin decryption. 2003-04-07 10:54:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:54:10: DEBUG: oakley.c:2639:oakley_do_decrypt(): IV was saved for next processing: 2003-04-07 10:54:10: DEBUG: plog.c:193:plogdump(): aed5eee9 8de431d1 2003-04-07 10:54:10: DEBUG: algorithm.c:382:alg_oakley_encdef(): encription(3des) 2003-04-07 10:54:10: DEBUG: oakley.c:2664:oakley_do_decrypt(): with key: 2003-04-07 10:54:10: DEBUG: plog.c:193:plogdump(): 7e74d7ca bf8deb5d c903ba3a d1ea16ef 6f7617c5 82963f1e 2003-04-07 10:54:10: DEBUG: oakley.c:2672:oakley_do_decrypt(): decrypted payload by IV: 2003-04-07 10:54:10: DEBUG: plog.c:193:plogdump(): aed5eee9 8de431d1 2003-04-07 10:54:10: DEBUG: oakley.c:2675:oakley_do_decrypt(): decrypted payload, but not trimed. 2003-04-07 10:54:10: DEBUG: plog.c:193:plogdump(): 0c000018 d4d7055b 76f80c37 4f4d7817 9d049542 c8781dc9 00000010 00000001 03040001 5da78faa 2003-04-07 10:54:10: DEBUG: oakley.c:2684:oakley_do_decrypt(): padding len=171 2003-04-07 10:54:10: DEBUG: oakley.c:2698:oakley_do_decrypt(): skip to trim padding. 2003-04-07 10:54:10: DEBUG: oakley.c:2713:oakley_do_decrypt(): decrypted. 2003-04-07 10:54:10: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08100501 17e4be02 00000044 0c000018 d4d7055b 76f80c37 4f4d7817 9d049542 c8781dc9 00000010 00000001 03040001 5da78faa 2003-04-07 10:54:10: DEBUG: isakmp.c:2248:isakmp_printpacket(): begin. 2003-04-07 10:54:10: DEBUG: isakmp.c:1112:isakmp_parsewoh(): begin. 2003-04-07 10:54:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=8(hash) 2003-04-07 10:54:10: DEBUG: isakmp.c:1139:isakmp_parsewoh(): seen nptype=12(delete) 2003-04-07 10:54:10: DEBUG: isakmp.c:1178:isakmp_parsewoh(): succeed. 2003-04-07 10:54:10: DEBUG: pfkey.c:271:pfkey_dump_sadb(): call pfkey_send_dump 2003-04-07 10:54:10: DEBUG: isakmp_inf.c:902:purge_ipsec_spi(): check spi(packet)=1571262378 spi(db)=1571262379. 2003-04-07 10:54:10: DEBUG: isakmp_inf.c:1249:isakmp_info_recv_d(): purged SAs. 2003-04-07 10:54:11: INFO: isakmp.c:1631:isakmp_ph2delete(): phase2 sa deleted 63.202.92.133-216.144.184.7 2003-04-07 10:54:51: DEBUG: isakmp.c:221:isakmp_handler(): === 2003-04-07 10:54:51: DEBUG: isakmp.c:222:isakmp_handler(): 292 bytes message received from 216.144.184.7[500] 2003-04-07 10:54:51: DEBUG: plog.c:193:plogdump(): 2c917f7d 76020366 351fa4e0 b6e19438 08102001 479486fe 00000124 37435f8c ac4c2c30 8ca9b875 bdc8cf1a 075b6ecc 0226125c 2078fd98 eec88076 a8e5b4e8 96aa0714 d79309af 2d85adb9 1cb97a2b 69271205 4a0adf5e 016393b2 81201e26 e24469c8 ea957a0e