2001-09-05 15:12:28: INFO: main.c:126:main(): @(#)racoon 20001216 sakane@ydc.co.jp 2001-09-05 15:12:28: INFO: main.c:127:main(): @(#)This product linked software developed by the OpenSSL Project for use in the OpenSSL Toolkit. (http://www.openssl.org/) 2001-09-05 15:12:28: DEBUG: pfkey.c:351:pfkey_init(): call pfkey_send_register 2001-09-05 15:12:28: DEBUG: pfkey.c:360:pfkey_init(): call pfkey_send_register 2001-09-05 15:12:28: DEBUG: pfkey.c:369:pfkey_init(): call pfkey_send_register 2001-09-05 15:12:28: DEBUG2: cftoken.l:119:yylex(): begin <5>path 2001-09-05 15:12:28: DEBUG2: cftoken.l:122:yylex(): <5>2001-09-05 15:12:28: DEBUG2: cftoken.l:457:yylex(): <5>2001-09-05 15:12:28: DEBUG2: cftoken.l:126:yylex(): begin <3>; 2001-09-05 15:12:28: DEBUG2: cftoken.l:119:yylex(): begin <5>path 2001-09-05 15:12:28: DEBUG2: cftoken.l:124:yylex(): <5>2001-09-05 15:12:28: DEBUG2: cftoken.l:457:yylex(): <5>2001-09-05 15:12:28: DEBUG2: cftoken.l:126:yylex(): begin <3>; 2001-09-05 15:12:28: DEBUG2: cftoken.l:156:yylex(): begin <13>listen 2001-09-05 15:12:28: DEBUG2: cftoken.l:158:yylex(): <13>2001-09-05 15:12:28: DEBUG2: cftoken.l:472:yylex(): <13>2001-09-05 15:12:28: DEBUG2: cftoken.l:348:yylex(): <13>2001-09-05 15:12:28: DEBUG2: cftoken.l:160:yylex(): <13>2001-09-05 15:12:28: DEBUG2: cftoken.l:255:yylex(): begin <33>remote 2001-09-05 15:12:28: DEBUG2: cftoken.l:256:yylex(): <33>2001-09-05 15:12:28: DEBUG2: cftoken.l:260:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:263:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:285:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:287:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:274:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:275:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:457:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:457:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:272:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:407:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:457:yylex(): <35>2001-09-05 15:12:28: DEBUG2: cftoken.l:295:yylex(): begin <37>proposal 2001-09-05 15:12:28: DEBUG2: cftoken.l:305:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:368:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:307:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:385:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:306:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:395:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:308:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:391:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:295:yylex(): begin <37>proposal 2001-09-05 15:12:28: DEBUG2: cftoken.l:305:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:368:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:307:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:385:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:306:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:396:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:308:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cftoken.l:391:yylex(): <37>2001-09-05 15:12:28: DEBUG2: cfparse.y:1722:set_isakmp_proposal(): lifetime = 28800 2001-09-05 15:12:28: DEBUG2: cfparse.y:1725:set_isakmp_proposal(): lifebyte = 0 2001-09-05 15:12:28: DEBUG2: cfparse.y:1728:set_isakmp_proposal(): strength=extra high 2001-09-05 15:12:28: DEBUG2: cfparse.y:1730:set_isakmp_proposal(): encklen=0 2001-09-05 15:12:28: DEBUG2: cfparse.y:1912:expand_isakmpspec(): p:1 t:1 2001-09-05 15:12:28: DEBUG2: cfparse.y:1916:expand_isakmpspec(): 3DES-CBC(5) 2001-09-05 15:12:28: DEBUG2: cfparse.y:1916:expand_isakmpspec(): SHA(2) 2001-09-05 15:12:28: DEBUG2: cfparse.y:1916:expand_isakmpspec(): 1024-bit MODP group(2) 2001-09-05 15:12:28: DEBUG2: cfparse.y:1916:expand_isakmpspec(): pre-shared key(1) 2001-09-05 15:12:28: DEBUG2: cfparse.y:1923:expand_isakmpspec(): 2001-09-05 15:12:28: DEBUG2: cfparse.y:1722:set_isakmp_proposal(): lifetime = 28800 2001-09-05 15:12:28: DEBUG2: cfparse.y:1725:set_isakmp_proposal(): lifebyte = 0 2001-09-05 15:12:28: DEBUG2: cfparse.y:1728:set_isakmp_proposal(): strength=extra high 2001-09-05 15:12:28: DEBUG2: cfparse.y:1730:set_isakmp_proposal(): encklen=0 2001-09-05 15:12:28: DEBUG2: cfparse.y:1912:expand_isakmpspec(): p:1 t:1 2001-09-05 15:12:28: DEBUG2: cfparse.y:1916:expand_isakmpspec(): 3DES-CBC(5) 2001-09-05 15:12:28: DEBUG2: cfparse.y:1916:expand_isakmpspec(): SHA(2) 2001-09-05 15:12:28: DEBUG2: cfparse.y:1916:expand_isakmpspec(): 1024-bit MODP group(2) 2001-09-05 15:12:28: DEBUG2: cfparse.y:1916:expand_isakmpspec(): RSA signatures(3) 2001-09-05 15:12:28: DEBUG2: cfparse.y:1923:expand_isakmpspec(): 2001-09-05 15:12:28: DEBUG2: cftoken.l:235:yylex(): begin <29>sainfo 2001-09-05 15:12:28: DEBUG2: cftoken.l:236:yylex(): <29>2001-09-05 15:12:28: DEBUG2: cftoken.l:249:yylex(): <31>2001-09-05 15:12:28: DEBUG2: cftoken.l:368:yylex(): <31>2001-09-05 15:12:28: DEBUG2: cftoken.l:250:yylex(): <31>2001-09-05 15:12:28: DEBUG2: cftoken.l:381:yylex(): <31>2001-09-05 15:12:28: DEBUG2: cftoken.l:251:yylex(): <31>2001-09-05 15:12:28: DEBUG2: cftoken.l:388:yylex(): <31>2001-09-05 15:12:28: WARNING: pfkey.c:1977:pk_checkalg(): compression algorithm can not be checked. 2001-09-05 15:12:28: DEBUG2: cfparse.y:2005:cfparse(): parse successed. 2001-09-05 15:12:28: INFO: isakmp.c:1250:isakmp_open(): 165.227.249.5[500] used as isakmp port (fd=6) 2001-09-05 15:12:28: DEBUG: pfkey.c:190:pfkey_handler(): get pfkey X_SPDDUMP message 2001-09-05 15:12:28: DEBUG: plog.c:204:plogdump(): 02120000 0f000100 01000000 d0420000 03000500 ff200000 10020000 c0a8de1e 00000000 00000000 03000600 ff200000 10020000 0a080807 00000000 00000000 07001200 02000100 07000000 00000000 28003200 02020000 10020000 d1babd73 00000000 00000000 10020000 a5e3f905 00000000 00000000 2001-09-05 15:12:28: DEBUG: pfkey.c:190:pfkey_handler(): get pfkey X_SPDDUMP message 2001-09-05 15:12:28: DEBUG: plog.c:204:plogdump(): 02120000 0f000100 00000000 d0420000 03000500 ff200000 10020000 0a080807 00000000 00000000 03000600 ff200000 10020000 c0a8de1e 00000000 00000000 07001200 02000200 08000000 00000000 28003200 02020000 10020000 a5e3f905 00000000 00000000 10020000 d1babd73 00000000 00000000 2001-09-05 15:12:28: DEBUG: policy.c:181:cmpspidx(): sub:0xbfbfd648: 10.8.8.7/32[0] 192.168.222.30/32[0] proto=255 dir=2 2001-09-05 15:12:28: DEBUG: policy.c:182:cmpspidx(): db :0x809c408: 192.168.222.30/32[0] 10.8.8.7/32[0] proto=255 dir=1 2001-09-05 15:12:31: DEBUG: grabmyaddr.c:452:update_myaddrs(): caught rtm:15, need update interface address list 2001-09-05 15:12:31: DEBUG: grabmyaddr.c:446:update_myaddrs(): msg 14 not interesting 2001-09-05 15:15:09: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:15:09: DEBUG: isakmp.c:232:isakmp_handler(): 84 bytes message received from 209.186.189.115[500] 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 00000000 00000000 01100200 00000000 00000054 00000038 00000001 00000001 0000002c 01010001 00000024 01010000 80040002 80030001 80010005 80020002 800b0001 000c0004 00000e10 2001-09-05 15:15:09: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:09: DEBUG: remoteconf.c:127:getrmconf(): anonymous configuration selected for 209.186.189.115[500]. 2001-09-05 15:15:09: DEBUG: isakmp.c:840:isakmp_ph1begin_r(): === 2001-09-05 15:15:09: INFO: isakmp.c:845:isakmp_ph1begin_r(): responde new phase 1 negotiation: 165.227.249.5[500]<=>209.186.189.115[500] 2001-09-05 15:15:09: INFO: isakmp.c:850:isakmp_ph1begin_r(): begin Identity Protection mode. 2001-09-05 15:15:09: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:09: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=1(sa) 2001-09-05 15:15:09: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1052:get_proppair(): total SA len=52 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 00000001 00000001 0000002c 01010001 00000024 01010000 80040002 80030001 80010005 80020002 800b0001 000c0004 00000e10 2001-09-05 15:15:09: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:09: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=2(prop) 2001-09-05 15:15:09: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1105:get_proppair(): proposal #1 len=44 2001-09-05 15:15:09: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:09: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=3(trns) 2001-09-05 15:15:09: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1246:get_transform(): transform #1 len=36 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1805:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1805:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1805:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1805:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1805:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1805:check_attr_isakmp(): type=Life Duration, flag=0x0000, lorv=4 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1148:get_proppair(): pair 1: 2001-09-05 15:15:09: DEBUG: proposal.c:866:print_proppair0(): 0x8097370: next=0x0 tnext=0x0 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:1183:get_proppair(): proposal #1: 1 transform 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:318:get_ph1approvalx(): prop#=1, prot-id=ISAKMP, spi-size=0, #trns=1 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:323:get_ph1approvalx(): trns#=1, trns-id=IKE 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:421:t2isakmpsa(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:421:t2isakmpsa(): type=Authentication Method, flag=0x8000, lorv=pre-shared key 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:421:t2isakmpsa(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:421:t2isakmpsa(): type=Hash Algorithm, flag=0x8000, lorv=SHA 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:421:t2isakmpsa(): type=Life Type, flag=0x8000, lorv=seconds 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:421:t2isakmpsa(): type=Life Duration, flag=0x0000, lorv=4 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:334:get_ph1approvalx(): Compared: DB:Peer 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:335:get_ph1approvalx(): (lifetime = 28800:3600) 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:337:get_ph1approvalx(): (lifebyte = 0:0) 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:339:get_ph1approvalx(): enctype = 3DES-CBC:3DES-CBC 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:344:get_ph1approvalx(): (encklen = 0:0) 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:346:get_ph1approvalx(): hashtype = SHA:SHA 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:351:get_ph1approvalx(): authmethod = pre-shared key:pre-shared key 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:356:get_ph1approvalx(): dh_group = 1024-bit MODP group:1024-bit MODP group 2001-09-05 15:15:09: DEBUG: ipsec_doi.c:378:get_ph1approvalx(): acceptable proposal found. 2001-09-05 15:15:09: DEBUG: isakmp.c:1876:isakmp_newcookie(): new cookie: 4b5155853ed2a469 2001-09-05 15:15:09: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 52, next type 0 2001-09-05 15:15:09: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:09: DEBUG: sockmisc.c:357:sendfromto(): sockname 165.227.249.5[500] 2001-09-05 15:15:09: DEBUG: sockmisc.c:359:sendfromto(): send packet from 165.227.249.5[500] 2001-09-05 15:15:09: DEBUG: sockmisc.c:361:sendfromto(): send packet to 209.186.189.115[500] 2001-09-05 15:15:09: DEBUG: isakmp.c:1327:isakmp_send(): 1 times of 84 bytes message will be sent. 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 01100200 00000000 00000054 00000038 00000001 00000001 0000002c 01010001 00000024 01010000 80040002 80030001 80010005 80020002 800b0001 000c0004 00000e10 2001-09-05 15:15:09: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:15:09: DEBUG: isakmp.c:232:isakmp_handler(): 172 bytes message received from 209.186.189.115[500] 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 04100200 00000000 000000ac 0a000084 5b4a431e 1144b108 d6887dda befd546d deeb6a56 28e2a2e7 9c3e66e4 4aae168b 718265fe eeeef826 743a7a8c ecbd0174 5f782807 3ba4ea20 1dec2573 ceb204a4 ffaa4b65 aee23fce a1d75ecb 6fc2befd 63367f2f 3096e9b0 6a981e8a b20950fa 236ec713 b56b784c 5df3339f 9377e328 ee20f117 85e81e25 58a02cf1 2f747f55 0000000c 87989bb7 902266fb 2001-09-05 15:15:09: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:09: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:09: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=4(ke) 2001-09-05 15:15:09: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=10(nonce) 2001-09-05 15:15:09: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:09: DEBUG: isakmp.c:609:ph1_main(): === 2001-09-05 15:15:09: DEBUG: oakley.c:228:oakley_dh_generate(): compute DH's private. 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 6814ce6c e849cede f0702904 ba8b88de 911bfe5f 40510d18 69a1d5b5 6dc125e4 c3d65e3e de1414e2 13126705 1f489452 bef80ffb 5e15206d 3d457fa7 8db8adb4 3347d072 8f6b6990 88f806b7 3d427070 d2f056d9 85e9d41e 6b2af343 bc3f2d0e 30de502a 3d2bb3fc fed67994 c546fece 66675e7f afe9ce53 34e8b298 2932317b2001-09-05 15:15:09: DEBUG: oakley.c:230:oakley_dh_generate(): compute DH's public. 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 90a678a5 3a59347e 551e4eb4 7cc0c890 94e02ba1 c3d3e023 b0edb7a2 d98bbee9 a4c31d57 5497f9f4 7f8ff901 a53cf063 a6ff835f 1fd8338b 5a1b5290 fadefbf1 6ef822b4 0f2a579f ee830cee de7a452f 923e8eea c974e546 dc517ac8 6e3e5434 4c04e8e8 7e00f3bb 1886dc09 e620fca1 051bedb5 05ea045c 17fad106 a6304f1a2001-09-05 15:15:09: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 128, next type 10 2001-09-05 15:15:09: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 16, next type 13 2001-09-05 15:15:09: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 16, next type 0 2001-09-05 15:15:09: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:09: DEBUG: sockmisc.c:357:sendfromto(): sockname 165.227.249.5[500] 2001-09-05 15:15:09: DEBUG: sockmisc.c:359:sendfromto(): send packet from 165.227.249.5[500] 2001-09-05 15:15:09: DEBUG: sockmisc.c:361:sendfromto(): send packet to 209.186.189.115[500] 2001-09-05 15:15:09: DEBUG: isakmp.c:1327:isakmp_send(): 1 times of 200 bytes message will be sent. 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 04100200 00000000 000000c8 0a000084 90a678a5 3a59347e 551e4eb4 7cc0c890 94e02ba1 c3d3e023 b0edb7a2 d98bbee9 a4c31d57 5497f9f4 7f8ff901 a53cf063 a6ff835f 1fd8338b 5a1b5290 fadefbf1 6ef822b4 0f2a579f ee830cee de7a452f 923e8eea c974e546 dc517ac8 6e3e5434 4c04e8e8 7e00f3bb 1886dc09 e620fca1 051bedb5 05ea045c 17fad106 a6304f1a 0d000014 c6f9350a f26cdf55 ee52f9bf 2b9706d7 00000014 7003cbc1 097dbe9c 2600ba69 83bc8b35 2001-09-05 15:15:09: DEBUG: oakley.c:192:oakley_dh_compute(): compute DH's shared. 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 64243c66 3e83262d 1ada18b6 67132a07 6c2ae6bb 92638350 ecf940e3 856c7b2c 27e18610 88a5dddc 4410c7d0 dc9d369d f603367e d7f4188a a034d128 abe13791 e8a6ea60 22987c7e 9e5b5189 40a570df de8786f1 f810b78d e2b8c29b da3357c6 cb370be1 14e2d8ff c1d7188f b6125704 cfa5f109 7997625f 472b1cc2 72850ad92001-09-05 15:15:09: DEBUG: oakley.c:1924:oakley_skeyid(): psk found: 2001-09-05 15:15:09: DEBUG2: plog.c:204:plogdump(): 6d656b6d 69746173 6469676f 6174 2001-09-05 15:15:09: DEBUG: oakley.c:1938:oakley_skeyid(): nonce 1: 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 87989bb7 902266fb 2001-09-05 15:15:09: DEBUG: oakley.c:1944:oakley_skeyid(): nonce 2: 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): c6f9350a f26cdf55 ee52f9bf 2b9706d7 2001-09-05 15:15:09: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:09: DEBUG: oakley.c:1997:oakley_skeyid(): SKEYID computed: 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 805715f3 ff6d79e0 b9140fb8 6b00f505 a1c770a6 2001-09-05 15:15:09: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:09: DEBUG: oakley.c:2054:oakley_skeyid_dae(): SKEYID_d computed: 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 22f25b29 99faf7ad 1c42dd83 493f08ba e9a66a44 2001-09-05 15:15:09: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:09: DEBUG: oakley.c:2083:oakley_skeyid_dae(): SKEYID_a computed: 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): ce927ac8 8cdf6904 8b7d8c3f 27daeadd 621c783f 2001-09-05 15:15:09: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:09: DEBUG: oakley.c:2112:oakley_skeyid_dae(): SKEYID_e computed: 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 379d5dce 7d29dc7f 7af1bedc da9c12c0 2b512c2a 2001-09-05 15:15:09: DEBUG: oakley.c:2207:oakley_compute_enckey(): len(SKEYID_e) < len(Ka) (20 < 24), generating long key (Ka = K1 | K2 | ...) 2001-09-05 15:15:09: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:09: DEBUG: oakley.c:2232:oakley_compute_enckey(): compute intermediate cipher key K1 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 00 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf 2001-09-05 15:15:09: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:09: DEBUG: oakley.c:2232:oakley_compute_enckey(): compute intermediate cipher key K2 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): a707d8a9 3cc855e5 e32b29e0 4e4a3eed 98b809a7 2001-09-05 15:15:09: DEBUG: oakley.c:2276:oakley_compute_enckey(): final cipher key computed: 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf a707d8a9 2001-09-05 15:15:09: DEBUG: oakley.c:353:oakley_hash(): use sha1 to calculate phase 1. 2001-09-05 15:15:09: DEBUG: oakley.c:2379:oakley_newiv(): IV computed: 2001-09-05 15:15:09: DEBUG: plog.c:204:plogdump(): 17bcfaf8 c92b5f30 2001-09-05 15:15:10: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:15:10: DEBUG: isakmp.c:232:isakmp_handler(): 68 bytes message received from 209.186.189.115[500] 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 05100201 00000000 00000044 d777f3a0 25576c6d 5c8c2aae 1b12f181 0ca8c613 d05b67f0 a13e73b8 749f45d4 7a56c1af be725399 2001-09-05 15:15:10: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:10: DEBUG: oakley.c:2492:oakley_do_decrypt(): begin decryption. 2001-09-05 15:15:10: DEBUG: oakley.c:2498:oakley_do_decrypt(): IV was saved for next processing: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 7a56c1af be725399 2001-09-05 15:15:10: DEBUG: oakley.c:2523:oakley_do_decrypt(): decrypt(3des) 2001-09-05 15:15:10: DEBUG: oakley.c:2526:oakley_do_decrypt(): with key: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf a707d8a9 2001-09-05 15:15:10: DEBUG: oakley.c:2535:oakley_do_decrypt(): decrypted payload by IV: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 7a56c1af be725399 2001-09-05 15:15:10: DEBUG: oakley.c:2538:oakley_do_decrypt(): decrypted payload, but not trimed. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 0800000c 01000000 d1babd73 00000018 a68a2900 5a78219f c4f09ef7 933c4486 2eb91b5b 00000000 2001-09-05 15:15:10: DEBUG: oakley.c:2547:oakley_do_decrypt(): padding len=1 2001-09-05 15:15:10: DEBUG: oakley.c:2561:oakley_do_decrypt(): skip to trim padding. 2001-09-05 15:15:10: DEBUG: oakley.c:2576:oakley_do_decrypt(): decrypted. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 05100201 00000000 00000044 0800000c 01000000 d1babd73 00000018 a68a2900 5a78219f c4f09ef7 933c4486 2eb91b5b 00000000 2001-09-05 15:15:10: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:10: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=5(id) 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=8(hash) 2001-09-05 15:15:10: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:10: DEBUG: oakley.c:1123:oakley_validate_auth(): HASH received:2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): a68a2900 5a78219f c4f09ef7 933c4486 2eb91b5b 2001-09-05 15:15:10: DEBUG: oakley.c:834:oakley_ph1hash_common(): HASH with: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 5b4a431e 1144b108 d6887dda befd546d deeb6a56 28e2a2e7 9c3e66e4 4aae168b 718265fe eeeef826 743a7a8c ecbd0174 5f782807 3ba4ea20 1dec2573 ceb204a4 ffaa4b65 aee23fce a1d75ecb 6fc2befd 63367f2f 3096e9b0 6a981e8a b20950fa 236ec713 b56b784c 5df3339f 9377e328 ee20f117 85e81e25 58a02cf1 2f747f55 90a678a5 3a59347e 551e4eb4 7cc0c890 94e02ba1 c3d3e023 b0edb7a2 d98bbee9 a4c31d57 5497f9f4 7f8ff901 a53cf063 a6ff835f 1fd8338b 5a1b5290 fadefbf1 6ef822b4 0f2a579f ee830cee de7a452f 923e8eea c974e546 dc517ac8 6e3e5434 4c04e8e8 7e00f3bb 1886dc09 e620fca1 051bedb5 05ea045c 17fad106 a6304f1a 49b2e686 6a923488 4b515585 3ed2a469 00000001 00000001 0000002c 01010001 00000024 01010000 80040002 80030001 80010005 80020002 800b0001 000c0004 00000e10 01000000 d1babd73 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: oakley.c:844:oakley_ph1hash_common(): HASH computed: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): a68a2900 5a78219f c4f09ef7 933c4486 2eb91b5b 2001-09-05 15:15:10: DEBUG: oakley.c:1154:oakley_validate_auth(): HASH for PSK validated. 2001-09-05 15:15:10: DEBUG: isakmp_ident.c:1192:ident_r3recv(): peer's ID 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 01000000 d1babd73 2001-09-05 15:15:10: DEBUG: isakmp.c:609:ph1_main(): === 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:3156:ipsecdoi_setid1(): use ID type of IPv4_address 2001-09-05 15:15:10: DEBUG: isakmp_ident.c:1270:ident_r3send(): generate HASH_R 2001-09-05 15:15:10: DEBUG: oakley.c:834:oakley_ph1hash_common(): HASH with: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 90a678a5 3a59347e 551e4eb4 7cc0c890 94e02ba1 c3d3e023 b0edb7a2 d98bbee9 a4c31d57 5497f9f4 7f8ff901 a53cf063 a6ff835f 1fd8338b 5a1b5290 fadefbf1 6ef822b4 0f2a579f ee830cee de7a452f 923e8eea c974e546 dc517ac8 6e3e5434 4c04e8e8 7e00f3bb 1886dc09 e620fca1 051bedb5 05ea045c 17fad106 a6304f1a 5b4a431e 1144b108 d6887dda befd546d deeb6a56 28e2a2e7 9c3e66e4 4aae168b 718265fe eeeef826 743a7a8c ecbd0174 5f782807 3ba4ea20 1dec2573 ceb204a4 ffaa4b65 aee23fce a1d75ecb 6fc2befd 63367f2f 3096e9b0 6a981e8a b20950fa 236ec713 b56b784c 5df3339f 9377e328 ee20f117 85e81e25 58a02cf1 2f747f55 4b515585 3ed2a469 49b2e686 6a923488 00000001 00000001 0000002c 01010001 00000024 01010000 80040002 80030001 80010005 80020002 800b0001 000c0004 00000e10 011101f4 a5e3f905 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: oakley.c:844:oakley_ph1hash_common(): HASH computed: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): ff3a0d75 b29614d3 e2c10678 f22a62a9 44277c08 2001-09-05 15:15:10: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 8, next type 8 2001-09-05 15:15:10: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 20, next type 0 2001-09-05 15:15:10: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:10: DEBUG: oakley.c:2610:oakley_do_encrypt(): begin encryption. 2001-09-05 15:15:10: DEBUG: oakley.c:2617:oakley_do_encrypt(): pad length = 4 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 0800000c 011101f4 a5e3f905 00000018 ff3a0d75 b29614d3 e2c10678 f22a62a9 44277c08 0a713403 2001-09-05 15:15:10: DEBUG: oakley.c:2652:oakley_do_encrypt(): encrypt(3des). 2001-09-05 15:15:10: DEBUG: oakley.c:2655:oakley_do_encrypt(): with key: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf a707d8a9 2001-09-05 15:15:10: DEBUG: oakley.c:2664:oakley_do_encrypt(): encrypted payload by IV: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 26d7884c 2fd37c2d 2001-09-05 15:15:10: DEBUG: oakley.c:2671:oakley_do_encrypt(): save IV for next: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 26d7884c 2fd37c2d 2001-09-05 15:15:10: DEBUG: oakley.c:2688:oakley_do_encrypt(): encrypted. 2001-09-05 15:15:10: DEBUG: sockmisc.c:357:sendfromto(): sockname 165.227.249.5[500] 2001-09-05 15:15:10: DEBUG: sockmisc.c:359:sendfromto(): send packet from 165.227.249.5[500] 2001-09-05 15:15:10: DEBUG: sockmisc.c:361:sendfromto(): send packet to 209.186.189.115[500] 2001-09-05 15:15:10: DEBUG: isakmp.c:1327:isakmp_send(): 1 times of 68 bytes message will be sent. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 05100201 00000000 00000044 90f439b1 f2da6fbe 13a4b663 1237dce1 16ee1a38 2821dd5d 14a46c5c 430c7e14 26d7884c 2fd37c2d 2001-09-05 15:15:10: DEBUG: oakley.c:2423:oakley_newiv2(): compute IV for phase2 2001-09-05 15:15:10: DEBUG: oakley.c:2424:oakley_newiv2(): phase1 last IV: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 26d7884c 2fd37c2d fc50be73 2001-09-05 15:15:10: DEBUG: oakley.c:353:oakley_hash(): use sha1 to calculate phase 1. 2001-09-05 15:15:10: DEBUG: oakley.c:2450:oakley_newiv2(): phase2 IV computed: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 019b129c 5f708076 2001-09-05 15:15:10: DEBUG: oakley.c:715:oakley_compute_hash1(): HASH with: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): fc50be73 0000001c 00000001 01106002 49b2e686 6a923488 4b515585 3ed2a4692001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: oakley.c:725:oakley_compute_hash1(): HASH computed: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 59011375 0e074d22 d1e2ddff 9e73e791 505b3796 2001-09-05 15:15:10: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:10: DEBUG: oakley.c:2610:oakley_do_encrypt(): begin encryption. 2001-09-05 15:15:10: DEBUG: oakley.c:2617:oakley_do_encrypt(): pad length = 4 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 0b000018 59011375 0e074d22 d1e2ddff 9e73e791 505b3796 0000001c 00000001 01106002 49b2e686 6a923488 4b515585 3ed2a469 141fd703 2001-09-05 15:15:10: DEBUG: oakley.c:2652:oakley_do_encrypt(): encrypt(3des). 2001-09-05 15:15:10: DEBUG: oakley.c:2655:oakley_do_encrypt(): with key: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf a707d8a9 2001-09-05 15:15:10: DEBUG: oakley.c:2664:oakley_do_encrypt(): encrypted payload by IV: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 9f786b3c c19a6a33 2001-09-05 15:15:10: DEBUG: oakley.c:2671:oakley_do_encrypt(): save IV for next: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 9f786b3c c19a6a33 2001-09-05 15:15:10: DEBUG: oakley.c:2688:oakley_do_encrypt(): encrypted. 2001-09-05 15:15:10: DEBUG: sockmisc.c:357:sendfromto(): sockname 165.227.249.5[500] 2001-09-05 15:15:10: DEBUG: sockmisc.c:359:sendfromto(): send packet from 165.227.249.5[500] 2001-09-05 15:15:10: DEBUG: sockmisc.c:361:sendfromto(): send packet to 209.186.189.115[500] 2001-09-05 15:15:10: DEBUG: isakmp.c:1327:isakmp_send(): 1 times of 84 bytes message will be sent. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08100501 fc50be73 00000054 b51ef503 3c4a2694 415fe1d8 77d51dea dc62a67b b9c5911d 80e6c683 44667aac f03e988d 7d98ae87 2d394008 776660d0 9f786b3c c19a6a33 2001-09-05 15:15:10: DEBUG: isakmp_inf.c:633:isakmp_info_send_common(): sendto Information notify. 2001-09-05 15:15:10: INFO: isakmp.c:2273:log_ph1established(): ISAKMP-SA established 165.227.249.5[500]-209.186.189.115[500] spi:49b2e6866a923488:4b5155853ed2a469 2001-09-05 15:15:10: DEBUG: isakmp.c:649:ph1_main(): === 2001-09-05 15:15:10: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:15:10: DEBUG: isakmp.c:232:isakmp_handler(): 140 bytes message received from 209.186.189.115[500] 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 0000008c 3e6162b0 8edfdf42 84e3ad95 5ae5bde2 991d4cf7 0231ccb8 18f42562 e392e5a5 7169394e 1da48cfa 021933c4 42cf3b8c 63126b9b e142d305 53f89a79 471d95ff a1e63b6b ca72aecd 17a23623 2f5e6f98 bfbba121 c02797b2 8a453ba4 c608676d 173b2fce 73793880 798d89a0 9436b4ba 2001-09-05 15:15:10: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:10: DEBUG: oakley.c:2423:oakley_newiv2(): compute IV for phase2 2001-09-05 15:15:10: DEBUG: oakley.c:2424:oakley_newiv2(): phase1 last IV: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 26d7884c 2fd37c2d 6587dd9e 2001-09-05 15:15:10: DEBUG: oakley.c:353:oakley_hash(): use sha1 to calculate phase 1. 2001-09-05 15:15:10: DEBUG: oakley.c:2450:oakley_newiv2(): phase2 IV computed: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 28c0b356 d46fd3e9 2001-09-05 15:15:10: DEBUG: isakmp.c:948:isakmp_ph2begin_r(): === 2001-09-05 15:15:10: INFO: isakmp.c:953:isakmp_ph2begin_r(): responde new phase 2 negotiation: 165.227.249.5[0]<=>209.186.189.115[0] 2001-09-05 15:15:10: DEBUG: oakley.c:2492:oakley_do_decrypt(): begin decryption. 2001-09-05 15:15:10: DEBUG: oakley.c:2498:oakley_do_decrypt(): IV was saved for next processing: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 798d89a0 9436b4ba 2001-09-05 15:15:10: DEBUG: oakley.c:2523:oakley_do_decrypt(): decrypt(3des) 2001-09-05 15:15:10: DEBUG: oakley.c:2526:oakley_do_decrypt(): with key: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf a707d8a9 2001-09-05 15:15:10: DEBUG: oakley.c:2535:oakley_do_decrypt(): decrypted payload by IV: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 798d89a0 9436b4ba 2001-09-05 15:15:10: DEBUG: oakley.c:2538:oakley_do_decrypt(): decrypted payload, but not trimed. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 01000018 12f2d6ec 48220e44 817ac2fe 443419d6 848bfa88 0a000034 00000001 00000001 00000028 01030401 d4629a99 0000001c 01030000 80050002 80040001 80010001 00020004 000007f8 0500000c cdffffa2 ba4f15ff 0500000c 01000000 c0a8de1e 0000000c 01000000 0a080807 2001-09-05 15:15:10: DEBUG: oakley.c:2547:oakley_do_decrypt(): padding len=8 2001-09-05 15:15:10: DEBUG: oakley.c:2561:oakley_do_decrypt(): skip to trim padding. 2001-09-05 15:15:10: DEBUG: oakley.c:2576:oakley_do_decrypt(): decrypted. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 0000008c 01000018 12f2d6ec 48220e44 817ac2fe 443419d6 848bfa88 0a000034 00000001 00000001 00000028 01030401 d4629a99 0000001c 01030000 80050002 80040001 80010001 00020004 000007f8 0500000c cdffffa2 ba4f15ff 0500000c 01000000 c0a8de1e 0000000c 01000000 0a080807 2001-09-05 15:15:10: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:10: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=8(hash) 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=1(sa) 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=10(nonce) 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=5(id) 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=5(id) 2001-09-05 15:15:10: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:978:quick_r1recv(): received IDci2:2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 01000000 c0a8de1e 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:982:quick_r1recv(): received IDcr2:2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 01000000 0a080807 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:997:quick_r1recv(): HASH(1) validate:2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 12f2d6ec 48220e44 817ac2fe 443419d6 848bfa88 2001-09-05 15:15:10: DEBUG: oakley.c:715:oakley_compute_hash1(): HASH with: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 6587dd9e 0a000034 00000001 00000001 00000028 01030401 d4629a99 0000001c 01030000 80050002 80040001 80010001 00020004 000007f8 0500000c cdffffa2 ba4f15ff 0500000c 01000000 c0a8de1e 0000000c 01000000 0a080807 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: oakley.c:725:oakley_compute_hash1(): HASH computed: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 12f2d6ec 48220e44 817ac2fe 443419d6 848bfa88 2001-09-05 15:15:10: DEBUG: sainfo.c:98:getsainfo(): anonymous sainfo selected. 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:1700:get_sainfo_r(): get sa info: anonymous 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:1848:get_proposal_r(): get a src address from ID payload 192.168.222.30[0] prefixlen=32 ul_proto=255 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:1853:get_proposal_r(): get dst address from ID payload 10.8.8.7[0] prefixlen=32 ul_proto=255 2001-09-05 15:15:10: DEBUG: policy.c:211:cmpspidx_wild(): sub:0xbfbfd4dc: 192.168.222.30/32[0] 10.8.8.7/32[0] proto=255 dir=1 2001-09-05 15:15:10: DEBUG: policy.c:212:cmpspidx_wild(): db: 0x809c408: 192.168.222.30/32[0] 10.8.8.7/32[0] proto=255 dir=1 2001-09-05 15:15:10: DEBUG: policy.c:239:cmpspidx_wild(): 0xbfbfd4dc masked with /32: 192.168.222.30[0] 2001-09-05 15:15:10: DEBUG: policy.c:241:cmpspidx_wild(): 0x809c408 masked with /32: 192.168.222.30[0] 2001-09-05 15:15:10: DEBUG: policy.c:255:cmpspidx_wild(): 0xbfbfd4dc masked with /32: 10.8.8.7[0] 2001-09-05 15:15:10: DEBUG: policy.c:257:cmpspidx_wild(): 0x809c408 masked with /32: 10.8.8.7[0] 2001-09-05 15:15:10: DEBUG: policy.c:211:cmpspidx_wild(): sub:0xbfbfd4dc: 10.8.8.7/32[0] 192.168.222.30/32[0] proto=255 dir=2 2001-09-05 15:15:10: DEBUG: policy.c:212:cmpspidx_wild(): db: 0x809c408: 192.168.222.30/32[0] 10.8.8.7/32[0] proto=255 dir=1 2001-09-05 15:15:10: DEBUG: policy.c:211:cmpspidx_wild(): sub:0xbfbfd4dc: 10.8.8.7/32[0] 192.168.222.30/32[0] proto=255 dir=2 2001-09-05 15:15:10: DEBUG: policy.c:212:cmpspidx_wild(): db: 0x809c808: 10.8.8.7/32[0] 192.168.222.30/32[0] proto=255 dir=2 2001-09-05 15:15:10: DEBUG: policy.c:239:cmpspidx_wild(): 0xbfbfd4dc masked with /32: 10.8.8.7[0] 2001-09-05 15:15:10: DEBUG: policy.c:241:cmpspidx_wild(): 0x809c808 masked with /32: 10.8.8.7[0] 2001-09-05 15:15:10: DEBUG: policy.c:255:cmpspidx_wild(): 0xbfbfd4dc masked with /32: 192.168.222.30[0] 2001-09-05 15:15:10: DEBUG: policy.c:257:cmpspidx_wild(): 0x809c808 masked with /32: 192.168.222.30[0] 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:1887:get_proposal_r(): suitable SP found:10.8.8.7/32[0] 192.168.222.30/32[0] proto=255 dir=2 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:1979:get_proposal_r(): my single bundle: 2001-09-05 15:15:10: DEBUG: proposal.c:799:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2001-09-05 15:15:10: DEBUG: proposal.c:833:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:1052:get_proppair(): total SA len=48 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 00000001 00000001 00000028 01030401 d4629a99 0000001c 01030000 80050002 80040001 80010001 00020004 000007f8 2001-09-05 15:15:10: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=2(prop) 2001-09-05 15:15:10: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:1105:get_proppair(): proposal #1 len=40 2001-09-05 15:15:10: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=3(trns) 2001-09-05 15:15:10: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:1246:get_transform(): transform #1 len=28 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:2037:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:2037:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:2037:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:2037:check_attr_ipsec(): type=SA Life Duration, flag=0x0000, lorv=4 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:1148:get_proppair(): pair 1: 2001-09-05 15:15:10: DEBUG: proposal.c:866:print_proppair0(): 0x8097790: next=0x0 tnext=0x0 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:1183:get_proppair(): proposal #1: 1 transform 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:887:get_ph2approval(): begin compare proposals. 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:893:get_ph2approval(): pair[1]: 0x8097790 2001-09-05 15:15:10: DEBUG: proposal.c:866:print_proppair0(): 0x8097790: next=0x0 tnext=0x0 2001-09-05 15:15:10: DEBUG: proposal.c:667:aproppair2saprop(): prop#=1 prot-id=ESP spi-size=4 #trns=1 trns#=1 trns-id=3DES 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:3595:ipsecdoi_t2satrns(): type=Authentication Algorithm, flag=0x8000, lorv=2 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:3595:ipsecdoi_t2satrns(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:3595:ipsecdoi_t2satrns(): type=SA Life Type, flag=0x8000, lorv=seconds 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:3595:ipsecdoi_t2satrns(): type=SA Life Duration, flag=0x0000, lorv=4 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:929:get_ph2approvalx(): peer's single bundle: 2001-09-05 15:15:10: DEBUG: proposal.c:799:printsaproto(): (proto_id=ESP spisize=4 spi=d4629a99 spi_p=00000000 encmode=Tunnel reqid=0:0) 2001-09-05 15:15:10: DEBUG: proposal.c:833:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:932:get_ph2approvalx(): my single bundle: 2001-09-05 15:15:10: DEBUG: proposal.c:799:printsaproto(): (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Tunnel reqid=0:0) 2001-09-05 15:15:10: DEBUG: proposal.c:833:printsatrns(): (trns_id=3DES encklen=0 authtype=2) 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:950:get_ph2approvalx(): matched 2001-09-05 15:15:10: DEBUG: isakmp.c:978:isakmp_ph2begin_r(): === 2001-09-05 15:15:10: DEBUG: pfkey.c:824:pk_sendgetspi(): call pfkey_send_getspi 2001-09-05 15:15:10: DEBUG: pfkey.c:837:pk_sendgetspi(): pfkey GETSPI sent: ESP/Tunnel 209.186.189.115->165.227.249.5 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:1093:quick_r1prep(): pfkey getspi sent. 2001-09-05 15:15:10: DEBUG: pfkey.c:190:pfkey_handler(): get pfkey GETSPI message 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 02010003 0a000000 759c983d d1420000 02000100 083bab87 00946ffd 49b2e686 03000500 ff200000 10020000 d1babd73 00000000 00000000 03000600 ff200000 10020000 a5e3f905 00000000 00000000 2001-09-05 15:15:10: DEBUG: pfkey.c:898:pk_recvgetspi(): pfkey GETSPI succeeded: ESP/Tunnel 209.186.189.115->165.227.249.5 spi=138128263(0x83bab87) 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:1052:get_proppair(): total SA len=48 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 00000001 00000001 00000028 01030401 00000000 0000001c 01030000 80050002 80040001 80010001 00020004 000007f8 2001-09-05 15:15:10: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=2(prop) 2001-09-05 15:15:10: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:1105:get_proppair(): proposal #1 len=40 2001-09-05 15:15:10: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=3(trns) 2001-09-05 15:15:10: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:1246:get_transform(): transform #1 len=28 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:2037:check_attr_ipsec(): type=Authentication Algorithm, flag=0x8000, lorv=2 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:2037:check_attr_ipsec(): type=Encription Mode, flag=0x8000, lorv=Tunnel 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:2037:check_attr_ipsec(): type=SA Life Type, flag=0x8000, lorv=seconds 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:2037:check_attr_ipsec(): type=SA Life Duration, flag=0x0000, lorv=4 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:1148:get_proppair(): pair 1: 2001-09-05 15:15:10: DEBUG: proposal.c:866:print_proppair0(): 0x8097790: next=0x0 tnext=0x0 2001-09-05 15:15:10: DEBUG: ipsec_doi.c:1183:get_proppair(): proposal #1: 1 transform 2001-09-05 15:15:10: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 48, next type 10 2001-09-05 15:15:10: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 16, next type 5 2001-09-05 15:15:10: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 8, next type 5 2001-09-05 15:15:10: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 8, next type 0 2001-09-05 15:15:10: DEBUG: oakley.c:715:oakley_compute_hash1(): HASH with: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 6587dd9e cdffffa2 ba4f15ff 0a000034 00000001 00000001 00000028 01030401 083bab87 0000001c 01030000 80050002 80040001 80010001 00020004 000007f8 05000014 806f29cd 7611ff5e 5333bb36 d2e96402 0500000c 01000000 c0a8de1e 0000000c 01000000 0a080807 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: oakley.c:725:oakley_compute_hash1(): HASH computed: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 01330661 0354709a 5bba8988 b2994e8d 26033df5 2001-09-05 15:15:10: DEBUG: isakmp.c:1989:set_isakmp_payload(): add payload of len 20, next type 1 2001-09-05 15:15:10: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:10: DEBUG: oakley.c:2610:oakley_do_encrypt(): begin encryption. 2001-09-05 15:15:10: DEBUG: oakley.c:2617:oakley_do_encrypt(): pad length = 8 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 01000018 01330661 0354709a 5bba8988 b2994e8d 26033df5 0a000034 00000001 00000001 00000028 01030401 083bab87 0000001c 01030000 80050002 80040001 80010001 00020004 000007f8 05000014 806f29cd 7611ff5e 5333bb36 d2e96402 0500000c 01000000 c0a8de1e 0000000c 01000000 0a080807 09bd979c 0541f2072001-09-05 15:15:10: DEBUG: oakley.c:2652:oakley_do_encrypt(): encrypt(3des). 2001-09-05 15:15:10: DEBUG: oakley.c:2655:oakley_do_encrypt(): with key: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf a707d8a9 2001-09-05 15:15:10: DEBUG: oakley.c:2664:oakley_do_encrypt(): encrypted payload by IV: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 26b4d608 021ecd09 2001-09-05 15:15:10: DEBUG: oakley.c:2671:oakley_do_encrypt(): save IV for next: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 26b4d608 021ecd09 2001-09-05 15:15:10: DEBUG: oakley.c:2688:oakley_do_encrypt(): encrypted. 2001-09-05 15:15:10: DEBUG: sockmisc.c:357:sendfromto(): sockname 165.227.249.5[500] 2001-09-05 15:15:10: DEBUG: sockmisc.c:359:sendfromto(): send packet from 165.227.249.5[500] 2001-09-05 15:15:10: DEBUG: sockmisc.c:361:sendfromto(): send packet to 209.186.189.115[500] 2001-09-05 15:15:10: DEBUG: isakmp.c:1327:isakmp_send(): 1 times of 156 bytes message will be sent. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 0000009c d6740970 5b154d6f 76fc446b 0488ab08 434c951d 32dc6a91 7a94199a 23553048 a41699ce 96896464 00cc96b1 bc7e903e 466a5b6f f8be586e b8c24629 910c0c4a 3baea5d7 aa3eab02 b8689230 1e7c9a62 9d283089 8e254d85 5ee3a2d3 99e6b29a 5a7eeccb 798a173a 2d2f34ae 65c70b64 5ef2ecb6 d626656e 26b4d608 021ecd09 2001-09-05 15:15:10: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:15:10: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:15:10: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:10: DEBUG: oakley.c:2492:oakley_do_decrypt(): begin decryption. 2001-09-05 15:15:10: DEBUG: oakley.c:2498:oakley_do_decrypt(): IV was saved for next processing: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 31a2ad50 2d055f27 2001-09-05 15:15:10: DEBUG: oakley.c:2523:oakley_do_decrypt(): decrypt(3des) 2001-09-05 15:15:10: DEBUG: oakley.c:2526:oakley_do_decrypt(): with key: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf a707d8a9 2001-09-05 15:15:10: DEBUG: oakley.c:2535:oakley_do_decrypt(): decrypted payload by IV: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 31a2ad50 2d055f27 2001-09-05 15:15:10: DEBUG: oakley.c:2538:oakley_do_decrypt(): decrypted payload, but not trimed. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 00000018 6af4f326 d29460ca 89090b9f beca1bf7 e31df480 2001-09-05 15:15:10: DEBUG: oakley.c:2547:oakley_do_decrypt(): padding len=129 2001-09-05 15:15:10: DEBUG: oakley.c:2561:oakley_do_decrypt(): skip to trim padding. 2001-09-05 15:15:10: DEBUG: oakley.c:2576:oakley_do_decrypt(): decrypted. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 00000018 6af4f326 d29460ca 89090b9f beca1bf7 e31df480 2001-09-05 15:15:10: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:10: DEBUG: isakmp.c:1011:isakmp_parsewoh(): begin. 2001-09-05 15:15:10: DEBUG: isakmp.c:1038:isakmp_parsewoh(): seen nptype=8(hash) 2001-09-05 15:15:10: DEBUG: isakmp.c:1076:isakmp_parsewoh(): succeed. 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:1363:quick_r3recv(): HASH(3) validate:2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 6af4f326 d29460ca 89090b9f beca1bf7 e31df480 2001-09-05 15:15:10: DEBUG: oakley.c:659:oakley_compute_hash3(): HASH with: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 006587dd 9ecdffff a2ba4f15 ff806f29 cd7611ff 5e5333bb 36d2e964 02 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: oakley.c:669:oakley_compute_hash3(): HASH computed: 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 6af4f326 d29460ca 89090b9f beca1bf7 e31df480 2001-09-05 15:15:10: DEBUG: isakmp.c:713:quick_main(): === 2001-09-05 15:15:10: DEBUG: oakley.c:462:oakley_compute_keymat_x(): KEYMAT compute with 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 03083bab 87cdffff a2ba4f15 ff806f29 cd7611ff 5e5333bb 36d2e964 02 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: oakley.c:475:oakley_compute_keymat_x(): dupkeymat=3 2001-09-05 15:15:10: DEBUG: oakley.c:491:oakley_compute_keymat_x(): generating K1...K3 for KEYMAT. 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): ee3a7e0e be3d2a03 03849138 5c7809db 975fb596 c8916d53 c9372850 a9755a33 0599d1a6 b42e0026 106b3107 7ff5007d efa7a7eb 37caffce 9a02fa85 2001-09-05 15:15:10: DEBUG: oakley.c:462:oakley_compute_keymat_x(): KEYMAT compute with 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 03d4629a 99cdffff a2ba4f15 ff806f29 cd7611ff 5e5333bb 36d2e964 02 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: oakley.c:475:oakley_compute_keymat_x(): dupkeymat=3 2001-09-05 15:15:10: DEBUG: oakley.c:491:oakley_compute_keymat_x(): generating K1...K3 for KEYMAT. 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): be06da0b 7e140589 0aa647ed 57a8865d a54df79b 606b692a 02a78151 8ecdca49 c3f15243 0550b1e9 453bc49a 02634f9a b9616a71 d2257def d372ba06 2001-09-05 15:15:10: DEBUG: oakley.c:392:oakley_compute_keymat(): KEYMAT computed. 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:1541:quick_r3prep(): call pk_sendupdate 2001-09-05 15:15:10: DEBUG: pfkey.c:985:pk_sendupdate(): call pfkey_send_update 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:1546:quick_r3prep(): pfkey update sent. 2001-09-05 15:15:10: DEBUG: pfkey.c:1174:pk_sendadd(): call pfkey_send_add 2001-09-05 15:15:10: DEBUG: isakmp_quick.c:1553:quick_r3prep(): pfkey add sent. 2001-09-05 15:15:10: DEBUG: pfkey.c:190:pfkey_handler(): get pfkey UPDATE message 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 02020003 14000000 759c983d d1420000 02000100 083bab87 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 d1babd73 00000000 00000000 03000600 ff200000 10020000 a5e3f905 00000000 00000000 04000300 00000000 00000000 00000000 f8070000 00000000 00000000 00000000 04000400 00000000 00000000 00000000 60060000 00000000 00000000 000000002001-09-05 15:15:10: DEBUG: pfkey.c:1079:pk_recvupdate(): pfkey UPDATE succeeded: ESP/Tunnel 209.186.189.115->165.227.249.5 spi=138128263(0x83bab87) 2001-09-05 15:15:10: INFO: pfkey.c:1086:pk_recvupdate(): IPsec-SA established: ESP/Tunnel 209.186.189.115->165.227.249.5 spi=138128263(0x83bab87) 2001-09-05 15:15:10: DEBUG: pfkey.c:1118:pk_recvupdate(): === 2001-09-05 15:15:10: DEBUG: pfkey.c:190:pfkey_handler(): get pfkey ADD message 2001-09-05 15:15:10: DEBUG: plog.c:204:plogdump(): 02030003 14000000 759c983d d1420000 02000100 d4629a99 04000303 00000000 02001300 02000000 00000000 00000000 03000500 ff200000 10020000 a5e3f905 00000000 00000000 03000600 ff200000 10020000 d1babd73 00000000 00000000 04000300 00000000 00000000 00000000 f8070000 00000000 00000000 00000000 04000400 00000000 00000000 00000000 60060000 00000000 00000000 000000002001-09-05 15:15:10: INFO: pfkey.c:1246:pk_recvadd(): IPsec-SA established: ESP/Tunnel 165.227.249.5->209.186.189.115 spi=3563231897(0xd4629a99) 2001-09-05 15:15:10: DEBUG: pfkey.c:1251:pk_recvadd(): === 2001-09-05 15:15:12: DEBUG: grabmyaddr.c:446:update_myaddrs(): msg 1 not interesting 2001-09-05 15:15:40: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:15:40: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:15:40: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:15:40: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:15:40: DEBUG: isakmp.c:503:isakmp_main(): the packet retransmited by peer. 2001-09-05 15:16:10: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:16:10: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:16:10: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:16:10: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:16:10: DEBUG: isakmp.c:503:isakmp_main(): the packet retransmited by peer. 2001-09-05 15:16:41: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:16:41: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:16:41: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:16:41: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:16:41: DEBUG: isakmp.c:503:isakmp_main(): the packet retransmited by peer. 2001-09-05 15:17:11: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:17:11: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:17:11: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:17:11: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:17:11: DEBUG: isakmp.c:503:isakmp_main(): the packet retransmited by peer. 2001-09-05 15:17:41: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:17:41: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:17:41: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:17:41: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:17:41: DEBUG: isakmp.c:503:isakmp_main(): the packet retransmited by peer. 2001-09-05 15:18:11: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:18:11: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:18:11: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:18:11: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:18:11: DEBUG: isakmp.c:503:isakmp_main(): the packet retransmited by peer. 2001-09-05 15:18:41: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:18:41: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:18:41: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:18:41: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:18:41: DEBUG: isakmp.c:503:isakmp_main(): the packet retransmited by peer. 2001-09-05 15:19:11: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:19:11: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:19:11: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:19:11: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:19:11: DEBUG: isakmp.c:503:isakmp_main(): the packet retransmited by peer. 2001-09-05 15:19:41: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:19:41: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:19:41: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:19:41: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:19:41: DEBUG: isakmp.c:503:isakmp_main(): the packet retransmited by peer. 2001-09-05 15:20:11: DEBUG: isakmp.c:231:isakmp_handler(): === 2001-09-05 15:20:11: DEBUG: isakmp.c:232:isakmp_handler(): 52 bytes message received from 209.186.189.115[500] 2001-09-05 15:20:11: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08102001 6587dd9e 00000034 c926bffb c781a1ba 72e5322e b71bcb03 31a2ad50 2d055f27 2001-09-05 15:20:11: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:20:11: DEBUG: isakmp.c:503:isakmp_main(): the packet retransmited by peer. 2001-09-05 15:42:01: INFO: session.c:274:check_sigreq(): caught signal 15 2001-09-05 15:42:01: DEBUG: pfkey.c:190:pfkey_handler(): get pfkey FLUSH message 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 02090000 02000000 00000000 d1420000 2001-09-05 15:42:01: DEBUG: oakley.c:2423:oakley_newiv2(): compute IV for phase2 2001-09-05 15:42:01: DEBUG: oakley.c:2424:oakley_newiv2(): phase1 last IV: 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 26d7884c 2fd37c2d 64f94526 2001-09-05 15:42:01: DEBUG: oakley.c:353:oakley_hash(): use sha1 to calculate phase 1. 2001-09-05 15:42:01: DEBUG: oakley.c:2450:oakley_newiv2(): phase2 IV computed: 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 725ab595 1876be68 2001-09-05 15:42:01: DEBUG: oakley.c:715:oakley_compute_hash1(): HASH with: 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 64f94526 00000010 00000001 03040001 083bab87 2001-09-05 15:42:01: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:42:01: DEBUG: oakley.c:725:oakley_compute_hash1(): HASH computed: 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 4b399d53 df7b7bbe cf110775 784a61c9 76576044 2001-09-05 15:42:01: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:42:01: DEBUG: oakley.c:2610:oakley_do_encrypt(): begin encryption. 2001-09-05 15:42:01: DEBUG: oakley.c:2617:oakley_do_encrypt(): pad length = 8 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 0c000018 4b399d53 df7b7bbe cf110775 784a61c9 76576044 00000010 00000001 03040001 083bab87 9eba91e9 d3daba07 2001-09-05 15:42:01: DEBUG: oakley.c:2652:oakley_do_encrypt(): encrypt(3des). 2001-09-05 15:42:01: DEBUG: oakley.c:2655:oakley_do_encrypt(): with key: 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf a707d8a9 2001-09-05 15:42:01: DEBUG: oakley.c:2664:oakley_do_encrypt(): encrypted payload by IV: 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 6ac3dc31 28ee39e9 2001-09-05 15:42:01: DEBUG: oakley.c:2671:oakley_do_encrypt(): save IV for next: 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 6ac3dc31 28ee39e9 2001-09-05 15:42:01: DEBUG: oakley.c:2688:oakley_do_encrypt(): encrypted. 2001-09-05 15:42:01: DEBUG: sockmisc.c:357:sendfromto(): sockname 165.227.249.5[500] 2001-09-05 15:42:01: DEBUG: sockmisc.c:359:sendfromto(): send packet from 165.227.249.5[500] 2001-09-05 15:42:01: DEBUG: sockmisc.c:361:sendfromto(): send packet to 209.186.189.115[500] 2001-09-05 15:42:01: DEBUG: isakmp.c:1327:isakmp_send(): 1 times of 76 bytes message will be sent. 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08100501 64f94526 0000004c 32e78462 8df6dc79 1db6535d 68b307df 3f776d5b aac79179 dcbd2497 c3d61c2c 2d2ff6ea 344f781e 6ac3dc31 28ee39e9 2001-09-05 15:42:01: DEBUG: isakmp_inf.c:633:isakmp_info_send_common(): sendto Information delete. 2001-09-05 15:42:01: DEBUG: grabmyaddr.c:452:update_myaddrs(): caught rtm:15, need update interface address list 2001-09-05 15:42:01: DEBUG: grabmyaddr.c:446:update_myaddrs(): msg 14 not interesting 2001-09-05 15:42:01: DEBUG: pfkey.c:190:pfkey_handler(): get pfkey FLUSH message 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 02090000 02000000 00000000 42430000 2001-09-05 15:42:01: DEBUG: pfkey.c:190:pfkey_handler(): get pfkey X_SPDFLUSH message 2001-09-05 15:42:01: DEBUG: plog.c:204:plogdump(): 02130000 02000000 00000000 43430000 2001-09-05 15:42:02: DEBUG: pfkey.c:266:pfkey_dump_sadb(): call pfkey_send_dump 2001-09-05 15:42:02: DEBUG: oakley.c:2423:oakley_newiv2(): compute IV for phase2 2001-09-05 15:42:02: DEBUG: oakley.c:2424:oakley_newiv2(): phase1 last IV: 2001-09-05 15:42:02: DEBUG: plog.c:204:plogdump(): 26d7884c 2fd37c2d c225c67c 2001-09-05 15:42:02: DEBUG: oakley.c:353:oakley_hash(): use sha1 to calculate phase 1. 2001-09-05 15:42:02: DEBUG: oakley.c:2450:oakley_newiv2(): phase2 IV computed: 2001-09-05 15:42:02: DEBUG: plog.c:204:plogdump(): e77a6907 6c51286a 2001-09-05 15:42:02: DEBUG: oakley.c:715:oakley_compute_hash1(): HASH with: 2001-09-05 15:42:02: DEBUG: plog.c:204:plogdump(): c225c67c 0000001c 00000001 01100001 49b2e686 6a923488 4b515585 3ed2a4692001-09-05 15:42:02: DEBUG: oakley.c:310:oakley_prf(): hmac-sha1 used. 2001-09-05 15:42:02: DEBUG: oakley.c:725:oakley_compute_hash1(): HASH computed: 2001-09-05 15:42:02: DEBUG: plog.c:204:plogdump(): 90e25a8d cea634f8 806e5e75 c932bb32 0a612c4f 2001-09-05 15:42:02: DEBUG: isakmp.c:2110:isakmp_printpacket(): begin. 2001-09-05 15:42:02: DEBUG: oakley.c:2610:oakley_do_encrypt(): begin encryption. 2001-09-05 15:42:02: DEBUG: oakley.c:2617:oakley_do_encrypt(): pad length = 4 2001-09-05 15:42:02: DEBUG: plog.c:204:plogdump(): 0c000018 90e25a8d cea634f8 806e5e75 c932bb32 0a612c4f 0000001c 00000001 01100001 49b2e686 6a923488 4b515585 3ed2a469 bbfc7e03 2001-09-05 15:42:02: DEBUG: oakley.c:2652:oakley_do_encrypt(): encrypt(3des). 2001-09-05 15:42:02: DEBUG: oakley.c:2655:oakley_do_encrypt(): with key: 2001-09-05 15:42:02: DEBUG: plog.c:204:plogdump(): 28fe0735 433122d5 1889ac1d ebbcdf8e ba9508cf a707d8a9 2001-09-05 15:42:02: DEBUG: oakley.c:2664:oakley_do_encrypt(): encrypted payload by IV: 2001-09-05 15:42:02: DEBUG: plog.c:204:plogdump(): be28b23b e5fce12a 2001-09-05 15:42:02: DEBUG: oakley.c:2671:oakley_do_encrypt(): save IV for next: 2001-09-05 15:42:02: DEBUG: plog.c:204:plogdump(): be28b23b e5fce12a 2001-09-05 15:42:02: DEBUG: oakley.c:2688:oakley_do_encrypt(): encrypted. 2001-09-05 15:42:02: DEBUG: sockmisc.c:357:sendfromto(): sockname 165.227.249.5[500] 2001-09-05 15:42:02: DEBUG: sockmisc.c:359:sendfromto(): send packet from 165.227.249.5[500] 2001-09-05 15:42:02: DEBUG: sockmisc.c:361:sendfromto(): send packet to 209.186.189.115[500] 2001-09-05 15:42:02: DEBUG: isakmp.c:1327:isakmp_send(): 1 times of 84 bytes message will be sent. 2001-09-05 15:42:02: DEBUG: plog.c:204:plogdump(): 49b2e686 6a923488 4b515585 3ed2a469 08100501 c225c67c 00000054 ad2bb08c 73b92187 b54f551c 0842bef8 845cad69 caab3284 dab718d0 95e18646 f4a097c4 3de36c8b 6e7169dd 7a3ea946 be28b23b e5fce12a 2001-09-05 15:42:02: DEBUG: isakmp_inf.c:633:isakmp_info_send_common(): sendto Information delete. 2001-09-05 15:42:02: INFO: session.c:178:close_session(): racoon shutdown