2002-07-15 17:19:01: DEBUG: pfkey.c:192:pfkey_handler(): get pfkey ACQUIRE message
2002-07-15 17:19:01: DEBUG2: plog.c:209:plogdump():
02060003 2a000000 c7010000 00000000 05000500 ffe00000 1c1c0000 00000000
3ffe0b80 04470001 00000000 00000001 00000000 00000000 05000600 ffe00000
1c1c0000 00000000 3ffe0b80 04470001 020476ff feda1de4 00000000 00000000
02001200 02000200 09000000 00000000 1c000d00 20000000 00030000 00000000
00010008 00000000 01000000 01000000 00000000 00000000 00000000 00000000
00000000 00000000 80510100 00000000 80700000 00000000 00000000 00000000
00040000 00000000 0001c001 00000000 01000000 01000000 00000000 00000000
00000000 00000000 00000000 00000000 80510100 00000000 80700000 00000000
00000000 00000000 000c0000 00000000 00010001 00000000 01000000 01000000
00000000 00000000 00000000 00000000 00000000 00000000 80510100 00000000
80700000 00000000 00000000 00000000
2002-07-15 17:19:01: DEBUG: policy.c:245:cmpspidxwild(): sub:0xbfbff8dc: 3ffe:b80:447:1:204:76ff:feda:1de4/128[0] 3ffe:b80:447:1::1/128[0] proto=any dir=in
2002-07-15 17:19:01: DEBUG: policy.c:246:cmpspidxwild(): db: 0x80a3a08: 3ffe:b80:447:1:204:76ff:feda:1de4/128[0] 3ffe:b80:447:1::1/128[0] proto=any dir=in
2002-07-15 17:19:01: DEBUG: policy.c:274:cmpspidxwild(): 0xbfbff8dc masked with /128: 3ffe:b80:447:1:204:76ff:feda:1de4[0]
2002-07-15 17:19:01: DEBUG: policy.c:276:cmpspidxwild(): 0x80a3a08 masked with /128: 3ffe:b80:447:1:204:76ff:feda:1de4[0]
2002-07-15 17:19:01: DEBUG: policy.c:290:cmpspidxwild(): 0xbfbff8dc masked with /128: 3ffe:b80:447:1::1[0]
2002-07-15 17:19:01: DEBUG: policy.c:292:cmpspidxwild(): 0x80a3a08 masked with /128: 3ffe:b80:447:1::1[0]
2002-07-15 17:19:01: DEBUG: pfkey.c:1539:pk_recvacquire(): suitable outbound SP found: 3ffe:b80:447:1::1/128[0] 3ffe:b80:447:1:204:76ff:feda:1de4/128[0] proto=any dir=out.
2002-07-15 17:19:01: DEBUG: pfkey.c:1541:pk_recvacquire(): suitable inbound SP found: 3ffe:b80:447:1:204:76ff:feda:1de4/128[0] 3ffe:b80:447:1::1/128[0] proto=any dir=in.
2002-07-15 17:19:01: DEBUG: pfkey.c:1573:pk_recvacquire(): new acquire 3ffe:b80:447:1::1/128[0] 3ffe:b80:447:1:204:76ff:feda:1de4/128[0] proto=any dir=out
2002-07-15 17:19:01: DEBUG: sainfo.c:99:getsainfo(): anonymous sainfo selected.
2002-07-15 17:19:01: DEBUG: proposal.c:824:printsaproto():  (proto_id=AH spisize=4 spi=00000000 spi_p=00000000 encmode=Transport reqid=0:0)
2002-07-15 17:19:01: DEBUG: proposal.c:852:printsatrns():   (trns_id=SHA authtype=2)
2002-07-15 17:19:01: DEBUG: proposal.c:852:printsatrns():   (trns_id=MD5 authtype=1)
2002-07-15 17:19:01: DEBUG: proposal.c:824:printsaproto():  (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Transport reqid=0:0)
2002-07-15 17:19:01: DEBUG: proposal.c:858:printsatrns():   (trns_id=3DES encklen=0 authtype=2)
2002-07-15 17:19:01: DEBUG: proposal.c:858:printsatrns():   (trns_id=3DES encklen=0 authtype=1)
2002-07-15 17:19:01: DEBUG: remoteconf.c:129:getrmconf(): anonymous configuration selected for 3ffe:b80:447:1:204:76ff:feda:1de4.
2002-07-15 17:19:01: INFO: isakmp.c:1734:isakmp_post_acquire(): IPsec-SA request for 3ffe:b80:447:1:204:76ff:feda:1de4 queued due to no phase1 found.
2002-07-15 17:19:01: DEBUG: isakmp.c:819:isakmp_ph1begin_i(): ===
2002-07-15 17:19:01: INFO: isakmp.c:824:isakmp_ph1begin_i(): initiate new phase 1 negotiation: 3ffe:b80:447:1::1[500]<=>3ffe:b80:447:1:204:76ff:feda:1de4[500]
2002-07-15 17:19:01: INFO: isakmp.c:829:isakmp_ph1begin_i(): begin Identity Protection mode.
2002-07-15 17:19:01: DEBUG: isakmp.c:2046:isakmp_newcookie(): new cookie:
c4a28d4ecc06791d
2002-07-15 17:19:01: DEBUG: isakmp.c:2163:set_isakmp_payload(): add payload of len 48, next type 0
2002-07-15 17:19:01: DEBUG: isakmp.c:2298:isakmp_printpacket(): begin.
19:01.912719 3ffe:b80:447:1::1:500 -> 3ffe:b80:447:1:204:76ff:feda:1de4:500: isakmp 1.0 msgid 00000000: phase 1 I ident:
    (sa: doi=ipsec situation=identity
        (p: #1 protoid=isakmp transform=1
            (t: #1 id=ike (type=lifetype value=sec)(type=lifeduration value=0c30)(type=enc value=3des)(type=auth value=preshared)(type=hash value=sha1)(type=group desc value=modp1024))))
2002-07-15 17:19:01: DEBUG: sockmisc.c:419:sendfromto(): sockname 3ffe:b80:447:1::1[500]
2002-07-15 17:19:01: DEBUG: sockmisc.c:421:sendfromto(): send packet from 3ffe:b80:447:1::1[500]
2002-07-15 17:19:01: DEBUG: sockmisc.c:423:sendfromto(): send packet to 3ffe:b80:447:1:204:76ff:feda:1de4[500]
2002-07-15 17:19:01: DEBUG: sockmisc.c:479:sendfromto(): src6 3ffe:b80:447:1::1[500] 0
2002-07-15 17:19:01: DEBUG: sockmisc.c:483:sendfromto(): dst6 3ffe:b80:447:1:204:76ff:feda:1de4[500] 0
2002-07-15 17:19:01: DEBUG: isakmp.c:1470:isakmp_send(): 1 times of 80 bytes message will be sent.
2002-07-15 17:19:01: DEBUG: plog.c:209:plogdump():
c4a28d4e cc06791d 00000000 00000000 01100200 00000000 00000050 00000034
00000001 00000001 00000028 01010001 00000020 01010000 800b0001 800c0c30
80010005 80030001 80020002 80040002
2002-07-15 17:19:21: DEBUG: isakmp.c:1490:isakmp_ph1resend(): resend phase1 packet c4a28d4ecc06791d:0000000000000000
2002-07-15 17:19:21: DEBUG: sockmisc.c:419:sendfromto(): sockname 3ffe:b80:447:1::1[500]
2002-07-15 17:19:21: DEBUG: sockmisc.c:421:sendfromto(): send packet from 3ffe:b80:447:1::1[500]
2002-07-15 17:19:21: DEBUG: sockmisc.c:423:sendfromto(): send packet to 3ffe:b80:447:1:204:76ff:feda:1de4[500]
2002-07-15 17:19:21: DEBUG: sockmisc.c:479:sendfromto(): src6 3ffe:b80:447:1::1[500] 0
2002-07-15 17:19:21: DEBUG: sockmisc.c:483:sendfromto(): dst6 3ffe:b80:447:1:204:76ff:feda:1de4[500] 0
2002-07-15 17:19:21: DEBUG: isakmp.c:1470:isakmp_send(): 1 times of 80 bytes message will be sent.
2002-07-15 17:19:21: DEBUG: plog.c:209:plogdump():
c4a28d4e cc06791d 00000000 00000000 01100200 00000000 00000050 00000034
00000001 00000001 00000028 01010001 00000020 01010000 800b0001 800c0c30
80010005 80030001 80020002 80040002
2002-07-15 17:19:26: DEBUG: pfkey.c:192:pfkey_handler(): get pfkey ACQUIRE message
2002-07-15 17:19:26: DEBUG2: plog.c:209:plogdump():
02060003 2a000000 c8010000 00000000 05000500 ffe00000 1c1c0000 00000000
3ffe0b80 04470001 00000000 00000001 00000000 00000000 05000600 ffe00000
1c1c0000 00000000 3ffe0b80 04470001 020476ff feda1de4 00000000 00000000
02001200 02000200 09000000 00000000 1c000d00 20000000 00030000 00000000
00010008 00000000 01000000 01000000 00000000 00000000 00000000 00000000
00000000 00000000 80510100 00000000 80700000 00000000 00000000 00000000
00040000 00000000 0001c001 00000000 01000000 01000000 00000000 00000000
00000000 00000000 00000000 00000000 80510100 00000000 80700000 00000000
00000000 00000000 000c0000 00000000 00010001 00000000 01000000 01000000
00000000 00000000 00000000 00000000 00000000 00000000 80510100 00000000
80700000 00000000 00000000 00000000
2002-07-15 17:19:26: DEBUG: pfkey.c:1503:pk_recvacquire(): ignore the acquire becuase ph2 found
2002-07-15 17:19:33: ERROR: isakmp.c:1826:isakmp_chkph1there(): phase2 negotiation failed due to time up waiting for phase1. ESP 3ffe:b80:447:1:204:76ff:feda:1de4->3ffe:b80:447:1::1
2002-07-15 17:19:33: INFO: isakmp.c:1831:isakmp_chkph1there(): delete phase 2 handler.
2002-07-15 17:19:41: DEBUG: isakmp.c:1490:isakmp_ph1resend(): resend phase1 packet c4a28d4ecc06791d:0000000000000000
2002-07-15 17:19:41: DEBUG: sockmisc.c:419:sendfromto(): sockname 3ffe:b80:447:1::1[500]
2002-07-15 17:19:41: DEBUG: sockmisc.c:421:sendfromto(): send packet from 3ffe:b80:447:1::1[500]
2002-07-15 17:19:41: DEBUG: sockmisc.c:423:sendfromto(): send packet to 3ffe:b80:447:1:204:76ff:feda:1de4[500]
2002-07-15 17:19:41: DEBUG: sockmisc.c:479:sendfromto(): src6 3ffe:b80:447:1::1[500] 0
2002-07-15 17:19:41: DEBUG: sockmisc.c:483:sendfromto(): dst6 3ffe:b80:447:1:204:76ff:feda:1de4[500] 0
2002-07-15 17:19:41: DEBUG: isakmp.c:1470:isakmp_send(): 1 times of 80 bytes message will be sent.
2002-07-15 17:19:41: DEBUG: plog.c:209:plogdump():
c4a28d4e cc06791d 00000000 00000000 01100200 00000000 00000050 00000034
00000001 00000001 00000028 01010001 00000020 01010000 800b0001 800c0c30
80010005 80030001 80020002 80040002
2002-07-15 17:19:47: DEBUG: pfkey.c:192:pfkey_handler(): get pfkey ACQUIRE message
2002-07-15 17:19:47: DEBUG2: plog.c:209:plogdump():
02060003 2a000000 c9010000 00000000 05000500 ffe00000 1c1c0000 00000000
3ffe0b80 04470001 00000000 00000001 00000000 00000000 05000600 ffe00000
1c1c0000 00000000 3ffe0b80 04470001 020476ff feda1de4 00000000 00000000
02001200 02000200 09000000 00000000 1c000d00 20000000 00030000 00000000
00010008 00000000 01000000 01000000 00000000 00000000 00000000 00000000
00000000 00000000 80510100 00000000 80700000 00000000 00000000 00000000
00040000 00000000 0001c001 00000000 01000000 01000000 00000000 00000000
00000000 00000000 00000000 00000000 80510100 00000000 80700000 00000000
00000000 00000000 000c0000 00000000 00010001 00000000 01000000 01000000
00000000 00000000 00000000 00000000 00000000 00000000 80510100 00000000
80700000 00000000 00000000 00000000
2002-07-15 17:19:47: DEBUG: policy.c:245:cmpspidxwild(): sub:0xbfbff8dc: 3ffe:b80:447:1:204:76ff:feda:1de4/128[0] 3ffe:b80:447:1::1/128[0] proto=any dir=in
2002-07-15 17:19:47: DEBUG: policy.c:246:cmpspidxwild(): db: 0x80a3a08: 3ffe:b80:447:1:204:76ff:feda:1de4/128[0] 3ffe:b80:447:1::1/128[0] proto=any dir=in
2002-07-15 17:19:47: DEBUG: policy.c:274:cmpspidxwild(): 0xbfbff8dc masked with /128: 3ffe:b80:447:1:204:76ff:feda:1de4[0]
2002-07-15 17:19:47: DEBUG: policy.c:276:cmpspidxwild(): 0x80a3a08 masked with /128: 3ffe:b80:447:1:204:76ff:feda:1de4[0]
2002-07-15 17:19:47: DEBUG: policy.c:290:cmpspidxwild(): 0xbfbff8dc masked with /128: 3ffe:b80:447:1::1[0]
2002-07-15 17:19:47: DEBUG: policy.c:292:cmpspidxwild(): 0x80a3a08 masked with /128: 3ffe:b80:447:1::1[0]
2002-07-15 17:19:47: DEBUG: pfkey.c:1539:pk_recvacquire(): suitable outbound SP found: 3ffe:b80:447:1::1/128[0] 3ffe:b80:447:1:204:76ff:feda:1de4/128[0] proto=any dir=out.
2002-07-15 17:19:47: DEBUG: pfkey.c:1541:pk_recvacquire(): suitable inbound SP found: 3ffe:b80:447:1:204:76ff:feda:1de4/128[0] 3ffe:b80:447:1::1/128[0] proto=any dir=in.
2002-07-15 17:19:47: DEBUG: pfkey.c:1573:pk_recvacquire(): new acquire 3ffe:b80:447:1::1/128[0] 3ffe:b80:447:1:204:76ff:feda:1de4/128[0] proto=any dir=out
2002-07-15 17:19:47: DEBUG: sainfo.c:99:getsainfo(): anonymous sainfo selected.
2002-07-15 17:19:47: DEBUG: proposal.c:824:printsaproto():  (proto_id=AH spisize=4 spi=00000000 spi_p=00000000 encmode=Transport reqid=0:0)
2002-07-15 17:19:47: DEBUG: proposal.c:852:printsatrns():   (trns_id=SHA authtype=2)
2002-07-15 17:19:47: DEBUG: proposal.c:852:printsatrns():   (trns_id=MD5 authtype=1)
2002-07-15 17:19:47: DEBUG: proposal.c:824:printsaproto():  (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Transport reqid=0:0)
2002-07-15 17:19:47: DEBUG: proposal.c:858:printsatrns():   (trns_id=3DES encklen=0 authtype=2)
2002-07-15 17:19:47: DEBUG: proposal.c:858:printsatrns():   (trns_id=3DES encklen=0 authtype=1)
2002-07-15 17:19:47: DEBUG: remoteconf.c:129:getrmconf(): anonymous configuration selected for 3ffe:b80:447:1:204:76ff:feda:1de4.
2002-07-15 17:19:47: INFO: isakmp.c:1753:isakmp_post_acquire(): request for establishing IPsec-SA was queued due to no phase1 found.
2002-07-15 17:20:01: DEBUG: isakmp.c:1490:isakmp_ph1resend(): resend phase1 packet c4a28d4ecc06791d:0000000000000000
2002-07-15 17:20:01: DEBUG: sockmisc.c:419:sendfromto(): sockname 3ffe:b80:447:1::1[500]
2002-07-15 17:20:01: DEBUG: sockmisc.c:421:sendfromto(): send packet from 3ffe:b80:447:1::1[500]
2002-07-15 17:20:01: DEBUG: sockmisc.c:423:sendfromto(): send packet to 3ffe:b80:447:1:204:76ff:feda:1de4[500]
2002-07-15 17:20:01: DEBUG: sockmisc.c:479:sendfromto(): src6 3ffe:b80:447:1::1[500] 0
2002-07-15 17:20:01: DEBUG: sockmisc.c:483:sendfromto(): dst6 3ffe:b80:447:1:204:76ff:feda:1de4[500] 0
2002-07-15 17:20:01: DEBUG: isakmp.c:1470:isakmp_send(): 1 times of 80 bytes message will be sent.
2002-07-15 17:20:01: DEBUG: plog.c:209:plogdump():
c4a28d4e cc06791d 00000000 00000000 01100200 00000000 00000050 00000034
00000001 00000001 00000028 01010001 00000020 01010000 800b0001 800c0c30
80010005 80030001 80020002 80040002
302002-07-15 17:20:11: DEBUG: isakmp.c:233:isakmp_handler(): ===
2002-07-15 17:20:11: DEBUG: isakmp.c:234:isakmp_handler(): 176 bytes message received from 3ffe:b80:447:1:204:76ff:feda:1de4[500]
2002-07-15 17:20:11: DEBUG: plog.c:209:plogdump():
70610c48 aeb77436 00000000 00000000 01100200 00000000 000000b0 00000094
00000001 00000001 00000088 00010004 03000020 00010000 800b0001 800c0c30
80010005 80020001 80030001 80040005 03000020 01010000 800b0001 800c0c30
80010005 80020002 80030001 80040005 03000020 02010000 800b0001 800c0c30
80010005 80020002 80030001 80040002 00000020 03010000 800b0001 800c0c30
80010005 80020001 80030001 80040002
2002-07-15 17:20:11: DEBUG: isakmp.c:2298:isakmp_printpacket(): begin.
20:11.567087 3ffe:b80:447:1:204:76ff:feda:1de4:500 -> 3ffe:b80:447:1::1:500: isakmp 1.0 msgid 00000000: phase 1 I ident:
    (sa: doi=ipsec situation=identity
        (p: #0 protoid=isakmp transform=4
            (t: #0 id=ike (type=lifetype value=sec)(type=lifeduration value=0c30)(type=enc value=3des)(type=hash value=md5)(type=auth value=preshared)(type=group desc value=0005))
            (t: #1 id=ike (type=lifetype value=sec)(type=lifeduration value=0c30)(type=enc value=3des)(type=hash value=sha1)(type=auth value=preshared)(type=group desc value=0005))
            (t: #2 id=ike (type=lifetype value=sec)(type=lifeduration value=0c30)(type=enc value=3des)(type=hash value=sha1)(type=auth value=preshared)(type=group desc value=modp1024))
            (t: #3 id=ike (type=lifetype value=sec)(type=lifeduration value=0c30)(type=enc value=3des)(type=hash value=md5)(type=auth value=preshared)(type=group desc value=modp1024))))
2002-07-15 17:20:11: DEBUG: remoteconf.c:129:getrmconf(): anonymous configuration selected for 3ffe:b80:447:1:204:76ff:feda:1de4[500].
2002-07-15 17:20:11: DEBUG: isakmp.c:915:isakmp_ph1begin_r(): ===
2002-07-15 17:20:11: INFO: isakmp.c:920:isakmp_ph1begin_r(): respond new phase 1 negotiation: 3ffe:b80:447:1::1[500]<=>3ffe:b80:447:1:204:76ff:feda:1de4[500]
2002-07-15 17:20:11: INFO: isakmp.c:925:isakmp_ph1begin_r(): begin Identity Protection mode.
2002-07-15 17:20:11: DEBUG: isakmp.c:1141:isakmp_parsewoh(): begin.
2002-07-15 17:20:11: DEBUG: isakmp.c:1168:isakmp_parsewoh(): seen nptype=1(sa)
2002-07-15 17:20:11: DEBUG: isakmp.c:1206:isakmp_parsewoh(): succeed.
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1115:get_proppair(): total SA len=144
2002-07-15 17:20:11: DEBUG: plog.c:209:plogdump():
00000001 00000001 00000088 00010004 03000020 00010000 800b0001 800c0c30
80010005 80020001 80030001 80040005 03000020 01010000 800b0001 800c0c30
80010005 80020002 80030001 80040005 03000020 02010000 800b0001 800c0c30
80010005 80020002 80030001 80040002 00000020 03010000 800b0001 800c0c30
80010005 80020001 80030001 80040002
2002-07-15 17:20:11: DEBUG: isakmp.c:1141:isakmp_parsewoh(): begin.
2002-07-15 17:20:11: DEBUG: isakmp.c:1168:isakmp_parsewoh(): seen nptype=2(prop)
2002-07-15 17:20:11: DEBUG: isakmp.c:1206:isakmp_parsewoh(): succeed.
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1168:get_proppair(): proposal #0 len=136
2002-07-15 17:20:11: DEBUG: isakmp.c:1141:isakmp_parsewoh(): begin.
2002-07-15 17:20:11: DEBUG: isakmp.c:1168:isakmp_parsewoh(): seen nptype=3(trns)
2002-07-15 17:20:11: DEBUG: isakmp.c:1168:isakmp_parsewoh(): seen nptype=3(trns)
2002-07-15 17:20:11: DEBUG: isakmp.c:1168:isakmp_parsewoh(): seen nptype=3(trns)
2002-07-15 17:20:11: DEBUG: isakmp.c:1168:isakmp_parsewoh(): seen nptype=3(trns)
2002-07-15 17:20:11: DEBUG: isakmp.c:1206:isakmp_parsewoh(): succeed.
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1309:get_transform(): transform #0 len=32
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Life Duration, flag=0x8000, lorv=3120
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
2002-07-15 17:20:11: DEBUG: algorithm.c:380:alg_oakley_encdef(): encription(3des)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=MD5
2002-07-15 17:20:11: DEBUG: algorithm.c:250:alg_oakley_hashdef(): hash(md5)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1536-bit MODP group
2002-07-15 17:20:11: DEBUG: algorithm.c:608:alg_oakley_dhdef(): hmac(modp1536)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1309:get_transform(): transform #1 len=32
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Life Duration, flag=0x8000, lorv=3120
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
2002-07-15 17:20:11: DEBUG: algorithm.c:380:alg_oakley_encdef(): encription(3des)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=SHA
2002-07-15 17:20:11: DEBUG: algorithm.c:250:alg_oakley_hashdef(): hash(sha1)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1536-bit MODP group
2002-07-15 17:20:11: DEBUG: algorithm.c:608:alg_oakley_dhdef(): hmac(modp1536)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1309:get_transform(): transform #2 len=32
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Life Duration, flag=0x8000, lorv=3120
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
2002-07-15 17:20:11: DEBUG: algorithm.c:380:alg_oakley_encdef(): encription(3des)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=SHA
2002-07-15 17:20:11: DEBUG: algorithm.c:250:alg_oakley_hashdef(): hash(sha1)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group
2002-07-15 17:20:11: DEBUG: algorithm.c:608:alg_oakley_dhdef(): hmac(modp1024)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1309:get_transform(): transform #3 len=32
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Life Type, flag=0x8000, lorv=seconds
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Life Duration, flag=0x8000, lorv=3120
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
2002-07-15 17:20:11: DEBUG: algorithm.c:380:alg_oakley_encdef(): encription(3des)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Hash Algorithm, flag=0x8000, lorv=MD5
2002-07-15 17:20:11: DEBUG: algorithm.c:250:alg_oakley_hashdef(): hash(md5)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Authentication Method, flag=0x8000, lorv=pre-shared key
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1868:check_attr_isakmp(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group
2002-07-15 17:20:11: DEBUG: algorithm.c:608:alg_oakley_dhdef(): hmac(modp1024)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1211:get_proppair(): pair 0:
2002-07-15 17:20:11: DEBUG: proposal.c:891:print_proppair0():  0x80a9680: next=0x0 tnext=0x80a9690
2002-07-15 17:20:11: DEBUG: proposal.c:891:print_proppair0():   0x80a9690: next=0x0 tnext=0x80a96a0
2002-07-15 17:20:11: DEBUG: proposal.c:891:print_proppair0():    0x80a96a0: next=0x0 tnext=0x80a96b0
2002-07-15 17:20:11: DEBUG: proposal.c:891:print_proppair0():     0x80a96b0: next=0x0 tnext=0x0
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:1246:get_proppair(): proposal #0: 4 transform
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:324:get_ph1approvalx(): prop#=0, prot-id=ISAKMP, spi-size=0, #trns=4
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:329:get_ph1approvalx(): trns#=0, trns-id=IKE
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Life Type, flag=0x8000, lorv=seconds
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Life Duration, flag=0x8000, lorv=3120
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Hash Algorithm, flag=0x8000, lorv=MD5
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Authentication Method, flag=0x8000, lorv=pre-shared key
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Group Description, flag=0x8000, lorv=1536-bit MODP group
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:340:get_ph1approvalx(): Compared: DB:Peer
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:341:get_ph1approvalx(): (lifetime = 3120:3120)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:343:get_ph1approvalx(): (lifebyte = 0:0)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:345:get_ph1approvalx(): enctype = 3DES-CBC:3DES-CBC
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:350:get_ph1approvalx(): (encklen = 0:0)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:352:get_ph1approvalx(): hashtype = SHA:MD5
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:357:get_ph1approvalx(): authmethod = pre-shared key:pre-shared key
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:362:get_ph1approvalx(): dh_group = 1024-bit MODP group:1536-bit MODP group
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:324:get_ph1approvalx(): prop#=0, prot-id=ISAKMP, spi-size=0, #trns=4
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:329:get_ph1approvalx(): trns#=1, trns-id=IKE
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Life Type, flag=0x8000, lorv=seconds
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Life Duration, flag=0x8000, lorv=3120
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Hash Algorithm, flag=0x8000, lorv=SHA
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Authentication Method, flag=0x8000, lorv=pre-shared key
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Group Description, flag=0x8000, lorv=1536-bit MODP group
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:340:get_ph1approvalx(): Compared: DB:Peer
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:341:get_ph1approvalx(): (lifetime = 3120:3120)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:343:get_ph1approvalx(): (lifebyte = 0:0)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:345:get_ph1approvalx(): enctype = 3DES-CBC:3DES-CBC
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:350:get_ph1approvalx(): (encklen = 0:0)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:352:get_ph1approvalx(): hashtype = SHA:SHA
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:357:get_ph1approvalx(): authmethod = pre-shared key:pre-shared key
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:362:get_ph1approvalx(): dh_group = 1024-bit MODP group:1536-bit MODP group
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:324:get_ph1approvalx(): prop#=0, prot-id=ISAKMP, spi-size=0, #trns=4
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:329:get_ph1approvalx(): trns#=2, trns-id=IKE
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Life Type, flag=0x8000, lorv=seconds
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Life Duration, flag=0x8000, lorv=3120
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Hash Algorithm, flag=0x8000, lorv=SHA
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Authentication Method, flag=0x8000, lorv=pre-shared key
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:493:t2isakmpsa(): type=Group Description, flag=0x8000, lorv=1024-bit MODP group
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:340:get_ph1approvalx(): Compared: DB:Peer
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:341:get_ph1approvalx(): (lifetime = 3120:3120)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:343:get_ph1approvalx(): (lifebyte = 0:0)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:345:get_ph1approvalx(): enctype = 3DES-CBC:3DES-CBC
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:350:get_ph1approvalx(): (encklen = 0:0)
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:352:get_ph1approvalx(): hashtype = SHA:SHA
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:357:get_ph1approvalx(): authmethod = pre-shared key:pre-shared key
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:362:get_ph1approvalx(): dh_group = 1024-bit MODP group:1024-bit MODP group
2002-07-15 17:20:11: DEBUG: ipsec_doi.c:248:get_ph1approval(): an acceptable proposal found.
2002-07-15 17:20:11: DEBUG: algorithm.c:608:alg_oakley_dhdef(): hmac(modp1024)
2002-07-15 17:20:11: DEBUG: isakmp.c:2046:isakmp_newcookie(): new cookie:
e30a1269ea890092
2002-07-15 17:20:11: DEBUG: isakmp.c:2163:set_isakmp_payload(): add payload of len 48, next type 13
2002-07-15 17:20:11: DEBUG: isakmp.c:2163:set_isakmp_payload(): add payload of len 16, next type 0
2002-07-15 17:20:11: DEBUG: isakmp.c:2298:isakmp_printpacket(): begin.
20:11.650007 3ffe:b80:447:1::1:500 -> 3ffe:b80:447:1:204:76ff:feda:1de4:500: isakmp 1.0 msgid 00000000: phase 1 ? ident:
    (sa: doi=ipsec situation=identity
        (p: #0 protoid=isakmp transform=1
            (t: #2 id=ike (type=lifetype value=sec)(type=lifeduration value=0c30)(type=enc value=3des)(type=hash value=sha1)(type=auth value=preshared)(type=group desc value=modp1024))))
    (vid: len=16)
2002-07-15 17:20:11: DEBUG: sockmisc.c:419:sendfromto(): sockname 3ffe:b80:447:1::1[500]
2002-07-15 17:20:11: DEBUG: sockmisc.c:421:sendfromto(): send packet from 3ffe:b80:447:1::1[500]
2002-07-15 17:20:11: DEBUG: sockmisc.c:423:sendfromto(): send packet to 3ffe:b80:447:1:204:76ff:feda:1de4[500]
2002-07-15 17:20:11: DEBUG: sockmisc.c:479:sendfromto(): src6 3ffe:b80:447:1::1[500] 0
2002-07-15 17:20:11: DEBUG: sockmisc.c:483:sendfromto(): dst6 3ffe:b80:447:1:204:76ff:feda:1de4[500] 0
2002-07-15 17:20:11: DEBUG: isakmp.c:1470:isakmp_send(): 1 times of 100 bytes message will be sent.
2002-07-15 17:20:11: DEBUG: plog.c:209:plogdump():
70610c48 aeb77436 e30a1269 ea890092 01100200 00000000 00000064 0d000034
00000001 00000001 00000028 00010001 00000020 02010000 800b0001 800c0c30
80010005 80020002 80030001 80040002 00000014 7003cbc1 097dbe9c 2600ba69
83bc8b35
2002-07-15 17:20:18: ERROR: isakmp.c:1826:isakmp_chkph1there(): phase2 negotiation failed due to time up waiting for phase1. ESP 3ffe:b80:447:1:204:76ff:feda:1de4->3ffe:b80:447:1::1
2002-07-15 17:20:18: INFO: isakmp.c:1831:isakmp_chkph1there(): delete phase 2 handler.
^C2002-07-15 17:20:18: INFO: session.c:276:check_sigreq(): caught signal 2
2002-07-15 17:20:18: DEBUG: pfkey.c:192:pfkey_handler(): get pfkey FLUSH message
2002-07-15 17:20:18: DEBUG2: plog.c:209:plogdump():
02090000 02000000 00000000 e41d0000
2002-07-15 17:20:19: DEBUG: pfkey.c:268:pfkey_dump_sadb(): call pfkey_send_dump
2002-07-15 17:20:19: INFO: session.c:180:close_session(): racoon shutdown